{"level":"warn","ts":"2026-05-12T11:25:24.56189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:25:24.561982Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.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://199.19.213.205:2380","--initial-cluster=instance=https://199.19.213.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.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-05-12T11:25:24.56205Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-12T11:25:24.562057Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.205:2380"]} {"level":"info","ts":"2026-05-12T11:25:24.562076Z","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-05-12T11:25:24.562707Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"]} {"level":"info","ts":"2026-05-12T11:25:24.562778Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.205:2380"],"listen-peer-urls":["https://199.19.213.205:2380"],"advertise-client-urls":["https://199.19.213.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.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-05-12T11:25:24.603022Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"40.018913ms"} {"level":"info","ts":"2026-05-12T11:25:24.613602Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f69f598a0f963672","cluster-id":"740466542a91995c"} {"level":"info","ts":"2026-05-12T11:25:24.613708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=()"} {"level":"info","ts":"2026-05-12T11:25:24.61373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became follower at term 0"} {"level":"info","ts":"2026-05-12T11:25:24.613744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f69f598a0f963672 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-12T11:25:24.613753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became follower at term 1"} {"level":"info","ts":"2026-05-12T11:25:24.6138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=(17771021104129128050)"} {"level":"warn","ts":"2026-05-12T11:25:24.621414Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-12T11:25:24.624465Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-12T11:25:24.646655Z","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-05-12T11:25:24.659974Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f69f598a0f963672","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-12T11:25:24.66021Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f69f598a0f963672","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-12T11:25:24.660851Z","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-05-12T11:25:24.660961Z","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-05-12T11:25:24.66099Z","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-05-12T11:25:24.665002Z","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-05-12T11:25:24.665129Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.205:2380"} {"level":"info","ts":"2026-05-12T11:25:24.665199Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.205:2380"} {"level":"info","ts":"2026-05-12T11:25:24.665399Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f69f598a0f963672","initial-advertise-peer-urls":["https://199.19.213.205:2380"],"listen-peer-urls":["https://199.19.213.205:2380"],"advertise-client-urls":["https://199.19.213.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-12T11:25:24.665448Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-12T11:25:24.689343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 switched to configuration voters=(17771021104129128050)"} {"level":"info","ts":"2026-05-12T11:25:24.68948Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"740466542a91995c","local-member-id":"f69f598a0f963672","added-peer-id":"f69f598a0f963672","added-peer-peer-urls":["https://199.19.213.205:2380"]} {"level":"info","ts":"2026-05-12T11:25:25.114455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 is starting a new election at term 1"} {"level":"info","ts":"2026-05-12T11:25:25.114525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-12T11:25:25.114574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 received MsgPreVoteResp from f69f598a0f963672 at term 1"} {"level":"info","ts":"2026-05-12T11:25:25.114595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became candidate at term 2"} {"level":"info","ts":"2026-05-12T11:25:25.114607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 received MsgVoteResp from f69f598a0f963672 at term 2"} {"level":"info","ts":"2026-05-12T11:25:25.114819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f69f598a0f963672 became leader at term 2"} {"level":"info","ts":"2026-05-12T11:25:25.114833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f69f598a0f963672 elected leader f69f598a0f963672 at term 2"} {"level":"info","ts":"2026-05-12T11:25:25.116239Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f69f598a0f963672","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.205:2379]}","request-path":"/0/members/f69f598a0f963672/attributes","cluster-id":"740466542a91995c","publish-timeout":"7s"} {"level":"info","ts":"2026-05-12T11:25:25.116493Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:25:25.116684Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:25.116675Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-12T11:25:25.116853Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-12T11:25:25.116967Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-12T11:25:25.118249Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"740466542a91995c","local-member-id":"f69f598a0f963672","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:25.118336Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:25.118434Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-12T11:25:25.12023Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-12T11:25:25.126909Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.205:2379"} {"level":"info","ts":"2026-05-12T11:25:26.873454Z","caller":"traceutil/trace.go:171","msg":"trace[1719393184] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"259.96639ms","start":"2026-05-12T11:25:26.613459Z","end":"2026-05-12T11:25:26.873426Z","steps":["trace[1719393184] 'process raft request' (duration: 240.197038ms)","trace[1719393184] 'compare' (duration: 19.478056ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:26.873536Z","caller":"traceutil/trace.go:171","msg":"trace[569376328] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"259.223952ms","start":"2026-05-12T11:25:26.614289Z","end":"2026-05-12T11:25:26.873513Z","steps":["trace[569376328] 'process raft request' (duration: 259.100399ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:26.87352Z","caller":"traceutil/trace.go:171","msg":"trace[531911044] linearizableReadLoop","detail":"{readStateIndex:12; appliedIndex:7; }","duration":"203.415375ms","start":"2026-05-12T11:25:26.670078Z","end":"2026-05-12T11:25:26.873493Z","steps":["trace[531911044] 'read index received' (duration: 183.588871ms)","trace[531911044] 'applied index is now lower than readState.Index' (duration: 19.825274ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:26.873669Z","caller":"traceutil/trace.go:171","msg":"trace[2135577145] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"259.346215ms","start":"2026-05-12T11:25:26.614313Z","end":"2026-05-12T11:25:26.873659Z","steps":["trace[2135577145] 'process raft request' (duration: 259.10409ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:26.873669Z","caller":"traceutil/trace.go:171","msg":"trace[1900631194] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"259.430987ms","start":"2026-05-12T11:25:26.614217Z","end":"2026-05-12T11:25:26.873648Z","steps":["trace[1900631194] 'process raft request' (duration: 259.11891ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:26.873638Z","caller":"traceutil/trace.go:171","msg":"trace[1708488063] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"259.302144ms","start":"2026-05-12T11:25:26.614307Z","end":"2026-05-12T11:25:26.873609Z","steps":["trace[1708488063] 'process raft request' (duration: 259.136741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:26.873797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.148254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:26.87396Z","caller":"traceutil/trace.go:171","msg":"trace[1950542965] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"212.35199ms","start":"2026-05-12T11:25:26.661575Z","end":"2026-05-12T11:25:26.873927Z","steps":["trace[1950542965] 'agreement among raft nodes before linearized reading' (duration: 212.062493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:26.873876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.897114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:26.87515Z","caller":"traceutil/trace.go:171","msg":"trace[1410133408] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:9; }","duration":"242.200874ms","start":"2026-05-12T11:25:26.632888Z","end":"2026-05-12T11:25:26.875089Z","steps":["trace[1410133408] 'agreement among raft nodes before linearized reading' (duration: 240.904724ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:26.892648Z","caller":"traceutil/trace.go:171","msg":"trace[111127798] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"220.136409ms","start":"2026-05-12T11:25:26.672481Z","end":"2026-05-12T11:25:26.892617Z","steps":["trace[111127798] 'process raft request' (duration: 219.973375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:26.892823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.611763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:26.892875Z","caller":"traceutil/trace.go:171","msg":"trace[1731012739] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:11; }","duration":"100.693335ms","start":"2026-05-12T11:25:26.792169Z","end":"2026-05-12T11:25:26.892863Z","steps":["trace[1731012739] 'agreement among raft nodes before linearized reading' (duration: 100.519701ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:26.892672Z","caller":"traceutil/trace.go:171","msg":"trace[1803557478] transaction","detail":"{read_only:false; number_of_response:0; response_revision:10; }","duration":"220.154109ms","start":"2026-05-12T11:25:26.67251Z","end":"2026-05-12T11:25:26.892664Z","steps":["trace[1803557478] 'process raft request' (duration: 220.031396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:26.893016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.273618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:26.893047Z","caller":"traceutil/trace.go:171","msg":"trace[973983243] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"215.34042ms","start":"2026-05-12T11:25:26.677699Z","end":"2026-05-12T11:25:26.89304Z","steps":["trace[973983243] 'agreement among raft nodes before linearized reading' (duration: 215.294809ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:26.892703Z","caller":"traceutil/trace.go:171","msg":"trace[484568897] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"219.151456ms","start":"2026-05-12T11:25:26.673538Z","end":"2026-05-12T11:25:26.892689Z","steps":["trace[484568897] 'process raft request' (duration: 219.028223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.28624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.012322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-12T11:25:27.28657Z","caller":"traceutil/trace.go:171","msg":"trace[870136790] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"193.261167ms","start":"2026-05-12T11:25:27.093263Z","end":"2026-05-12T11:25:27.286525Z","steps":["trace[870136790] 'process raft request' (duration: 62.400646ms)","trace[870136790] 'compare' (duration: 129.851937ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:27.286675Z","caller":"traceutil/trace.go:171","msg":"trace[424006973] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:42; }","duration":"186.835601ms","start":"2026-05-12T11:25:27.099767Z","end":"2026-05-12T11:25:27.286603Z","steps":["trace[424006973] 'read index received' (duration: 56.035412ms)","trace[424006973] 'applied index is now lower than readState.Index' (duration: 130.799089ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:27.286991Z","caller":"traceutil/trace.go:171","msg":"trace[63876010] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"191.878156ms","start":"2026-05-12T11:25:27.095099Z","end":"2026-05-12T11:25:27.286977Z","steps":["trace[63876010] 'process raft request' (duration: 191.388445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.287166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.395774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:27.28721Z","caller":"traceutil/trace.go:171","msg":"trace[793897414] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:40; }","duration":"187.488036ms","start":"2026-05-12T11:25:27.099712Z","end":"2026-05-12T11:25:27.2872Z","steps":["trace[793897414] 'agreement among raft nodes before linearized reading' (duration: 187.311732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.290953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.861367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:27.29106Z","caller":"traceutil/trace.go:171","msg":"trace[797073573] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:41; }","duration":"119.307228ms","start":"2026-05-12T11:25:27.171726Z","end":"2026-05-12T11:25:27.291033Z","steps":["trace[797073573] 'agreement among raft nodes before linearized reading' (duration: 118.852398ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:27.75259Z","caller":"traceutil/trace.go:171","msg":"trace[805277937] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"317.545867ms","start":"2026-05-12T11:25:27.43502Z","end":"2026-05-12T11:25:27.752565Z","steps":["trace[805277937] 'process raft request' (duration: 317.464945ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:27.752673Z","caller":"traceutil/trace.go:171","msg":"trace[791297614] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"320.237608ms","start":"2026-05-12T11:25:27.432395Z","end":"2026-05-12T11:25:27.752633Z","steps":["trace[791297614] 'process raft request' (duration: 270.204373ms)","trace[791297614] 'compare' (duration: 49.755549ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:27.752847Z","caller":"traceutil/trace.go:171","msg":"trace[1150569034] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:59; }","duration":"244.701564ms","start":"2026-05-12T11:25:27.508125Z","end":"2026-05-12T11:25:27.752826Z","steps":["trace[1150569034] 'read index received' (duration: 194.486384ms)","trace[1150569034] 'applied index is now lower than readState.Index' (duration: 50.21363ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:27.752962Z","caller":"traceutil/trace.go:171","msg":"trace[141436514] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"299.549669ms","start":"2026-05-12T11:25:27.453401Z","end":"2026-05-12T11:25:27.752951Z","steps":["trace[141436514] 'process raft request' (duration: 299.126759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.753025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.871694ms","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-05-12T11:25:27.753081Z","caller":"traceutil/trace.go:171","msg":"trace[1273651375] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:57; }","duration":"239.950725ms","start":"2026-05-12T11:25:27.513116Z","end":"2026-05-12T11:25:27.753066Z","steps":["trace[1273651375] 'agreement among raft nodes before linearized reading' (duration: 239.856153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.75319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.065361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:27.753266Z","caller":"traceutil/trace.go:171","msg":"trace[2000199023] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:57; }","duration":"245.180354ms","start":"2026-05-12T11:25:27.50806Z","end":"2026-05-12T11:25:27.753241Z","steps":["trace[2000199023] 'agreement among raft nodes before linearized reading' (duration: 245.079032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.753268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.045371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-12T11:25:27.753228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:27.435011Z","time spent":"317.652529ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:25:27.753324Z","caller":"traceutil/trace.go:171","msg":"trace[337827100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:57; }","duration":"137.136233ms","start":"2026-05-12T11:25:27.616172Z","end":"2026-05-12T11:25:27.753308Z","steps":["trace[337827100] 'agreement among raft nodes before linearized reading' (duration: 137.04061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:27.75342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:27.432388Z","time spent":"320.411872ms","remote":"127.0.0.1:51804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:25:27.962939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.04272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-12T11:25:27.963013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.349654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18aecdbe7f16797d\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-05-12T11:25:27.963054Z","caller":"traceutil/trace.go:171","msg":"trace[1110622422] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:65; }","duration":"107.241624ms","start":"2026-05-12T11:25:27.855786Z","end":"2026-05-12T11:25:27.963028Z","steps":["trace[1110622422] 'range keys from in-memory index tree' (duration: 106.940907ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:27.963106Z","caller":"traceutil/trace.go:171","msg":"trace[1955150993] range","detail":"{range_begin:/registry/events/default/instance.18aecdbe7f16797d; range_end:; response_count:1; response_revision:65; }","duration":"106.503737ms","start":"2026-05-12T11:25:27.856579Z","end":"2026-05-12T11:25:27.963082Z","steps":["trace[1955150993] 'range keys from in-memory index tree' (duration: 106.254232ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:27.963709Z","caller":"traceutil/trace.go:171","msg":"trace[1585790040] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"105.861352ms","start":"2026-05-12T11:25:27.857823Z","end":"2026-05-12T11:25:27.963685Z","steps":["trace[1585790040] 'process raft request' (duration: 105.81011ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:27.963911Z","caller":"traceutil/trace.go:171","msg":"trace[918027478] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"106.883936ms","start":"2026-05-12T11:25:27.857011Z","end":"2026-05-12T11:25:27.963895Z","steps":["trace[918027478] 'process raft request' (duration: 61.876065ms)","trace[918027478] 'compare' (duration: 43.915837ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:28.145083Z","caller":"traceutil/trace.go:171","msg":"trace[474372716] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"106.070568ms","start":"2026-05-12T11:25:28.038972Z","end":"2026-05-12T11:25:28.145043Z","steps":["trace[474372716] 'read index received' (duration: 25.167277ms)","trace[474372716] 'applied index is now lower than readState.Index' (duration: 80.896111ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:28.145151Z","caller":"traceutil/trace.go:171","msg":"trace[2125073139] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"106.702111ms","start":"2026-05-12T11:25:28.0384Z","end":"2026-05-12T11:25:28.145102Z","steps":["trace[2125073139] 'process raft request' (duration: 25.696358ms)","trace[2125073139] 'compare' (duration: 80.763748ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:28.145329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.312793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:28.145396Z","caller":"traceutil/trace.go:171","msg":"trace[2115736692] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:71; }","duration":"106.452226ms","start":"2026-05-12T11:25:28.038923Z","end":"2026-05-12T11:25:28.145375Z","steps":["trace[2115736692] 'agreement among raft nodes before linearized reading' (duration: 106.272353ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:28.148713Z","caller":"traceutil/trace.go:171","msg":"trace[1343958887] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"107.9743ms","start":"2026-05-12T11:25:28.040722Z","end":"2026-05-12T11:25:28.148696Z","steps":["trace[1343958887] 'process raft request' (duration: 107.920849ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:28.149287Z","caller":"traceutil/trace.go:171","msg":"trace[1445675759] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"108.632905ms","start":"2026-05-12T11:25:28.040623Z","end":"2026-05-12T11:25:28.149256Z","steps":["trace[1445675759] 'process raft request' (duration: 107.933089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:28.632431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.212808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-12T11:25:28.632661Z","caller":"traceutil/trace.go:171","msg":"trace[830025894] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"408.292131ms","start":"2026-05-12T11:25:28.224352Z","end":"2026-05-12T11:25:28.632644Z","steps":["trace[830025894] 'process raft request' (duration: 408.23739ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:28.632665Z","caller":"traceutil/trace.go:171","msg":"trace[2089784540] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"410.172613ms","start":"2026-05-12T11:25:28.222406Z","end":"2026-05-12T11:25:28.632578Z","steps":["trace[2089784540] 'process raft request' (duration: 89.7388ms)","trace[2089784540] 'compare' (duration: 320.063225ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:28.632944Z","caller":"traceutil/trace.go:171","msg":"trace[1912693542] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"410.080811ms","start":"2026-05-12T11:25:28.2226Z","end":"2026-05-12T11:25:28.63268Z","steps":["trace[1912693542] 'read index received' (duration: 89.562106ms)","trace[1912693542] 'applied index is now lower than readState.Index' (duration: 320.515935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:28.632978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:28.22238Z","time spent":"410.47627ms","remote":"127.0.0.1:51694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:25:28.632752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:28.224329Z","time spent":"408.374314ms","remote":"127.0.0.1:51804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:25:28.63299Z","caller":"traceutil/trace.go:171","msg":"trace[764504305] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"409.060899ms","start":"2026-05-12T11:25:28.223826Z","end":"2026-05-12T11:25:28.632887Z","steps":["trace[764504305] 'process raft request' (duration: 408.711671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:28.634441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:28.223811Z","time spent":"410.584303ms","remote":"127.0.0.1:51804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:25:28.6333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.661094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18aecdbe7f1690cf\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-05-12T11:25:28.634588Z","caller":"traceutil/trace.go:171","msg":"trace[753328863] range","detail":"{range_begin:/registry/events/default/instance.18aecdbe7f1690cf; range_end:; response_count:1; response_revision:84; }","duration":"411.945874ms","start":"2026-05-12T11:25:28.222565Z","end":"2026-05-12T11:25:28.634511Z","steps":["trace[753328863] 'agreement among raft nodes before linearized reading' (duration: 410.46285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:28.634731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:28.22255Z","time spent":"412.118777ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":671,"request content":"key:\"/registry/events/default/instance.18aecdbe7f1690cf\" "} {"level":"info","ts":"2026-05-12T11:25:28.980242Z","caller":"traceutil/trace.go:171","msg":"trace[152839402] linearizableReadLoop","detail":"{readStateIndex:98; appliedIndex:97; }","duration":"132.692996ms","start":"2026-05-12T11:25:28.847519Z","end":"2026-05-12T11:25:28.980212Z","steps":["trace[152839402] 'read index received' (duration: 79.56193ms)","trace[152839402] 'applied index is now lower than readState.Index' (duration: 53.129716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:28.980419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.867651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-12T11:25:28.980462Z","caller":"traceutil/trace.go:171","msg":"trace[208012720] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:93; }","duration":"132.956573ms","start":"2026-05-12T11:25:28.847495Z","end":"2026-05-12T11:25:28.980452Z","steps":["trace[208012720] 'agreement among raft nodes before linearized reading' (duration: 132.83087ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:28.980295Z","caller":"traceutil/trace.go:171","msg":"trace[1444036837] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"134.14074ms","start":"2026-05-12T11:25:28.846097Z","end":"2026-05-12T11:25:28.980238Z","steps":["trace[1444036837] 'process raft request' (duration: 81.018964ms)","trace[1444036837] 'compare' (duration: 52.947022ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:29.208785Z","caller":"traceutil/trace.go:171","msg":"trace[936072921] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:100; }","duration":"177.672847ms","start":"2026-05-12T11:25:29.031052Z","end":"2026-05-12T11:25:29.208724Z","steps":["trace[936072921] 'read index received' (duration: 149.10597ms)","trace[936072921] 'applied index is now lower than readState.Index' (duration: 28.566087ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:29.208966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.889952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18aecdbe7f1690cf\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-05-12T11:25:29.209008Z","caller":"traceutil/trace.go:171","msg":"trace[586324737] range","detail":"{range_begin:/registry/events/default/instance.18aecdbe7f1690cf; range_end:; response_count:1; response_revision:96; }","duration":"177.968943ms","start":"2026-05-12T11:25:29.031027Z","end":"2026-05-12T11:25:29.208996Z","steps":["trace[586324737] 'agreement among raft nodes before linearized reading' (duration: 177.83257ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:29.208913Z","caller":"traceutil/trace.go:171","msg":"trace[1024655168] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"180.258644ms","start":"2026-05-12T11:25:29.02861Z","end":"2026-05-12T11:25:29.208868Z","steps":["trace[1024655168] 'process raft request' (duration: 151.600884ms)","trace[1024655168] 'compare' (duration: 28.423984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:30.76313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.513126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:25:30.763234Z","caller":"traceutil/trace.go:171","msg":"trace[1327945512] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:202; }","duration":"170.66741ms","start":"2026-05-12T11:25:30.592542Z","end":"2026-05-12T11:25:30.76321Z","steps":["trace[1327945512] 'range keys from in-memory index tree' (duration: 170.422894ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:32.815852Z","caller":"traceutil/trace.go:171","msg":"trace[326256000] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"100.094331ms","start":"2026-05-12T11:25:32.715732Z","end":"2026-05-12T11:25:32.815826Z","steps":["trace[326256000] 'process raft request' (duration: 99.961888ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:33.729651Z","caller":"traceutil/trace.go:171","msg":"trace[184551955] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"210.942021ms","start":"2026-05-12T11:25:33.518682Z","end":"2026-05-12T11:25:33.729624Z","steps":["trace[184551955] 'process raft request' (duration: 210.871179ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:33.72969Z","caller":"traceutil/trace.go:171","msg":"trace[804488058] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"213.045506ms","start":"2026-05-12T11:25:33.516619Z","end":"2026-05-12T11:25:33.729665Z","steps":["trace[804488058] 'process raft request' (duration: 160.778586ms)","trace[804488058] 'compare' (duration: 51.996504ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:37.956793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314339,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:25:38.384199Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.925029571s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:25:38.384719Z","caller":"traceutil/trace.go:171","msg":"trace[1053388885] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"1.925652175s","start":"2026-05-12T11:25:36.459039Z","end":"2026-05-12T11:25:38.384691Z","steps":["trace[1053388885] 'process raft request' (duration: 1.925496071s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:38.385152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:36.45901Z","time spent":"1.925988871s","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:25:38.390153Z","caller":"traceutil/trace.go:171","msg":"trace[469723100] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:328; }","duration":"933.847335ms","start":"2026-05-12T11:25:37.456285Z","end":"2026-05-12T11:25:38.390132Z","steps":["trace[469723100] 'read index received' (duration: 928.170577ms)","trace[469723100] 'applied index is now lower than readState.Index' (duration: 5.675988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:38.390323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.675595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-12T11:25:38.390336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.042508ms","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-05-12T11:25:38.390387Z","caller":"traceutil/trace.go:171","msg":"trace[1864881980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:321; }","duration":"801.795728ms","start":"2026-05-12T11:25:37.58858Z","end":"2026-05-12T11:25:38.390376Z","steps":["trace[1864881980] 'agreement among raft nodes before linearized reading' (duration: 801.689835ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:38.390411Z","caller":"traceutil/trace.go:171","msg":"trace[932976273] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:321; }","duration":"934.158691ms","start":"2026-05-12T11:25:37.456231Z","end":"2026-05-12T11:25:38.390389Z","steps":["trace[932976273] 'agreement among raft nodes before linearized reading' (duration: 934.017578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:38.390419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:37.588538Z","time spent":"801.870989ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-12T11:25:38.390461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:37.456202Z","time spent":"934.245713ms","remote":"127.0.0.1:51612","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":"info","ts":"2026-05-12T11:25:38.390319Z","caller":"traceutil/trace.go:171","msg":"trace[56036538] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"1.34787593s","start":"2026-05-12T11:25:37.042316Z","end":"2026-05-12T11:25:38.390192Z","steps":["trace[56036538] 'process raft request' (duration: 1.347695746s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:38.390762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:37.042269Z","time spent":"1.34837665s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:25:43.488332Z","caller":"traceutil/trace.go:171","msg":"trace[1389915224] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"160.129117ms","start":"2026-05-12T11:25:43.328176Z","end":"2026-05-12T11:25:43.488305Z","steps":["trace[1389915224] 'read index received' (duration: 159.894453ms)","trace[1389915224] 'applied index is now lower than readState.Index' (duration: 233.744µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:43.488433Z","caller":"traceutil/trace.go:171","msg":"trace[1821037536] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"572.594422ms","start":"2026-05-12T11:25:42.915815Z","end":"2026-05-12T11:25:43.488409Z","steps":["trace[1821037536] 'process raft request' (duration: 572.317047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:43.48846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.28889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:25:43.488495Z","caller":"traceutil/trace.go:171","msg":"trace[1376607302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"160.378262ms","start":"2026-05-12T11:25:43.328106Z","end":"2026-05-12T11:25:43.488485Z","steps":["trace[1376607302] 'agreement among raft nodes before linearized reading' (duration: 160.30583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:43.488548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:42.915802Z","time spent":"572.679904ms","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:25:44.091992Z","caller":"traceutil/trace.go:171","msg":"trace[528219277] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"593.325071ms","start":"2026-05-12T11:25:43.498643Z","end":"2026-05-12T11:25:44.091968Z","steps":["trace[528219277] 'process raft request' (duration: 593.190458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:44.092131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:43.498632Z","time spent":"593.428634ms","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:25:49.828481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314499,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:25:49.841757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.135123502s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:25:49.842172Z","caller":"traceutil/trace.go:171","msg":"trace[1966739335] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"513.704547ms","start":"2026-05-12T11:25:49.328414Z","end":"2026-05-12T11:25:49.842118Z","steps":["trace[1966739335] 'read index received' (duration: 513.569115ms)","trace[1966739335] 'applied index is now lower than readState.Index' (duration: 134.252µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:25:49.842216Z","caller":"traceutil/trace.go:171","msg":"trace[352307418] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"1.135630651s","start":"2026-05-12T11:25:48.706553Z","end":"2026-05-12T11:25:49.842183Z","steps":["trace[352307418] 'process raft request' (duration: 1.135415347s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:49.842327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.879241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:25:49.842367Z","caller":"traceutil/trace.go:171","msg":"trace[771067402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"514.001273ms","start":"2026-05-12T11:25:49.328354Z","end":"2026-05-12T11:25:49.842355Z","steps":["trace[771067402] 'agreement among raft nodes before linearized reading' (duration: 513.902941ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:49.842399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:49.328323Z","time spent":"514.068984ms","remote":"127.0.0.1:51334","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-05-12T11:25:49.842912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:48.706534Z","time spent":"1.135762914s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:25:50.625387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.321971ms","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-05-12T11:25:50.625386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.25122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T11:25:50.625437Z","caller":"traceutil/trace.go:171","msg":"trace[261665319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:400; }","duration":"143.420573ms","start":"2026-05-12T11:25:50.482004Z","end":"2026-05-12T11:25:50.625425Z","steps":["trace[261665319] 'range keys from in-memory index tree' (duration: 143.225989ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:50.625447Z","caller":"traceutil/trace.go:171","msg":"trace[1399919986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:400; }","duration":"143.362182ms","start":"2026-05-12T11:25:50.482072Z","end":"2026-05-12T11:25:50.625434Z","steps":["trace[1399919986] 'range keys from in-memory index tree' (duration: 143.140337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:50.625438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.584482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:25:50.62551Z","caller":"traceutil/trace.go:171","msg":"trace[2103719095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:400; }","duration":"781.77831ms","start":"2026-05-12T11:25:49.843711Z","end":"2026-05-12T11:25:50.625489Z","steps":["trace[2103719095] 'agreement among raft nodes before linearized reading' (duration: 19.24019ms)","trace[2103719095] 'range keys from in-memory index tree' (duration: 762.428798ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:50.625549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:49.843685Z","time spent":"781.856731ms","remote":"127.0.0.1:51348","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-05-12T11:25:50.734735Z","caller":"traceutil/trace.go:171","msg":"trace[248765052] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"103.565514ms","start":"2026-05-12T11:25:50.631145Z","end":"2026-05-12T11:25:50.734711Z","steps":["trace[248765052] 'process raft request' (duration: 103.411271ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:25:50.989698Z","caller":"traceutil/trace.go:171","msg":"trace[1965488874] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:412; }","duration":"337.729597ms","start":"2026-05-12T11:25:50.651941Z","end":"2026-05-12T11:25:50.989671Z","steps":["trace[1965488874] 'read index received' (duration: 82.622425ms)","trace[1965488874] 'applied index is now lower than readState.Index' (duration: 255.105872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:25:50.989851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.897719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:25:50.990066Z","caller":"traceutil/trace.go:171","msg":"trace[1134114151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"338.156025ms","start":"2026-05-12T11:25:50.651898Z","end":"2026-05-12T11:25:50.990054Z","steps":["trace[1134114151] 'agreement among raft nodes before linearized reading' (duration: 337.870189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:50.990107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:50.651886Z","time spent":"338.211146ms","remote":"127.0.0.1:51334","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-05-12T11:25:50.990097Z","caller":"traceutil/trace.go:171","msg":"trace[1889794356] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"357.674007ms","start":"2026-05-12T11:25:50.632332Z","end":"2026-05-12T11:25:50.990006Z","steps":["trace[1889794356] 'process raft request' (duration: 354.737371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:50.990351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:50.632316Z","time spent":"357.920031ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:25:56.77348Z","caller":"traceutil/trace.go:171","msg":"trace[1167350477] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"400.071613ms","start":"2026-05-12T11:25:56.373377Z","end":"2026-05-12T11:25:56.773449Z","steps":["trace[1167350477] 'process raft request' (duration: 399.90125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:56.773677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:56.373361Z","time spent":"400.212647ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:25:56.774907Z","caller":"traceutil/trace.go:171","msg":"trace[1110592405] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"401.282455ms","start":"2026-05-12T11:25:56.373599Z","end":"2026-05-12T11:25:56.774882Z","steps":["trace[1110592405] 'process raft request' (duration: 400.966129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:25:56.775131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:25:56.37359Z","time spent":"401.413358ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:05.558246Z","caller":"traceutil/trace.go:171","msg":"trace[622822226] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"108.845272ms","start":"2026-05-12T11:26:05.449377Z","end":"2026-05-12T11:26:05.558222Z","steps":["trace[622822226] 'process raft request' (duration: 108.760161ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:05.558391Z","caller":"traceutil/trace.go:171","msg":"trace[1034448433] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"110.128896ms","start":"2026-05-12T11:26:05.4482Z","end":"2026-05-12T11:26:05.558329Z","steps":["trace[1034448433] 'process raft request' (duration: 59.503239ms)","trace[1034448433] 'compare' (duration: 50.054996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:07.829142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314729,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-12T11:26:07.978469Z","caller":"traceutil/trace.go:171","msg":"trace[496122868] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"884.971571ms","start":"2026-05-12T11:26:07.093472Z","end":"2026-05-12T11:26:07.978443Z","steps":["trace[496122868] 'process raft request' (duration: 884.811608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.978624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.093454Z","time spent":"885.084014ms","remote":"127.0.0.1:51612","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-05-12T11:26:07.991062Z","caller":"traceutil/trace.go:171","msg":"trace[1296849262] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"770.009816ms","start":"2026-05-12T11:26:07.221036Z","end":"2026-05-12T11:26:07.991046Z","steps":["trace[1296849262] 'process raft request' (duration: 767.866049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.991141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.22102Z","time spent":"770.085487ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:26:07.991566Z","caller":"traceutil/trace.go:171","msg":"trace[911163346] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"768.343417ms","start":"2026-05-12T11:26:07.223177Z","end":"2026-05-12T11:26:07.991521Z","steps":["trace[911163346] 'process raft request' (duration: 767.740917ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:07.99183Z","caller":"traceutil/trace.go:171","msg":"trace[858795916] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"768.51366ms","start":"2026-05-12T11:26:07.223301Z","end":"2026-05-12T11:26:07.991815Z","steps":["trace[858795916] 'process raft request' (duration: 768.295216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.991939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.223296Z","time spent":"768.576131ms","remote":"127.0.0.1:51376","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-05-12T11:26:07.992063Z","caller":"traceutil/trace.go:171","msg":"trace[1768586706] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"754.558992ms","start":"2026-05-12T11:26:07.237493Z","end":"2026-05-12T11:26:07.992052Z","steps":["trace[1768586706] 'process raft request' (duration: 754.308518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.992114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.237489Z","time spent":"754.601253ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:26:07.992199Z","caller":"traceutil/trace.go:171","msg":"trace[770783347] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"754.764745ms","start":"2026-05-12T11:26:07.237426Z","end":"2026-05-12T11:26:07.992191Z","steps":["trace[770783347] 'process raft request' (duration: 754.324488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.992076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.223159Z","time spent":"768.583242ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:26:07.992247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.237407Z","time spent":"754.821506ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:26:07.992321Z","caller":"traceutil/trace.go:171","msg":"trace[1949005469] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:526; }","duration":"663.874161ms","start":"2026-05-12T11:26:07.328431Z","end":"2026-05-12T11:26:07.992305Z","steps":["trace[1949005469] 'read index received' (duration: 649.853641ms)","trace[1949005469] 'applied index is now lower than readState.Index' (duration: 14.01953ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:07.992359Z","caller":"traceutil/trace.go:171","msg":"trace[1711969047] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"734.359147ms","start":"2026-05-12T11:26:07.257989Z","end":"2026-05-12T11:26:07.992348Z","steps":["trace[1711969047] 'process raft request' (duration: 734.067512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.9924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.257974Z","time spent":"734.407577ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:26:07.992439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.006043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:07.992436Z","caller":"traceutil/trace.go:171","msg":"trace[537666963] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"748.214753ms","start":"2026-05-12T11:26:07.244198Z","end":"2026-05-12T11:26:07.992413Z","steps":["trace[537666963] 'process raft request' (duration: 747.724385ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:07.992447Z","caller":"traceutil/trace.go:171","msg":"trace[1419741934] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"754.835646ms","start":"2026-05-12T11:26:07.237587Z","end":"2026-05-12T11:26:07.992423Z","steps":["trace[1419741934] 'process raft request' (duration: 754.260396ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:07.992509Z","caller":"traceutil/trace.go:171","msg":"trace[218086661] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"734.422617ms","start":"2026-05-12T11:26:07.258077Z","end":"2026-05-12T11:26:07.992499Z","steps":["trace[218086661] 'process raft request' (duration: 734.038001ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:07.992513Z","caller":"traceutil/trace.go:171","msg":"trace[597254162] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"703.271146ms","start":"2026-05-12T11:26:07.289235Z","end":"2026-05-12T11:26:07.992506Z","steps":["trace[597254162] 'process raft request' (duration: 702.93248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.992523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.244192Z","time spent":"748.289704ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:26:07.992556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.289208Z","time spent":"703.330466ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:26:07.992566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.25807Z","time spent":"734.466508ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:26:07.992608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.237582Z","time spent":"754.930608ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-12T11:26:07.992475Z","caller":"traceutil/trace.go:171","msg":"trace[1698247992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"664.074495ms","start":"2026-05-12T11:26:07.328391Z","end":"2026-05-12T11:26:07.992466Z","steps":["trace[1698247992] 'agreement among raft nodes before linearized reading' (duration: 663.989173ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:07.99265Z","caller":"traceutil/trace.go:171","msg":"trace[1971769055] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"594.843861ms","start":"2026-05-12T11:26:07.397792Z","end":"2026-05-12T11:26:07.992636Z","steps":["trace[1971769055] 'process raft request' (duration: 594.452674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.992662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.328377Z","time spent":"664.272388ms","remote":"127.0.0.1:51334","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-05-12T11:26:07.992473Z","caller":"traceutil/trace.go:171","msg":"trace[778902656] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"741.809463ms","start":"2026-05-12T11:26:07.250654Z","end":"2026-05-12T11:26:07.992464Z","steps":["trace[778902656] 'process raft request' (duration: 741.343335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:07.992715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.397765Z","time spent":"594.920073ms","remote":"127.0.0.1:51376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-12T11:26:07.992734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:07.250638Z","time spent":"742.081949ms","remote":"127.0.0.1:51376","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-05-12T11:26:13.162766Z","caller":"traceutil/trace.go:171","msg":"trace[1754362935] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"209.932833ms","start":"2026-05-12T11:26:12.95282Z","end":"2026-05-12T11:26:13.162753Z","steps":["trace[1754362935] 'read index received' (duration: 209.849782ms)","trace[1754362935] 'applied index is now lower than readState.Index' (duration: 82.351µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:13.16286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.024584ms","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-05-12T11:26:13.162859Z","caller":"traceutil/trace.go:171","msg":"trace[1553217760] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"210.866618ms","start":"2026-05-12T11:26:12.951974Z","end":"2026-05-12T11:26:13.162841Z","steps":["trace[1553217760] 'process raft request' (duration: 210.702746ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:13.162881Z","caller":"traceutil/trace.go:171","msg":"trace[1291544125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"210.073806ms","start":"2026-05-12T11:26:12.952802Z","end":"2026-05-12T11:26:13.162876Z","steps":["trace[1291544125] 'agreement among raft nodes before linearized reading' (duration: 210.009035ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:13.669095Z","caller":"traceutil/trace.go:171","msg":"trace[1824399232] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"714.685433ms","start":"2026-05-12T11:26:12.954377Z","end":"2026-05-12T11:26:13.669063Z","steps":["trace[1824399232] 'process raft request' (duration: 660.631119ms)","trace[1824399232] 'compare' (duration: 53.917162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:13.669257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:12.954353Z","time spent":"714.821235ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:13.673698Z","caller":"traceutil/trace.go:171","msg":"trace[571617320] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:621; }","duration":"444.8961ms","start":"2026-05-12T11:26:13.228783Z","end":"2026-05-12T11:26:13.673679Z","steps":["trace[571617320] 'read index received' (duration: 386.247192ms)","trace[571617320] 'applied index is now lower than readState.Index' (duration: 58.647228ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:13.673771Z","caller":"traceutil/trace.go:171","msg":"trace[2136473109] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"504.77358ms","start":"2026-05-12T11:26:13.168966Z","end":"2026-05-12T11:26:13.67374Z","steps":["trace[2136473109] 'process raft request' (duration: 504.617247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:13.673829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.644347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:13.673867Z","caller":"traceutil/trace.go:171","msg":"trace[1247360702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"343.728629ms","start":"2026-05-12T11:26:13.330127Z","end":"2026-05-12T11:26:13.673856Z","steps":["trace[1247360702] 'agreement among raft nodes before linearized reading' (duration: 343.660788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:13.67385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.050703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:13.673956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:13.168937Z","time spent":"504.923074ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:13.673977Z","caller":"traceutil/trace.go:171","msg":"trace[65300766] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:599; }","duration":"445.228876ms","start":"2026-05-12T11:26:13.228717Z","end":"2026-05-12T11:26:13.673946Z","steps":["trace[65300766] 'agreement among raft nodes before linearized reading' (duration: 445.037112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:13.673901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:13.330098Z","time spent":"343.795261ms","remote":"127.0.0.1:51334","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-05-12T11:26:13.67404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:13.22869Z","time spent":"445.340148ms","remote":"127.0.0.1:51548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"warn","ts":"2026-05-12T11:26:15.684291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:15.702944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266339376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b2wxx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-12T11:26:15.703048Z","caller":"traceutil/trace.go:171","msg":"trace[661592441] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b2wxx; range_end:; response_count:1; response_revision:607; }","duration":"1.266497369s","start":"2026-05-12T11:26:14.436516Z","end":"2026-05-12T11:26:15.703013Z","steps":["trace[661592441] 'range keys from in-memory index tree' (duration: 1.266141794s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:15.70312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:14.436498Z","time spent":"1.266606441s","remote":"127.0.0.1:51524","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-b2wxx\" "} {"level":"warn","ts":"2026-05-12T11:26:15.73582Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057816738s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:26:15.738579Z","caller":"traceutil/trace.go:171","msg":"trace[850092187] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.062032547s","start":"2026-05-12T11:26:14.676514Z","end":"2026-05-12T11:26:15.738547Z","steps":["trace[850092187] 'process raft request' (duration: 1.060368439s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:15.738782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:14.676491Z","time spent":"1.062170629s","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:16.001314Z","caller":"traceutil/trace.go:171","msg":"trace[524318936] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"798.290957ms","start":"2026-05-12T11:26:15.202993Z","end":"2026-05-12T11:26:16.001284Z","steps":["trace[524318936] 'process raft request' (duration: 797.85433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.001478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:15.20297Z","time spent":"798.419588ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:16.001771Z","caller":"traceutil/trace.go:171","msg":"trace[1016668623] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"818.465106ms","start":"2026-05-12T11:26:15.183247Z","end":"2026-05-12T11:26:16.001712Z","steps":["trace[1016668623] 'read index received' (duration: 552.914547ms)","trace[1016668623] 'applied index is now lower than readState.Index' (duration: 265.532018ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:16.001908Z","caller":"traceutil/trace.go:171","msg":"trace[1571771871] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"285.827431ms","start":"2026-05-12T11:26:15.716058Z","end":"2026-05-12T11:26:16.001886Z","steps":["trace[1571771871] 'process raft request' (duration: 285.530336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.002123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.857633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T11:26:16.002217Z","caller":"traceutil/trace.go:171","msg":"trace[1494637075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"819.004665ms","start":"2026-05-12T11:26:15.183198Z","end":"2026-05-12T11:26:16.002203Z","steps":["trace[1494637075] 'agreement among raft nodes before linearized reading' (duration: 818.737991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.002283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:15.183166Z","time spent":"819.104396ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-12T11:26:16.046693Z","caller":"traceutil/trace.go:171","msg":"trace[1024051549] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"255.108546ms","start":"2026-05-12T11:26:15.791547Z","end":"2026-05-12T11:26:16.046656Z","steps":["trace[1024051549] 'process raft request' (duration: 254.858141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.046907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.523839ms","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-05-12T11:26:16.046968Z","caller":"traceutil/trace.go:171","msg":"trace[14252439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"368.644491ms","start":"2026-05-12T11:26:15.678307Z","end":"2026-05-12T11:26:16.046952Z","steps":["trace[14252439] 'agreement among raft nodes before linearized reading' (duration: 368.421517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.047005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:15.678249Z","time spent":"368.747192ms","remote":"127.0.0.1:51612","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-05-12T11:26:16.046988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.775388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T11:26:16.047053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.726302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:16.047102Z","caller":"traceutil/trace.go:171","msg":"trace[667349615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"718.842324ms","start":"2026-05-12T11:26:15.328246Z","end":"2026-05-12T11:26:16.047088Z","steps":["trace[667349615] 'agreement among raft nodes before linearized reading' (duration: 718.368186ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:16.047103Z","caller":"traceutil/trace.go:171","msg":"trace[144148829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"372.91367ms","start":"2026-05-12T11:26:15.674139Z","end":"2026-05-12T11:26:16.047052Z","steps":["trace[144148829] 'agreement among raft nodes before linearized reading' (duration: 372.647046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.047155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:15.32816Z","time spent":"718.979997ms","remote":"127.0.0.1:51334","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-05-12T11:26:16.04717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:15.674099Z","time spent":"373.054323ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T11:26:16.221573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.78633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:26:16.222304Z","caller":"traceutil/trace.go:171","msg":"trace[1055516123] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"173.409469ms","start":"2026-05-12T11:26:16.048872Z","end":"2026-05-12T11:26:16.222281Z","steps":["trace[1055516123] 'read index received' (duration: 45.580192ms)","trace[1055516123] 'applied index is now lower than readState.Index' (duration: 127.826617ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:16.22251Z","caller":"traceutil/trace.go:171","msg":"trace[1973495896] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"170.535693ms","start":"2026-05-12T11:26:16.051961Z","end":"2026-05-12T11:26:16.222497Z","steps":["trace[1973495896] 'process raft request' (duration: 170.201907ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.222509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.607062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:16.222487Z","caller":"traceutil/trace.go:171","msg":"trace[1905614239] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"213.929337ms","start":"2026-05-12T11:26:16.008468Z","end":"2026-05-12T11:26:16.222397Z","steps":["trace[1905614239] 'process raft request' (duration: 85.977858ms)","trace[1905614239] 'compare' (duration: 126.662869ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:16.222599Z","caller":"traceutil/trace.go:171","msg":"trace[1013885130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"173.733524ms","start":"2026-05-12T11:26:16.048842Z","end":"2026-05-12T11:26:16.222575Z","steps":["trace[1013885130] 'agreement among raft nodes before linearized reading' (duration: 173.556301ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:16.223156Z","caller":"traceutil/trace.go:171","msg":"trace[117182196] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"171.018261ms","start":"2026-05-12T11:26:16.052118Z","end":"2026-05-12T11:26:16.223136Z","steps":["trace[117182196] 'process raft request' (duration: 170.39199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:16.223287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.982673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-12T11:26:16.223526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.265ms","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-05-12T11:26:16.223602Z","caller":"traceutil/trace.go:171","msg":"trace[1912237317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"135.365602ms","start":"2026-05-12T11:26:16.088223Z","end":"2026-05-12T11:26:16.223589Z","steps":["trace[1912237317] 'agreement among raft nodes before linearized reading' (duration: 135.003106ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:16.224281Z","caller":"traceutil/trace.go:171","msg":"trace[1557082226] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:614; }","duration":"162.135785ms","start":"2026-05-12T11:26:16.061239Z","end":"2026-05-12T11:26:16.223375Z","steps":["trace[1557082226] 'agreement among raft nodes before linearized reading' (duration: 161.980583ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:17.074566Z","caller":"traceutil/trace.go:171","msg":"trace[766939450] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"845.548408ms","start":"2026-05-12T11:26:16.228985Z","end":"2026-05-12T11:26:17.074534Z","steps":["trace[766939450] 'process raft request' (duration: 841.746937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:17.074723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:16.228954Z","time spent":"845.689669ms","remote":"127.0.0.1:51612","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-05-12T11:26:17.167427Z","caller":"traceutil/trace.go:171","msg":"trace[1794636962] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"524.003373ms","start":"2026-05-12T11:26:16.643389Z","end":"2026-05-12T11:26:17.167393Z","steps":["trace[1794636962] 'process raft request' (duration: 523.377152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:17.167598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:16.643351Z","time spent":"524.153476ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:26:18.730046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:19.230509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:19.731388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:20.232145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:20.733199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208314990,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:20.765505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.439380923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:20.765593Z","caller":"traceutil/trace.go:171","msg":"trace[555918376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"3.439499846s","start":"2026-05-12T11:26:17.326069Z","end":"2026-05-12T11:26:20.765569Z","steps":["trace[555918376] 'range keys from in-memory index tree' (duration: 3.439326123s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.76581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:17.326056Z","time spent":"3.439575307s","remote":"127.0.0.1:51334","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-05-12T11:26:20.766534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.844170499s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:20.766653Z","caller":"traceutil/trace.go:171","msg":"trace[1458274318] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:617; }","duration":"2.844297141s","start":"2026-05-12T11:26:17.922334Z","end":"2026-05-12T11:26:20.766631Z","steps":["trace[1458274318] 'range keys from in-memory index tree' (duration: 2.844146709s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.776377Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.113844118s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:26:20.777442Z","caller":"traceutil/trace.go:171","msg":"trace[1600584702] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"3.122017978s","start":"2026-05-12T11:26:17.655397Z","end":"2026-05-12T11:26:20.777415Z","steps":["trace[1600584702] 'process raft request' (duration: 3.121309067s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.777597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:17.655367Z","time spent":"3.122146981s","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:20.789048Z","caller":"traceutil/trace.go:171","msg":"trace[1714623021] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:642; }","duration":"2.559687973s","start":"2026-05-12T11:26:18.229342Z","end":"2026-05-12T11:26:20.78903Z","steps":["trace[1714623021] 'read index received' (duration: 2.547310786s)","trace[1714623021] 'applied index is now lower than readState.Index' (duration: 12.376597ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:20.789257Z","caller":"traceutil/trace.go:171","msg":"trace[747795912] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"256.729795ms","start":"2026-05-12T11:26:20.532519Z","end":"2026-05-12T11:26:20.789249Z","steps":["trace[747795912] 'process raft request' (duration: 256.422891ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.789501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.56006331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T11:26:20.789542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.462742869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:20.789571Z","caller":"traceutil/trace.go:171","msg":"trace[1832584541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"1.46280959s","start":"2026-05-12T11:26:19.326752Z","end":"2026-05-12T11:26:20.789562Z","steps":["trace[1832584541] 'agreement among raft nodes before linearized reading' (duration: 1.462756689s)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:20.789569Z","caller":"traceutil/trace.go:171","msg":"trace[1328383719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:619; }","duration":"2.560203012s","start":"2026-05-12T11:26:18.229353Z","end":"2026-05-12T11:26:20.789556Z","steps":["trace[1328383719] 'agreement among raft nodes before linearized reading' (duration: 2.560028909s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.789591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:19.326735Z","time spent":"1.46285155s","remote":"127.0.0.1:51348","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-05-12T11:26:20.789599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:18.229319Z","time spent":"2.560273753s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-12T11:26:20.789735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.560397605s","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-05-12T11:26:20.789762Z","caller":"traceutil/trace.go:171","msg":"trace[556453988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:619; }","duration":"2.560479926s","start":"2026-05-12T11:26:18.229276Z","end":"2026-05-12T11:26:20.789756Z","steps":["trace[556453988] 'agreement among raft nodes before linearized reading' (duration: 2.560395445s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.789766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.710943939s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-12T11:26:20.789782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:18.229246Z","time spent":"2.560531646s","remote":"127.0.0.1:51612","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":"info","ts":"2026-05-12T11:26:20.789789Z","caller":"traceutil/trace.go:171","msg":"trace[1924465246] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:619; }","duration":"1.71100456s","start":"2026-05-12T11:26:19.078776Z","end":"2026-05-12T11:26:20.789781Z","steps":["trace[1924465246] 'agreement among raft nodes before linearized reading' (duration: 1.710941829s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.789809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:19.078755Z","time spent":"1.711050761s","remote":"127.0.0.1:51612","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-05-12T11:26:20.7899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.558480074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T11:26:20.789917Z","caller":"traceutil/trace.go:171","msg":"trace[1278517992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:619; }","duration":"2.558524516s","start":"2026-05-12T11:26:18.231388Z","end":"2026-05-12T11:26:20.789912Z","steps":["trace[1278517992] 'agreement among raft nodes before linearized reading' (duration: 2.558470095s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.789934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:18.231375Z","time spent":"2.558554916s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T11:26:20.789937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.326058732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:20.78996Z","caller":"traceutil/trace.go:171","msg":"trace[1262594591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"1.326121262s","start":"2026-05-12T11:26:19.463832Z","end":"2026-05-12T11:26:20.789954Z","steps":["trace[1262594591] 'agreement among raft nodes before linearized reading' (duration: 1.326081772s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:20.789986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:19.463811Z","time spent":"1.326170054s","remote":"127.0.0.1:51334","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-05-12T11:26:21.686028Z","caller":"traceutil/trace.go:171","msg":"trace[251771452] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"230.270198ms","start":"2026-05-12T11:26:21.45573Z","end":"2026-05-12T11:26:21.686Z","steps":["trace[251771452] 'process raft request' (duration: 207.134331ms)","trace[251771452] 'compare' (duration: 23.001915ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:21.687817Z","caller":"traceutil/trace.go:171","msg":"trace[150923392] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"168.361245ms","start":"2026-05-12T11:26:21.519423Z","end":"2026-05-12T11:26:21.687785Z","steps":["trace[150923392] 'read index received' (duration: 143.42292ms)","trace[150923392] 'applied index is now lower than readState.Index' (duration: 24.936965ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:21.688007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.559479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-12T11:26:21.688057Z","caller":"traceutil/trace.go:171","msg":"trace[321872359] range","detail":"{range_begin:/registry/masterleases/199.19.213.205; range_end:; response_count:1; response_revision:629; }","duration":"168.64058ms","start":"2026-05-12T11:26:21.519401Z","end":"2026-05-12T11:26:21.688042Z","steps":["trace[321872359] 'agreement among raft nodes before linearized reading' (duration: 168.469258ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:21.68823Z","caller":"traceutil/trace.go:171","msg":"trace[1313173386] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"221.366735ms","start":"2026-05-12T11:26:21.466844Z","end":"2026-05-12T11:26:21.68821Z","steps":["trace[1313173386] 'process raft request' (duration: 220.827477ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:23.213136Z","caller":"traceutil/trace.go:171","msg":"trace[820756825] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"355.832329ms","start":"2026-05-12T11:26:22.857273Z","end":"2026-05-12T11:26:23.213105Z","steps":["trace[820756825] 'process raft request' (duration: 355.639166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:23.213469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:22.857257Z","time spent":"356.091964ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:23.269964Z","caller":"traceutil/trace.go:171","msg":"trace[1727893505] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"412.589699ms","start":"2026-05-12T11:26:22.857348Z","end":"2026-05-12T11:26:23.269938Z","steps":["trace[1727893505] 'process raft request' (duration: 412.526608ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:23.269976Z","caller":"traceutil/trace.go:171","msg":"trace[61065590] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"412.6291ms","start":"2026-05-12T11:26:22.85732Z","end":"2026-05-12T11:26:23.269949Z","steps":["trace[61065590] 'process raft request' (duration: 412.434377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:23.270116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:22.85732Z","time spent":"412.717202ms","remote":"127.0.0.1:51612","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-05-12T11:26:23.270147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:22.857299Z","time spent":"412.750742ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:23.270287Z","caller":"traceutil/trace.go:171","msg":"trace[1990354763] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"412.549699ms","start":"2026-05-12T11:26:22.857686Z","end":"2026-05-12T11:26:23.270235Z","steps":["trace[1990354763] 'process raft request' (duration: 412.222584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:23.270472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:22.857674Z","time spent":"412.699711ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:26:23.270929Z","caller":"traceutil/trace.go:171","msg":"trace[671639048] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"321.949682ms","start":"2026-05-12T11:26:22.948953Z","end":"2026-05-12T11:26:23.270903Z","steps":["trace[671639048] 'process raft request' (duration: 321.391273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:23.271077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:22.948919Z","time spent":"322.082173ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:26:25.901779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.294366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-12T11:26:25.901869Z","caller":"traceutil/trace.go:171","msg":"trace[1654116047] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:707; }","duration":"214.427709ms","start":"2026-05-12T11:26:25.687422Z","end":"2026-05-12T11:26:25.90185Z","steps":["trace[1654116047] 'range keys from in-memory index tree' (duration: 214.111624ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:32.26309Z","caller":"traceutil/trace.go:171","msg":"trace[260817175] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"146.670723ms","start":"2026-05-12T11:26:32.116398Z","end":"2026-05-12T11:26:32.263068Z","steps":["trace[260817175] 'process raft request' (duration: 146.4582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:36.662505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.37767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-knl96\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-12T11:26:36.662592Z","caller":"traceutil/trace.go:171","msg":"trace[1062105176] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-knl96; range_end:; response_count:1; response_revision:793; }","duration":"106.522253ms","start":"2026-05-12T11:26:36.556049Z","end":"2026-05-12T11:26:36.662571Z","steps":["trace[1062105176] 'range keys from in-memory index tree' (duration: 106.175857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:37.774764Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208315335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:38.275774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3923372068208315335,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-12T11:26:38.466161Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.79292585s","expected-duration":"1s"} {"level":"info","ts":"2026-05-12T11:26:38.466604Z","caller":"traceutil/trace.go:171","msg":"trace[1541341535] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.793432308s","start":"2026-05-12T11:26:36.673135Z","end":"2026-05-12T11:26:38.466567Z","steps":["trace[1541341535] 'process raft request' (duration: 1.793251665s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:38.466769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:36.673115Z","time spent":"1.793568209s","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:26:38.695111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.666531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-12T11:26:38.695204Z","caller":"traceutil/trace.go:171","msg":"trace[1563350401] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"1.420752226s","start":"2026-05-12T11:26:37.274432Z","end":"2026-05-12T11:26:38.695185Z","steps":["trace[1563350401] 'read index received' (duration: 1.191996099s)","trace[1563350401] 'applied index is now lower than readState.Index' (duration: 228.754537ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:26:38.695501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42104395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-12T11:26:38.695645Z","caller":"traceutil/trace.go:171","msg":"trace[671650143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.421203833s","start":"2026-05-12T11:26:37.274375Z","end":"2026-05-12T11:26:38.695578Z","steps":["trace[671650143] 'agreement among raft nodes before linearized reading' (duration: 1.420899868s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:38.695755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368307785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:26:38.695815Z","caller":"traceutil/trace.go:171","msg":"trace[120653190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"1.368411746s","start":"2026-05-12T11:26:37.327387Z","end":"2026-05-12T11:26:38.695799Z","steps":["trace[120653190] 'agreement among raft nodes before linearized reading' (duration: 1.368307725s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:38.695773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:37.274352Z","time spent":"1.421398926s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-12T11:26:38.695875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:37.327366Z","time spent":"1.368481168s","remote":"127.0.0.1:51334","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-05-12T11:26:38.696039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334853944s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-12T11:26:38.697165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356276937s","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-05-12T11:26:38.697307Z","caller":"traceutil/trace.go:171","msg":"trace[1640946637] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"1.35645643s","start":"2026-05-12T11:26:37.340812Z","end":"2026-05-12T11:26:38.697268Z","steps":["trace[1640946637] 'agreement among raft nodes before linearized reading' (duration: 1.355200432s)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:38.697209Z","caller":"traceutil/trace.go:171","msg":"trace[1114659702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"1.336048601s","start":"2026-05-12T11:26:37.361146Z","end":"2026-05-12T11:26:38.697195Z","steps":["trace[1114659702] 'agreement among raft nodes before linearized reading' (duration: 1.334791813s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:38.697349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:37.340788Z","time spent":"1.356551681s","remote":"127.0.0.1:51612","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-05-12T11:26:38.69629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11616189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-12T11:26:38.697408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:37.36113Z","time spent":"1.336236454s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-12T11:26:38.697461Z","caller":"traceutil/trace.go:171","msg":"trace[169312885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.117341166s","start":"2026-05-12T11:26:37.580081Z","end":"2026-05-12T11:26:38.697422Z","steps":["trace[169312885] 'agreement among raft nodes before linearized reading' (duration: 1.116145159s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:38.696155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334020891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T11:26:38.697588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:37.580059Z","time spent":"1.117441859s","remote":"127.0.0.1:51612","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-05-12T11:26:38.697611Z","caller":"traceutil/trace.go:171","msg":"trace[1709529131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.335488982s","start":"2026-05-12T11:26:37.362108Z","end":"2026-05-12T11:26:38.697597Z","steps":["trace[1709529131] 'agreement among raft nodes before linearized reading' (duration: 1.333643036s)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:38.697722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:26:37.362097Z","time spent":"1.335609805s","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-12T11:26:41.783458Z","caller":"traceutil/trace.go:171","msg":"trace[1731779644] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"179.393522ms","start":"2026-05-12T11:26:41.604039Z","end":"2026-05-12T11:26:41.783433Z","steps":["trace[1731779644] 'process raft request' (duration: 178.628222ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:26:52.982752Z","caller":"traceutil/trace.go:171","msg":"trace[325535505] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"132.898072ms","start":"2026-05-12T11:26:52.849833Z","end":"2026-05-12T11:26:52.982731Z","steps":["trace[325535505] 'read index received' (duration: 132.71363ms)","trace[325535505] 'applied index is now lower than readState.Index' (duration: 183.242µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:26:52.983038Z","caller":"traceutil/trace.go:171","msg":"trace[129910123] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"183.025417ms","start":"2026-05-12T11:26:52.800003Z","end":"2026-05-12T11:26:52.983029Z","steps":["trace[129910123] 'process raft request' (duration: 182.611471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:52.983267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.42354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T11:26:52.983302Z","caller":"traceutil/trace.go:171","msg":"trace[162443227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"133.500821ms","start":"2026-05-12T11:26:52.849794Z","end":"2026-05-12T11:26:52.983295Z","steps":["trace[162443227] 'agreement among raft nodes before linearized reading' (duration: 133.381489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:52.983421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.127833ms","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-05-12T11:26:52.983446Z","caller":"traceutil/trace.go:171","msg":"trace[1279272080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"117.168095ms","start":"2026-05-12T11:26:52.866273Z","end":"2026-05-12T11:26:52.983441Z","steps":["trace[1279272080] 'agreement among raft nodes before linearized reading' (duration: 117.111004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:52.983618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.139804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T11:26:52.983644Z","caller":"traceutil/trace.go:171","msg":"trace[1334315332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"117.201154ms","start":"2026-05-12T11:26:52.866437Z","end":"2026-05-12T11:26:52.983639Z","steps":["trace[1334315332] 'agreement among raft nodes before linearized reading' (duration: 117.137183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:26:52.983724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.247435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-12T11:26:52.983745Z","caller":"traceutil/trace.go:171","msg":"trace[1046598826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"117.307936ms","start":"2026-05-12T11:26:52.866433Z","end":"2026-05-12T11:26:52.983741Z","steps":["trace[1046598826] 'agreement among raft nodes before linearized reading' (duration: 117.256495ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:11.329671Z","caller":"traceutil/trace.go:171","msg":"trace[1855579385] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"144.042428ms","start":"2026-05-12T11:27:11.1856Z","end":"2026-05-12T11:27:11.329643Z","steps":["trace[1855579385] 'process raft request' (duration: 143.887706ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:11.388859Z","caller":"traceutil/trace.go:171","msg":"trace[240787967] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"202.754094ms","start":"2026-05-12T11:27:11.186059Z","end":"2026-05-12T11:27:11.388813Z","steps":["trace[240787967] 'process raft request' (duration: 202.475741ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:11.388872Z","caller":"traceutil/trace.go:171","msg":"trace[255751143] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"202.519082ms","start":"2026-05-12T11:27:11.186327Z","end":"2026-05-12T11:27:11.388846Z","steps":["trace[255751143] 'process raft request' (duration: 202.37853ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:11.774852Z","caller":"traceutil/trace.go:171","msg":"trace[1275721565] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"111.783378ms","start":"2026-05-12T11:27:11.663039Z","end":"2026-05-12T11:27:11.774823Z","steps":["trace[1275721565] 'process raft request' (duration: 105.412555ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:12.168269Z","caller":"traceutil/trace.go:171","msg":"trace[848569549] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"156.07702ms","start":"2026-05-12T11:27:12.012163Z","end":"2026-05-12T11:27:12.16824Z","steps":["trace[848569549] 'process raft request' (duration: 155.929078ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:12.309619Z","caller":"traceutil/trace.go:171","msg":"trace[1895247775] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"175.19378ms","start":"2026-05-12T11:27:12.13438Z","end":"2026-05-12T11:27:12.309574Z","steps":["trace[1895247775] 'process raft request' (duration: 149.925821ms)","trace[1895247775] 'compare' (duration: 25.111507ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:12.309776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.749585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-knl96\" ","response":"range_response_count:1 size:4126"} {"level":"info","ts":"2026-05-12T11:27:12.309758Z","caller":"traceutil/trace.go:171","msg":"trace[77487870] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"131.520752ms","start":"2026-05-12T11:27:12.178012Z","end":"2026-05-12T11:27:12.309533Z","steps":["trace[77487870] 'read index received' (duration: 106.225503ms)","trace[77487870] 'applied index is now lower than readState.Index' (duration: 25.294119ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:12.309814Z","caller":"traceutil/trace.go:171","msg":"trace[888997890] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-knl96; range_end:; response_count:1; response_revision:1035; }","duration":"131.832715ms","start":"2026-05-12T11:27:12.177971Z","end":"2026-05-12T11:27:12.309803Z","steps":["trace[888997890] 'agreement among raft nodes before linearized reading' (duration: 131.706444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:12.310228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.739524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:2929"} {"level":"info","ts":"2026-05-12T11:27:12.310293Z","caller":"traceutil/trace.go:171","msg":"trace[2021530336] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1035; }","duration":"131.813685ms","start":"2026-05-12T11:27:12.178461Z","end":"2026-05-12T11:27:12.310275Z","steps":["trace[2021530336] 'agreement among raft nodes before linearized reading' (duration: 131.670503ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:14.482086Z","caller":"traceutil/trace.go:171","msg":"trace[1993034324] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"166.658466ms","start":"2026-05-12T11:27:14.315402Z","end":"2026-05-12T11:27:14.48206Z","steps":["trace[1993034324] 'process raft request' (duration: 166.472093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:33.530362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.799291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-12T11:27:33.530476Z","caller":"traceutil/trace.go:171","msg":"trace[1686182514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"111.000323ms","start":"2026-05-12T11:27:33.419451Z","end":"2026-05-12T11:27:33.530451Z","steps":["trace[1686182514] 'range keys from in-memory index tree' (duration: 110.565118ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:27:33.809861Z","caller":"traceutil/trace.go:171","msg":"trace[1562144143] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"187.776501ms","start":"2026-05-12T11:27:33.622054Z","end":"2026-05-12T11:27:33.80983Z","steps":["trace[1562144143] 'read index received' (duration: 141.370697ms)","trace[1562144143] 'applied index is now lower than readState.Index' (duration: 46.404454ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:27:33.809912Z","caller":"traceutil/trace.go:171","msg":"trace[801858561] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"229.416297ms","start":"2026-05-12T11:27:33.58047Z","end":"2026-05-12T11:27:33.809886Z","steps":["trace[801858561] 'process raft request' (duration: 183.015333ms)","trace[801858561] 'compare' (duration: 46.213142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:27:33.810186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.097215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T11:27:33.810535Z","caller":"traceutil/trace.go:171","msg":"trace[8666894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"188.48733ms","start":"2026-05-12T11:27:33.622019Z","end":"2026-05-12T11:27:33.810507Z","steps":["trace[8666894] 'agreement among raft nodes before linearized reading' (duration: 187.938554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:27:44.044754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.380208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:27:44.044851Z","caller":"traceutil/trace.go:171","msg":"trace[632733792] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1142; }","duration":"101.54138ms","start":"2026-05-12T11:27:43.94329Z","end":"2026-05-12T11:27:44.044831Z","steps":["trace[632733792] 'count revisions from in-memory index tree' (duration: 101.227926ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:05.681478Z","caller":"traceutil/trace.go:171","msg":"trace[79859198] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"113.449149ms","start":"2026-05-12T11:28:05.568004Z","end":"2026-05-12T11:28:05.681453Z","steps":["trace[79859198] 'process raft request' (duration: 113.268657ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:07.812739Z","caller":"traceutil/trace.go:171","msg":"trace[1803806806] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"124.431594ms","start":"2026-05-12T11:28:07.68827Z","end":"2026-05-12T11:28:07.812702Z","steps":["trace[1803806806] 'process raft request' (duration: 124.183421ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:08.328595Z","caller":"traceutil/trace.go:171","msg":"trace[1581821129] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"234.188962ms","start":"2026-05-12T11:28:08.094389Z","end":"2026-05-12T11:28:08.328578Z","steps":["trace[1581821129] 'read index received' (duration: 232.532962ms)","trace[1581821129] 'applied index is now lower than readState.Index' (duration: 1.65528ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:08.328861Z","caller":"traceutil/trace.go:171","msg":"trace[451068330] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"294.470065ms","start":"2026-05-12T11:28:08.034358Z","end":"2026-05-12T11:28:08.328828Z","steps":["trace[451068330] 'process raft request' (duration: 292.583442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:08.328883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.478535ms","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-05-12T11:28:08.328927Z","caller":"traceutil/trace.go:171","msg":"trace[396795423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"234.559507ms","start":"2026-05-12T11:28:08.094359Z","end":"2026-05-12T11:28:08.328918Z","steps":["trace[396795423] 'agreement among raft nodes before linearized reading' (duration: 234.439295ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:09.124753Z","caller":"traceutil/trace.go:171","msg":"trace[1160388937] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"110.265584ms","start":"2026-05-12T11:28:09.014453Z","end":"2026-05-12T11:28:09.124719Z","steps":["trace[1160388937] 'process raft request' (duration: 110.160273ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:10.542029Z","caller":"traceutil/trace.go:171","msg":"trace[1375172404] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"128.138451ms","start":"2026-05-12T11:28:10.413872Z","end":"2026-05-12T11:28:10.54201Z","steps":["trace[1375172404] 'process raft request' (duration: 28.978698ms)","trace[1375172404] 'compare' (duration: 98.945981ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:12.769026Z","caller":"traceutil/trace.go:171","msg":"trace[566682549] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"131.118972ms","start":"2026-05-12T11:28:12.637889Z","end":"2026-05-12T11:28:12.769008Z","steps":["trace[566682549] 'process raft request' (duration: 131.001611ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:14.112671Z","caller":"traceutil/trace.go:171","msg":"trace[1084383689] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"165.331446ms","start":"2026-05-12T11:28:13.947319Z","end":"2026-05-12T11:28:14.112651Z","steps":["trace[1084383689] 'process raft request' (duration: 165.214884ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:14.941621Z","caller":"traceutil/trace.go:171","msg":"trace[470097524] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"281.025426ms","start":"2026-05-12T11:28:14.660561Z","end":"2026-05-12T11:28:14.941587Z","steps":["trace[470097524] 'process raft request' (duration: 280.785883ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:17.062583Z","caller":"traceutil/trace.go:171","msg":"trace[1621530199] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"114.880837ms","start":"2026-05-12T11:28:16.94768Z","end":"2026-05-12T11:28:17.062561Z","steps":["trace[1621530199] 'process raft request' (duration: 114.743405ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:18.606116Z","caller":"traceutil/trace.go:171","msg":"trace[233343408] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"166.798693ms","start":"2026-05-12T11:28:18.439296Z","end":"2026-05-12T11:28:18.606095Z","steps":["trace[233343408] 'process raft request' (duration: 160.386598ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:23.006839Z","caller":"traceutil/trace.go:171","msg":"trace[1701583473] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"125.126566ms","start":"2026-05-12T11:28:22.881658Z","end":"2026-05-12T11:28:23.006784Z","steps":["trace[1701583473] 'read index received' (duration: 125.030975ms)","trace[1701583473] 'applied index is now lower than readState.Index' (duration: 94.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:23.006847Z","caller":"traceutil/trace.go:171","msg":"trace[1827648995] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"182.448115ms","start":"2026-05-12T11:28:22.824362Z","end":"2026-05-12T11:28:23.00681Z","steps":["trace[1827648995] 'process raft request' (duration: 182.261282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:23.007062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.3694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:23.007137Z","caller":"traceutil/trace.go:171","msg":"trace[1341039416] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1282; }","duration":"125.503831ms","start":"2026-05-12T11:28:22.881616Z","end":"2026-05-12T11:28:23.00712Z","steps":["trace[1341039416] 'agreement among raft nodes before linearized reading' (duration: 125.311809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:23.04466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.952226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-015f0b41-70f4-4ee7-9a5a-c7586e694c50\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-05-12T11:28:23.044719Z","caller":"traceutil/trace.go:171","msg":"trace[2035899027] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-015f0b41-70f4-4ee7-9a5a-c7586e694c50; range_end:; response_count:1; response_revision:1283; }","duration":"106.051297ms","start":"2026-05-12T11:28:22.938654Z","end":"2026-05-12T11:28:23.044706Z","steps":["trace[2035899027] 'agreement among raft nodes before linearized reading' (duration: 105.874965ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:23.044725Z","caller":"traceutil/trace.go:171","msg":"trace[1093209510] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"105.276007ms","start":"2026-05-12T11:28:22.939353Z","end":"2026-05-12T11:28:23.044629Z","steps":["trace[1093209510] 'process raft request' (duration: 105.064035ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:30.49055Z","caller":"traceutil/trace.go:171","msg":"trace[736981972] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"159.212123ms","start":"2026-05-12T11:28:30.331281Z","end":"2026-05-12T11:28:30.490493Z","steps":["trace[736981972] 'read index received' (duration: 158.92724ms)","trace[736981972] 'applied index is now lower than readState.Index' (duration: 283.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:30.490608Z","caller":"traceutil/trace.go:171","msg":"trace[2054376442] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"164.142669ms","start":"2026-05-12T11:28:30.326436Z","end":"2026-05-12T11:28:30.490579Z","steps":["trace[2054376442] 'process raft request' (duration: 163.827196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:30.490992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.445846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:30.491049Z","caller":"traceutil/trace.go:171","msg":"trace[354628726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"159.77768ms","start":"2026-05-12T11:28:30.331257Z","end":"2026-05-12T11:28:30.491034Z","steps":["trace[354628726] 'agreement among raft nodes before linearized reading' (duration: 159.403636ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:30.830156Z","caller":"traceutil/trace.go:171","msg":"trace[1235800055] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"269.686441ms","start":"2026-05-12T11:28:30.560443Z","end":"2026-05-12T11:28:30.830129Z","steps":["trace[1235800055] 'process raft request' (duration: 269.474418ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:35.013429Z","caller":"traceutil/trace.go:171","msg":"trace[683787830] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"119.542897ms","start":"2026-05-12T11:28:34.893848Z","end":"2026-05-12T11:28:35.013391Z","steps":["trace[683787830] 'process raft request' (duration: 119.365845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:40.967318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.279388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-12T11:28:40.967403Z","caller":"traceutil/trace.go:171","msg":"trace[164120375] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1363; }","duration":"183.425103ms","start":"2026-05-12T11:28:40.783959Z","end":"2026-05-12T11:28:40.967385Z","steps":["trace[164120375] 'count revisions from in-memory index tree' (duration: 183.135744ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:28:41.108892Z","caller":"traceutil/trace.go:171","msg":"trace[1631630499] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"135.782288ms","start":"2026-05-12T11:28:40.973082Z","end":"2026-05-12T11:28:41.108864Z","steps":["trace[1631630499] 'process raft request' (duration: 135.587991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:41.282995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.614756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-12T11:28:41.283105Z","caller":"traceutil/trace.go:171","msg":"trace[959754064] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1421; }","duration":"245.961714ms","start":"2026-05-12T11:28:41.037124Z","end":"2026-05-12T11:28:41.283086Z","steps":["trace[959754064] 'read index received' (duration: 71.672119ms)","trace[959754064] 'applied index is now lower than readState.Index' (duration: 174.288445ms)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:28:41.283272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.143759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-12T11:28:41.283209Z","caller":"traceutil/trace.go:171","msg":"trace[84948202] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"309.705031ms","start":"2026-05-12T11:28:40.973427Z","end":"2026-05-12T11:28:41.283132Z","steps":["trace[84948202] 'process raft request' (duration: 207.867088ms)","trace[84948202] 'compare' (duration: 101.479032ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:41.283308Z","caller":"traceutil/trace.go:171","msg":"trace[1308624245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"246.225551ms","start":"2026-05-12T11:28:41.037071Z","end":"2026-05-12T11:28:41.283297Z","steps":["trace[1308624245] 'agreement among raft nodes before linearized reading' (duration: 246.068507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:41.283504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:40.973416Z","time spent":"309.920259ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:28:42.032702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:41.596148Z","time spent":"436.54754ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-12T11:28:42.600286Z","caller":"traceutil/trace.go:171","msg":"trace[2075763765] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"939.417606ms","start":"2026-05-12T11:28:41.66084Z","end":"2026-05-12T11:28:42.600258Z","steps":["trace[2075763765] 'process raft request' (duration: 939.249881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:42.600434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:41.660811Z","time spent":"939.54222ms","remote":"127.0.0.1:51612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-12T11:28:42.65372Z","caller":"traceutil/trace.go:171","msg":"trace[1579516675] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1426; }","duration":"321.804067ms","start":"2026-05-12T11:28:42.331881Z","end":"2026-05-12T11:28:42.653685Z","steps":["trace[1579516675] 'read index received' (duration: 268.088802ms)","trace[1579516675] 'applied index is now lower than readState.Index' (duration: 53.713865ms)"],"step_count":2} {"level":"info","ts":"2026-05-12T11:28:42.653798Z","caller":"traceutil/trace.go:171","msg":"trace[934322329] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"620.046906ms","start":"2026-05-12T11:28:42.033721Z","end":"2026-05-12T11:28:42.653768Z","steps":["trace[934322329] 'process raft request' (duration: 619.743887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:42.654029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:42.033681Z","time spent":"620.199581ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-12T11:28:42.654016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.103595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-12T11:28:42.65417Z","caller":"traceutil/trace.go:171","msg":"trace[182011369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"322.333603ms","start":"2026-05-12T11:28:42.331814Z","end":"2026-05-12T11:28:42.654148Z","steps":["trace[182011369] 'agreement among raft nodes before linearized reading' (duration: 321.994502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:28:42.654278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-12T11:28:42.331767Z","time spent":"322.487207ms","remote":"127.0.0.1:51334","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-05-12T11:28:57.627478Z","caller":"traceutil/trace.go:171","msg":"trace[117925150] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"104.676149ms","start":"2026-05-12T11:28:57.522764Z","end":"2026-05-12T11:28:57.62744Z","steps":["trace[117925150] 'process raft request' (duration: 102.631049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:29:00.082642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.008005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-05-12T11:29:00.082733Z","caller":"traceutil/trace.go:171","msg":"trace[402213351] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1431; }","duration":"118.147169ms","start":"2026-05-12T11:28:59.96456Z","end":"2026-05-12T11:29:00.082707Z","steps":["trace[402213351] 'range keys from in-memory index tree' (duration: 117.823429ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:04.885333Z","caller":"traceutil/trace.go:171","msg":"trace[43763042] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"104.267348ms","start":"2026-05-12T11:29:04.781021Z","end":"2026-05-12T11:29:04.885289Z","steps":["trace[43763042] 'process raft request' (duration: 103.922739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:29:13.885392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.157219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"warn","ts":"2026-05-12T11:29:13.885413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.399856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-05-12T11:29:13.885489Z","caller":"traceutil/trace.go:171","msg":"trace[970191676] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1475; }","duration":"121.289393ms","start":"2026-05-12T11:29:13.76418Z","end":"2026-05-12T11:29:13.885469Z","steps":["trace[970191676] 'range keys from in-memory index tree' (duration: 121.080027ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:13.885511Z","caller":"traceutil/trace.go:171","msg":"trace[1406493526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1475; }","duration":"124.53079ms","start":"2026-05-12T11:29:13.760954Z","end":"2026-05-12T11:29:13.885485Z","steps":["trace[1406493526] 'range keys from in-memory index tree' (duration: 124.313354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:29:13.885506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.512399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-12T11:29:13.885571Z","caller":"traceutil/trace.go:171","msg":"trace[1797084482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1475; }","duration":"104.600802ms","start":"2026-05-12T11:29:13.780955Z","end":"2026-05-12T11:29:13.885556Z","steps":["trace[1797084482] 'range keys from in-memory index tree' (duration: 104.386886ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:14.076332Z","caller":"traceutil/trace.go:171","msg":"trace[2000401489] linearizableReadLoop","detail":"{readStateIndex:1542; appliedIndex:1541; }","duration":"183.256486ms","start":"2026-05-12T11:29:13.893049Z","end":"2026-05-12T11:29:14.076305Z","steps":["trace[2000401489] 'read index received' (duration: 183.141453ms)","trace[2000401489] 'applied index is now lower than readState.Index' (duration: 113.423µs)"],"step_count":2} {"level":"warn","ts":"2026-05-12T11:29:14.076492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.41732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:1 size:191"} {"level":"info","ts":"2026-05-12T11:29:14.076537Z","caller":"traceutil/trace.go:171","msg":"trace[70494765] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:1; response_revision:1476; }","duration":"183.513913ms","start":"2026-05-12T11:29:13.893012Z","end":"2026-05-12T11:29:14.076526Z","steps":["trace[70494765] 'agreement among raft nodes before linearized reading' (duration: 183.41317ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:14.076445Z","caller":"traceutil/trace.go:171","msg":"trace[1776430493] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"186.067231ms","start":"2026-05-12T11:29:13.890316Z","end":"2026-05-12T11:29:14.076383Z","steps":["trace[1776430493] 'process raft request' (duration: 185.843695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-12T11:29:14.098503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.350708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-12T11:29:14.098552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.814562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:1 size:224"} {"level":"info","ts":"2026-05-12T11:29:14.098606Z","caller":"traceutil/trace.go:171","msg":"trace[1613667307] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:1; response_revision:1477; }","duration":"142.871774ms","start":"2026-05-12T11:29:13.955718Z","end":"2026-05-12T11:29:14.09859Z","steps":["trace[1613667307] 'agreement among raft nodes before linearized reading' (duration: 142.763681ms)"],"step_count":1} {"level":"info","ts":"2026-05-12T11:29:14.098597Z","caller":"traceutil/trace.go:171","msg":"trace[439678012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1477; }","duration":"159.500752ms","start":"2026-05-12T11:29:13.939074Z","end":"2026-05-12T11:29:14.098574Z","steps":["trace[439678012] 'agreement among raft nodes before linearized reading' (duration: 159.251906ms)"],"step_count":1}