{"level":"warn","ts":"2026-04-28T04:56:12.142284Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T04:56:12.142389Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T04:56:12.142463Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T04:56:12.142481Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-28T04:56:12.1425Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T04:56:12.143096Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-28T04:56:12.143174Z","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.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T04:56:12.147355Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.938836ms"} {"level":"info","ts":"2026-04-28T04:56:12.156903Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-28T04:56:12.156998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T04:56:12.157029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-28T04:56:12.157049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T04:56:12.15707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-28T04:56:12.157123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-28T04:56:12.163284Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T04:56:12.164743Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T04:56:12.166402Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T04:56:12.1687Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T04:56:12.170624Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T04:56:12.170767Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T04:56:12.170858Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T04:56:12.170876Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T04:56:12.171318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-28T04:56:12.17147Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-28T04:56:12.17507Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T04:56:12.175221Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-28T04:56:12.175259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-28T04:56:12.175465Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T04:56:12.175497Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T04:56:13.157596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T04:56:13.157655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T04:56:13.157678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-28T04:56:13.157891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-28T04:56:13.157913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-28T04:56:13.157933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-28T04:56:13.157966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-28T04:56:13.159228Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T04:56:13.160089Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T04:56:13.160092Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T04:56:13.160298Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T04:56:13.160901Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T04:56:13.161266Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T04:56:13.161316Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T04:56:13.163553Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-28T04:56:13.163727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-28T04:56:13.166859Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T04:56:13.16689Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T04:56:14.667767Z","caller":"traceutil/trace.go:171","msg":"trace[1594471354] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"285.835178ms","start":"2026-04-28T04:56:14.381911Z","end":"2026-04-28T04:56:14.667746Z","steps":["trace[1594471354] 'process raft request' (duration: 285.621421ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.728406Z","caller":"traceutil/trace.go:171","msg":"trace[404209932] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"346.242048ms","start":"2026-04-28T04:56:14.382145Z","end":"2026-04-28T04:56:14.728387Z","steps":["trace[404209932] 'process raft request' (duration: 345.877796ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.728418Z","caller":"traceutil/trace.go:171","msg":"trace[558033876] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"335.992853ms","start":"2026-04-28T04:56:14.392402Z","end":"2026-04-28T04:56:14.728395Z","steps":["trace[558033876] 'read index received' (duration: 275.20582ms)","trace[558033876] 'applied index is now lower than readState.Index' (duration: 60.786263ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:14.728522Z","caller":"traceutil/trace.go:171","msg":"trace[1517280483] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"258.804149ms","start":"2026-04-28T04:56:14.469708Z","end":"2026-04-28T04:56:14.728512Z","steps":["trace[1517280483] 'process raft request' (duration: 258.586582ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.728569Z","caller":"traceutil/trace.go:171","msg":"trace[862386429] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"258.902723ms","start":"2026-04-28T04:56:14.469628Z","end":"2026-04-28T04:56:14.728531Z","steps":["trace[862386429] 'process raft request' (duration: 258.645024ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.728788Z","caller":"traceutil/trace.go:171","msg":"trace[1711801996] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"259.382398ms","start":"2026-04-28T04:56:14.469376Z","end":"2026-04-28T04:56:14.728758Z","steps":["trace[1711801996] 'process raft request' (duration: 258.81406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.728923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:14.382136Z","time spent":"346.31925ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:14.728857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.434769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.728928Z","caller":"traceutil/trace.go:171","msg":"trace[1765995753] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"259.306595ms","start":"2026-04-28T04:56:14.469603Z","end":"2026-04-28T04:56:14.728909Z","steps":["trace[1765995753] 'process raft request' (duration: 258.611442ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.729008Z","caller":"traceutil/trace.go:171","msg":"trace[1705206303] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"254.227565ms","start":"2026-04-28T04:56:14.474739Z","end":"2026-04-28T04:56:14.728966Z","steps":["trace[1705206303] 'process raft request' (duration: 253.590774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.72902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.53507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.729017Z","caller":"traceutil/trace.go:171","msg":"trace[659042986] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"336.630175ms","start":"2026-04-28T04:56:14.392371Z","end":"2026-04-28T04:56:14.729001Z","steps":["trace[659042986] 'agreement among raft nodes before linearized reading' (duration: 336.403127ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.729172Z","caller":"traceutil/trace.go:171","msg":"trace[294728774] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"259.546395ms","start":"2026-04-28T04:56:14.469614Z","end":"2026-04-28T04:56:14.729161Z","steps":["trace[294728774] 'process raft request' (duration: 258.636084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.729191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:14.392356Z","time spent":"336.821292ms","remote":"127.0.0.1:52496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-28T04:56:14.729308Z","caller":"traceutil/trace.go:171","msg":"trace[588030225] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"247.551741ms","start":"2026-04-28T04:56:14.481744Z","end":"2026-04-28T04:56:14.729295Z","steps":["trace[588030225] 'process raft request' (duration: 246.618779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.729336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.388238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T04:56:14.729422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.874508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.729106Z","caller":"traceutil/trace.go:171","msg":"trace[472601716] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"294.661285ms","start":"2026-04-28T04:56:14.434428Z","end":"2026-04-28T04:56:14.72909Z","steps":["trace[472601716] 'agreement among raft nodes before linearized reading' (duration: 294.506729ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.729484Z","caller":"traceutil/trace.go:171","msg":"trace[1638853834] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:0; response_revision:11; }","duration":"268.9528ms","start":"2026-04-28T04:56:14.460517Z","end":"2026-04-28T04:56:14.72947Z","steps":["trace[1638853834] 'agreement among raft nodes before linearized reading' (duration: 268.861077ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.729491Z","caller":"traceutil/trace.go:171","msg":"trace[1655725287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"267.493931ms","start":"2026-04-28T04:56:14.461925Z","end":"2026-04-28T04:56:14.729419Z","steps":["trace[1655725287] 'agreement among raft nodes before linearized reading' (duration: 267.358897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.729555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.160479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.729627Z","caller":"traceutil/trace.go:171","msg":"trace[1201105288] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"217.238771ms","start":"2026-04-28T04:56:14.512369Z","end":"2026-04-28T04:56:14.729608Z","steps":["trace[1201105288] 'agreement among raft nodes before linearized reading' (duration: 217.131758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.72964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.453756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.729717Z","caller":"traceutil/trace.go:171","msg":"trace[2127764004] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"266.553919ms","start":"2026-04-28T04:56:14.463148Z","end":"2026-04-28T04:56:14.729702Z","steps":["trace[2127764004] 'agreement among raft nodes before linearized reading' (duration: 266.455946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.729749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.933464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.729824Z","caller":"traceutil/trace.go:171","msg":"trace[916836768] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"295.019857ms","start":"2026-04-28T04:56:14.434792Z","end":"2026-04-28T04:56:14.729812Z","steps":["trace[916836768] 'agreement among raft nodes before linearized reading' (duration: 294.936254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:14.729678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.82781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-28T04:56:14.729907Z","caller":"traceutil/trace.go:171","msg":"trace[2017111210] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"295.060529ms","start":"2026-04-28T04:56:14.434829Z","end":"2026-04-28T04:56:14.729889Z","steps":["trace[2017111210] 'agreement among raft nodes before linearized reading' (duration: 294.824011ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:14.730109Z","caller":"traceutil/trace.go:171","msg":"trace[1098251430] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"267.199801ms","start":"2026-04-28T04:56:14.462889Z","end":"2026-04-28T04:56:14.730088Z","steps":["trace[1098251430] 'process raft request' (duration: 265.262636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:19.188111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065813331s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:19.188331Z","caller":"traceutil/trace.go:171","msg":"trace[2114325892] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.056927854s","start":"2026-04-28T04:56:18.131383Z","end":"2026-04-28T04:56:19.188311Z","steps":["trace[2114325892] 'process raft request' (duration: 1.056850832s)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:19.188338Z","caller":"traceutil/trace.go:171","msg":"trace[1643398298] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:288; }","duration":"1.093222109s","start":"2026-04-28T04:56:18.095083Z","end":"2026-04-28T04:56:19.188305Z","steps":["trace[1643398298] 'read index received' (duration: 1.095824ms)","trace[1643398298] 'applied index is now lower than readState.Index' (duration: 1.092124495s)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:19.188347Z","caller":"traceutil/trace.go:171","msg":"trace[1578092844] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.097548799s","start":"2026-04-28T04:56:18.090747Z","end":"2026-04-28T04:56:19.188296Z","steps":["trace[1578092844] 'process raft request' (duration: 31.182611ms)","trace[1578092844] 'compare' (duration: 1.065040436s)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:19.188423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.131365Z","time spent":"1.057021489s","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:19.18848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093380054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:19.188508Z","caller":"traceutil/trace.go:171","msg":"trace[1598477704] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:283; }","duration":"1.093454857s","start":"2026-04-28T04:56:18.095044Z","end":"2026-04-28T04:56:19.188499Z","steps":["trace[1598477704] 'agreement among raft nodes before linearized reading' (duration: 1.093360603s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:19.18854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.095027Z","time spent":"1.093507329s","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-04-28T04:56:19.188619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.090725Z","time spent":"1.097704654s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:19.689255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513118500749159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T04:56:20.023598Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.305214509s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T04:56:20.114787Z","caller":"traceutil/trace.go:171","msg":"trace[110106275] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"1.396470311s","start":"2026-04-28T04:56:18.718288Z","end":"2026-04-28T04:56:20.114758Z","steps":["trace[110106275] 'process raft request' (duration: 1.305853859s)","trace[110106275] 'compare' (duration: 90.461827ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:20.11492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.718272Z","time spent":"1.396589164s","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:56:20.307021Z","caller":"traceutil/trace.go:171","msg":"trace[1100173471] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:291; }","duration":"1.118617113s","start":"2026-04-28T04:56:19.188385Z","end":"2026-04-28T04:56:20.307002Z","steps":["trace[1100173471] 'read index received' (duration: 835.768124ms)","trace[1100173471] 'applied index is now lower than readState.Index' (duration: 282.848159ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:20.307038Z","caller":"traceutil/trace.go:171","msg":"trace[1727977322] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"1.58841585s","start":"2026-04-28T04:56:18.718571Z","end":"2026-04-28T04:56:20.306986Z","steps":["trace[1727977322] 'process raft request' (duration: 1.588181783s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172732229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:20.307213Z","caller":"traceutil/trace.go:171","msg":"trace[912285130] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:284; }","duration":"1.172837574s","start":"2026-04-28T04:56:19.134368Z","end":"2026-04-28T04:56:20.307206Z","steps":["trace[912285130] 'agreement among raft nodes before linearized reading' (duration: 1.1727433s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.718545Z","time spent":"1.588573855s","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:56:20.307186Z","caller":"traceutil/trace.go:171","msg":"trace[374964212] transaction","detail":"{read_only:false; number_of_response:0; response_revision:283; }","duration":"1.588357658s","start":"2026-04-28T04:56:18.718798Z","end":"2026-04-28T04:56:20.307155Z","steps":["trace[374964212] 'process raft request' (duration: 1.588128371s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.013154239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:20.307289Z","caller":"traceutil/trace.go:171","msg":"trace[1109969542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:284; }","duration":"2.013269632s","start":"2026-04-28T04:56:18.294003Z","end":"2026-04-28T04:56:20.307273Z","steps":["trace[1109969542] 'agreement among raft nodes before linearized reading' (duration: 2.013157347s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.293984Z","time spent":"2.013336944s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T04:56:20.307335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11531881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-28T04:56:20.30724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:19.134348Z","time spent":"1.172886546s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T04:56:20.307406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609419715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T04:56:20.307392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.207097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-28T04:56:20.307431Z","caller":"traceutil/trace.go:171","msg":"trace[616910841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:284; }","duration":"1.609478956s","start":"2026-04-28T04:56:18.697943Z","end":"2026-04-28T04:56:20.307422Z","steps":["trace[616910841] 'agreement among raft nodes before linearized reading' (duration: 1.609447905s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590548418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-04-28T04:56:20.307456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.697926Z","time spent":"1.609523107s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T04:56:20.307467Z","caller":"traceutil/trace.go:171","msg":"trace[1972229217] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:284; }","duration":"191.31507ms","start":"2026-04-28T04:56:20.116126Z","end":"2026-04-28T04:56:20.307441Z","steps":["trace[1972229217] 'agreement among raft nodes before linearized reading' (duration: 191.149745ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:20.307461Z","caller":"traceutil/trace.go:171","msg":"trace[179907192] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"1.115432373s","start":"2026-04-28T04:56:19.191952Z","end":"2026-04-28T04:56:20.307384Z","steps":["trace[179907192] 'agreement among raft nodes before linearized reading' (duration: 1.115304689s)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:20.30749Z","caller":"traceutil/trace.go:171","msg":"trace[1624852795] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:284; }","duration":"1.590670662s","start":"2026-04-28T04:56:18.716792Z","end":"2026-04-28T04:56:20.307462Z","steps":["trace[1624852795] 'agreement among raft nodes before linearized reading' (duration: 1.590543689s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.716768Z","time spent":"1.590782375s","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-28T04:56:20.307559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:19.191933Z","time spent":"1.115609538s","remote":"127.0.0.1:52250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-28T04:56:20.307353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:18.718782Z","time spent":"1.588494043s","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:56:20.30709Z","caller":"traceutil/trace.go:171","msg":"trace[1112499657] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"1.114484383s","start":"2026-04-28T04:56:19.19256Z","end":"2026-04-28T04:56:20.307045Z","steps":["trace[1112499657] 'process raft request' (duration: 1.11438373s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:20.307807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:19.192546Z","time spent":"1.115215926s","remote":"127.0.0.1:52468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:56:25.256938Z","caller":"traceutil/trace.go:171","msg":"trace[549547472] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"144.510114ms","start":"2026-04-28T04:56:25.112407Z","end":"2026-04-28T04:56:25.256917Z","steps":["trace[549547472] 'read index received' (duration: 129.965132ms)","trace[549547472] 'applied index is now lower than readState.Index' (duration: 14.543982ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:25.257206Z","caller":"traceutil/trace.go:171","msg":"trace[876426638] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"186.58312ms","start":"2026-04-28T04:56:25.070496Z","end":"2026-04-28T04:56:25.257079Z","steps":["trace[876426638] 'process raft request' (duration: 171.844253ms)","trace[876426638] 'compare' (duration: 14.47483ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:25.257259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.827543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-28T04:56:25.257318Z","caller":"traceutil/trace.go:171","msg":"trace[683087024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:349; }","duration":"144.968977ms","start":"2026-04-28T04:56:25.112334Z","end":"2026-04-28T04:56:25.257303Z","steps":["trace[683087024] 'agreement among raft nodes before linearized reading' (duration: 144.791282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.268071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.368484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-04-28T04:56:26.268165Z","caller":"traceutil/trace.go:171","msg":"trace[270162478] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:395; }","duration":"327.510319ms","start":"2026-04-28T04:56:25.940634Z","end":"2026-04-28T04:56:26.268145Z","steps":["trace[270162478] 'range keys from in-memory index tree' (duration: 327.236051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.268208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:25.940613Z","time spent":"327.585631ms","remote":"127.0.0.1:52304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-28T04:56:26.268069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.189803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-28T04:56:26.268421Z","caller":"traceutil/trace.go:171","msg":"trace[1996186818] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:395; }","duration":"265.576414ms","start":"2026-04-28T04:56:26.002827Z","end":"2026-04-28T04:56:26.268404Z","steps":["trace[1996186818] 'range keys from in-memory index tree' (duration: 265.030948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.573921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.494305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:26.57419Z","caller":"traceutil/trace.go:171","msg":"trace[283550011] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"272.679891ms","start":"2026-04-28T04:56:26.301476Z","end":"2026-04-28T04:56:26.574156Z","steps":["trace[283550011] 'process raft request' (duration: 113.865557ms)","trace[283550011] 'compare' (duration: 158.375692ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:26.574296Z","caller":"traceutil/trace.go:171","msg":"trace[1360306762] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:407; }","duration":"196.50717ms","start":"2026-04-28T04:56:26.377773Z","end":"2026-04-28T04:56:26.57428Z","steps":["trace[1360306762] 'read index received' (duration: 37.582422ms)","trace[1360306762] 'applied index is now lower than readState.Index' (duration: 158.923688ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:26.574425Z","caller":"traceutil/trace.go:171","msg":"trace[536586964] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"262.838194ms","start":"2026-04-28T04:56:26.311538Z","end":"2026-04-28T04:56:26.574376Z","steps":["trace[536586964] 'process raft request' (duration: 262.646347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.574513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.739196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T04:56:26.574545Z","caller":"traceutil/trace.go:171","msg":"trace[6409459] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:397; }","duration":"196.809448ms","start":"2026-04-28T04:56:26.377726Z","end":"2026-04-28T04:56:26.574535Z","steps":["trace[6409459] 'agreement among raft nodes before linearized reading' (duration: 196.722356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.57455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.76953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T04:56:26.574588Z","caller":"traceutil/trace.go:171","msg":"trace[1035123859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"191.853592ms","start":"2026-04-28T04:56:26.382724Z","end":"2026-04-28T04:56:26.574577Z","steps":["trace[1035123859] 'agreement among raft nodes before linearized reading' (duration: 191.75145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.574596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.26644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-28T04:56:26.574645Z","caller":"traceutil/trace.go:171","msg":"trace[858045983] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:397; }","duration":"156.349943ms","start":"2026-04-28T04:56:26.41828Z","end":"2026-04-28T04:56:26.57463Z","steps":["trace[858045983] 'agreement among raft nodes before linearized reading' (duration: 156.25139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:26.574652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.859743ms","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-04-28T04:56:26.574702Z","caller":"traceutil/trace.go:171","msg":"trace[1427442288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"191.934325ms","start":"2026-04-28T04:56:26.382754Z","end":"2026-04-28T04:56:26.574688Z","steps":["trace[1427442288] 'agreement among raft nodes before linearized reading' (duration: 191.836182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.07979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.104762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:27.08012Z","caller":"traceutil/trace.go:171","msg":"trace[1198878472] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:417; }","duration":"438.320592ms","start":"2026-04-28T04:56:26.641744Z","end":"2026-04-28T04:56:27.080065Z","steps":["trace[1198878472] 'read index received' (duration: 1.770941ms)","trace[1198878472] 'applied index is now lower than readState.Index' (duration: 436.548341ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:27.080116Z","caller":"traceutil/trace.go:171","msg":"trace[810300821] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"443.69442ms","start":"2026-04-28T04:56:26.63635Z","end":"2026-04-28T04:56:27.080044Z","steps":["trace[810300821] 'process raft request' (duration: 274.226727ms)","trace[810300821] 'compare' (duration: 168.898206ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:27.080215Z","caller":"traceutil/trace.go:171","msg":"trace[324901872] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"441.285889ms","start":"2026-04-28T04:56:26.638919Z","end":"2026-04-28T04:56:27.080205Z","steps":["trace[324901872] 'process raft request' (duration: 441.057032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.080314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.638903Z","time spent":"441.355161ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:27.080329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.636324Z","time spent":"443.907645ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:27.080462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.694933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-28T04:56:27.080543Z","caller":"traceutil/trace.go:171","msg":"trace[1483167474] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:407; }","duration":"438.789816ms","start":"2026-04-28T04:56:26.641734Z","end":"2026-04-28T04:56:27.080523Z","steps":["trace[1483167474] 'agreement among raft nodes before linearized reading' (duration: 438.652232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.080587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.641701Z","time spent":"438.877628ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-28T04:56:27.116366Z","caller":"traceutil/trace.go:171","msg":"trace[1022117387] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"469.079422ms","start":"2026-04-28T04:56:26.647257Z","end":"2026-04-28T04:56:27.116337Z","steps":["trace[1022117387] 'process raft request' (duration: 468.952688ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.116423Z","caller":"traceutil/trace.go:171","msg":"trace[152563842] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"467.251679ms","start":"2026-04-28T04:56:26.649145Z","end":"2026-04-28T04:56:27.116397Z","steps":["trace[152563842] 'process raft request' (duration: 467.100764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.116532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.647246Z","time spent":"469.201355ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:27.116589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.649137Z","time spent":"467.364812ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:27.116753Z","caller":"traceutil/trace.go:171","msg":"trace[1522600338] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"470.498823ms","start":"2026-04-28T04:56:26.646228Z","end":"2026-04-28T04:56:27.116727Z","steps":["trace[1522600338] 'process raft request' (duration: 469.845823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.116864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.646209Z","time spent":"470.601626ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:27.116853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.357255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:27.116913Z","caller":"traceutil/trace.go:171","msg":"trace[1211169310] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"467.607858ms","start":"2026-04-28T04:56:26.649295Z","end":"2026-04-28T04:56:27.116903Z","steps":["trace[1211169310] 'process raft request' (duration: 467.015591ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.116938Z","caller":"traceutil/trace.go:171","msg":"trace[1216323116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"375.479938ms","start":"2026-04-28T04:56:26.741437Z","end":"2026-04-28T04:56:27.116917Z","steps":["trace[1216323116] 'agreement among raft nodes before linearized reading' (duration: 375.314664ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.116978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.649289Z","time spent":"467.65978ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:27.116983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:26.74142Z","time spent":"375.55479ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T04:56:27.304186Z","caller":"traceutil/trace.go:171","msg":"trace[1465601465] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"216.614778ms","start":"2026-04-28T04:56:27.087539Z","end":"2026-04-28T04:56:27.304154Z","steps":["trace[1465601465] 'process raft request' (duration: 192.837699ms)","trace[1465601465] 'compare' (duration: 23.550033ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:27.304279Z","caller":"traceutil/trace.go:171","msg":"trace[1312461119] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:424; }","duration":"182.536691ms","start":"2026-04-28T04:56:27.121718Z","end":"2026-04-28T04:56:27.304255Z","steps":["trace[1312461119] 'read index received' (duration: 158.605838ms)","trace[1312461119] 'applied index is now lower than readState.Index' (duration: 23.929943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:27.304351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.627313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-04-28T04:56:27.304769Z","caller":"traceutil/trace.go:171","msg":"trace[907639817] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:413; }","duration":"183.088207ms","start":"2026-04-28T04:56:27.121666Z","end":"2026-04-28T04:56:27.304754Z","steps":["trace[907639817] 'agreement among raft nodes before linearized reading' (duration: 182.644645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.304774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.934158ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:27.304826Z","caller":"traceutil/trace.go:171","msg":"trace[1955302503] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:413; }","duration":"147.988269ms","start":"2026-04-28T04:56:27.156831Z","end":"2026-04-28T04:56:27.304819Z","steps":["trace[1955302503] 'agreement among raft nodes before linearized reading' (duration: 147.926227ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.304377Z","caller":"traceutil/trace.go:171","msg":"trace[1710414253] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"215.366292ms","start":"2026-04-28T04:56:27.089005Z","end":"2026-04-28T04:56:27.304372Z","steps":["trace[1710414253] 'process raft request' (duration: 215.089065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.767093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.364297ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:27.767275Z","caller":"traceutil/trace.go:171","msg":"trace[2015643088] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"441.28021ms","start":"2026-04-28T04:56:27.325983Z","end":"2026-04-28T04:56:27.767263Z","steps":["trace[2015643088] 'read index received' (duration: 290.714416ms)","trace[2015643088] 'applied index is now lower than readState.Index' (duration: 150.565104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:27.767351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.435378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-04-28T04:56:27.767373Z","caller":"traceutil/trace.go:171","msg":"trace[98458775] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:416; }","duration":"457.48962ms","start":"2026-04-28T04:56:27.309877Z","end":"2026-04-28T04:56:27.767367Z","steps":["trace[98458775] 'agreement among raft nodes before linearized reading' (duration: 457.424158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.767397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:27.309866Z","time spent":"457.52673ms","remote":"127.0.0.1:52592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3833,"request content":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" "} {"level":"warn","ts":"2026-04-28T04:56:27.767626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.566734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-28T04:56:27.767593Z","caller":"traceutil/trace.go:171","msg":"trace[765965908] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"453.033041ms","start":"2026-04-28T04:56:27.31452Z","end":"2026-04-28T04:56:27.767553Z","steps":["trace[765965908] 'process raft request' (duration: 452.687721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.76764Z","caller":"traceutil/trace.go:171","msg":"trace[490337986] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"440.24396ms","start":"2026-04-28T04:56:27.327373Z","end":"2026-04-28T04:56:27.767617Z","steps":["trace[490337986] 'process raft request' (duration: 440.120506ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.767758Z","caller":"traceutil/trace.go:171","msg":"trace[1186390133] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"456.765248ms","start":"2026-04-28T04:56:27.310984Z","end":"2026-04-28T04:56:27.76775Z","steps":["trace[1186390133] 'process raft request' (duration: 305.69253ms)","trace[1186390133] 'compare' (duration: 150.252844ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:27.767797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:27.310963Z","time spent":"456.814169ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:27.767798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:27.327364Z","time spent":"440.352913ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:27.767651Z","caller":"traceutil/trace.go:171","msg":"trace[1376322296] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:417; }","duration":"455.591684ms","start":"2026-04-28T04:56:27.312053Z","end":"2026-04-28T04:56:27.767645Z","steps":["trace[1376322296] 'agreement among raft nodes before linearized reading' (duration: 455.537993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:27.767859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:27.312024Z","time spent":"455.819721ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4218,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-28T04:56:27.767795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:27.314511Z","time spent":"453.182295ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:27.972245Z","caller":"traceutil/trace.go:171","msg":"trace[529796529] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"199.901374ms","start":"2026-04-28T04:56:27.77232Z","end":"2026-04-28T04:56:27.972222Z","steps":["trace[529796529] 'process raft request' (duration: 165.656161ms)","trace[529796529] 'compare' (duration: 34.054348ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:27.97269Z","caller":"traceutil/trace.go:171","msg":"trace[248162461] transaction","detail":"{read_only:false; number_of_response:1; response_revision:421; }","duration":"194.972361ms","start":"2026-04-28T04:56:27.777707Z","end":"2026-04-28T04:56:27.972679Z","steps":["trace[248162461] 'process raft request' (duration: 194.93604ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.972948Z","caller":"traceutil/trace.go:171","msg":"trace[322061422] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"196.69777ms","start":"2026-04-28T04:56:27.77624Z","end":"2026-04-28T04:56:27.972938Z","steps":["trace[322061422] 'process raft request' (duration: 195.934059ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.973137Z","caller":"traceutil/trace.go:171","msg":"trace[768039396] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"196.214558ms","start":"2026-04-28T04:56:27.776913Z","end":"2026-04-28T04:56:27.973128Z","steps":["trace[768039396] 'process raft request' (duration: 195.680581ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.974974Z","caller":"traceutil/trace.go:171","msg":"trace[1764783985] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"115.080595ms","start":"2026-04-28T04:56:27.859866Z","end":"2026-04-28T04:56:27.974946Z","steps":["trace[1764783985] 'process raft request' (duration: 115.020253ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.975406Z","caller":"traceutil/trace.go:171","msg":"trace[1557113008] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"193.954321ms","start":"2026-04-28T04:56:27.781415Z","end":"2026-04-28T04:56:27.97537Z","steps":["trace[1557113008] 'process raft request' (duration: 193.253721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:27.975741Z","caller":"traceutil/trace.go:171","msg":"trace[1322581693] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"192.661213ms","start":"2026-04-28T04:56:27.783066Z","end":"2026-04-28T04:56:27.975727Z","steps":["trace[1322581693] 'process raft request' (duration: 191.745437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:28.495253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.485946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:28.495436Z","caller":"traceutil/trace.go:171","msg":"trace[1724676575] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"265.763848ms","start":"2026-04-28T04:56:28.229654Z","end":"2026-04-28T04:56:28.495418Z","steps":["trace[1724676575] 'process raft request' (duration: 265.701636ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:28.495456Z","caller":"traceutil/trace.go:171","msg":"trace[1928319219] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"269.649749ms","start":"2026-04-28T04:56:28.225783Z","end":"2026-04-28T04:56:28.495433Z","steps":["trace[1928319219] 'process raft request' (duration: 75.901075ms)","trace[1928319219] 'compare' (duration: 193.387543ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:32.764872Z","caller":"traceutil/trace.go:171","msg":"trace[1624070268] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"122.7911ms","start":"2026-04-28T04:56:32.642057Z","end":"2026-04-28T04:56:32.764848Z","steps":["trace[1624070268] 'read index received' (duration: 122.585554ms)","trace[1624070268] 'applied index is now lower than readState.Index' (duration: 204.436µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:32.764938Z","caller":"traceutil/trace.go:171","msg":"trace[2062915970] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"140.197347ms","start":"2026-04-28T04:56:32.62472Z","end":"2026-04-28T04:56:32.764917Z","steps":["trace[2062915970] 'process raft request' (duration: 139.9515ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:32.765065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.994235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T04:56:32.765144Z","caller":"traceutil/trace.go:171","msg":"trace[350499550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"123.113359ms","start":"2026-04-28T04:56:32.64202Z","end":"2026-04-28T04:56:32.765133Z","steps":["trace[350499550] 'agreement among raft nodes before linearized reading' (duration: 122.955734ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.408102Z","caller":"traceutil/trace.go:171","msg":"trace[2091595961] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"124.633729ms","start":"2026-04-28T04:56:41.283449Z","end":"2026-04-28T04:56:41.408083Z","steps":["trace[2091595961] 'process raft request' (duration: 123.0578ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.419495Z","caller":"traceutil/trace.go:171","msg":"trace[1176422018] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"116.781614ms","start":"2026-04-28T04:56:41.302698Z","end":"2026-04-28T04:56:41.41948Z","steps":["trace[1176422018] 'process raft request' (duration: 116.551728ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.419633Z","caller":"traceutil/trace.go:171","msg":"trace[9398450] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"130.149087ms","start":"2026-04-28T04:56:41.289478Z","end":"2026-04-28T04:56:41.419627Z","steps":["trace[9398450] 'process raft request' (duration: 129.601234ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.41968Z","caller":"traceutil/trace.go:171","msg":"trace[693291234] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"119.800598ms","start":"2026-04-28T04:56:41.299874Z","end":"2026-04-28T04:56:41.419675Z","steps":["trace[693291234] 'process raft request' (duration: 119.292116ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.419719Z","caller":"traceutil/trace.go:171","msg":"trace[2006835886] transaction","detail":"{read_only:false; number_of_response:1; response_revision:509; }","duration":"117.168443ms","start":"2026-04-28T04:56:41.302545Z","end":"2026-04-28T04:56:41.419713Z","steps":["trace[2006835886] 'process raft request' (duration: 116.661111ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.41988Z","caller":"traceutil/trace.go:171","msg":"trace[1729479294] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"110.25328ms","start":"2026-04-28T04:56:41.309606Z","end":"2026-04-28T04:56:41.41986Z","steps":["trace[1729479294] 'process raft request' (duration: 109.685976ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.41996Z","caller":"traceutil/trace.go:171","msg":"trace[738001505] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"102.260231ms","start":"2026-04-28T04:56:41.317688Z","end":"2026-04-28T04:56:41.419948Z","steps":["trace[738001505] 'process raft request' (duration: 101.672727ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.568401Z","caller":"traceutil/trace.go:171","msg":"trace[13577447] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"128.531506ms","start":"2026-04-28T04:56:41.439841Z","end":"2026-04-28T04:56:41.568373Z","steps":["trace[13577447] 'process raft request' (duration: 91.290238ms)","trace[13577447] 'compare' (duration: 37.080264ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:41.56844Z","caller":"traceutil/trace.go:171","msg":"trace[2084323527] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"122.560758ms","start":"2026-04-28T04:56:41.445862Z","end":"2026-04-28T04:56:41.568423Z","steps":["trace[2084323527] 'process raft request' (duration: 122.492256ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.56848Z","caller":"traceutil/trace.go:171","msg":"trace[2065223708] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"126.806994ms","start":"2026-04-28T04:56:41.44164Z","end":"2026-04-28T04:56:41.568447Z","steps":["trace[2065223708] 'process raft request' (duration: 126.67972ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.56877Z","caller":"traceutil/trace.go:171","msg":"trace[470615819] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"119.0661ms","start":"2026-04-28T04:56:41.449693Z","end":"2026-04-28T04:56:41.568759Z","steps":["trace[470615819] 'process raft request' (duration: 118.68829ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.569486Z","caller":"traceutil/trace.go:171","msg":"trace[1237776634] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"105.131993ms","start":"2026-04-28T04:56:41.464325Z","end":"2026-04-28T04:56:41.569457Z","steps":["trace[1237776634] 'process raft request' (duration: 104.620211ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:41.56969Z","caller":"traceutil/trace.go:171","msg":"trace[1655510529] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"113.421339ms","start":"2026-04-28T04:56:41.456201Z","end":"2026-04-28T04:56:41.569622Z","steps":["trace[1655510529] 'process raft request' (duration: 112.459646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:42.12154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.451464ms","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-04-28T04:56:42.121724Z","caller":"traceutil/trace.go:171","msg":"trace[1893899708] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:547; }","duration":"166.653198ms","start":"2026-04-28T04:56:41.955052Z","end":"2026-04-28T04:56:42.121705Z","steps":["trace[1893899708] 'range keys from in-memory index tree' (duration: 166.433053ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:42.121732Z","caller":"traceutil/trace.go:171","msg":"trace[224274606] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"399.646712ms","start":"2026-04-28T04:56:41.722056Z","end":"2026-04-28T04:56:42.121702Z","steps":["trace[224274606] 'process raft request' (duration: 376.582892ms)","trace[224274606] 'compare' (duration: 22.887796ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:42.121904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:41.72204Z","time spent":"399.769236ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:42.13795Z","caller":"traceutil/trace.go:171","msg":"trace[648162220] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"402.266652ms","start":"2026-04-28T04:56:41.735665Z","end":"2026-04-28T04:56:42.137932Z","steps":["trace[648162220] 'process raft request' (duration: 402.005256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:42.138051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:41.735642Z","time spent":"402.350495ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:42.138107Z","caller":"traceutil/trace.go:171","msg":"trace[1353482683] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"375.391142ms","start":"2026-04-28T04:56:41.762702Z","end":"2026-04-28T04:56:42.138093Z","steps":["trace[1353482683] 'process raft request' (duration: 375.198318ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:42.138122Z","caller":"traceutil/trace.go:171","msg":"trace[1140208185] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:567; }","duration":"121.964892ms","start":"2026-04-28T04:56:42.01611Z","end":"2026-04-28T04:56:42.138075Z","steps":["trace[1140208185] 'read index received' (duration: 82.60146ms)","trace[1140208185] 'applied index is now lower than readState.Index' (duration: 39.362752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:42.13816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:41.762688Z","time spent":"375.449203ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:42.138375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.243959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:42.138482Z","caller":"traceutil/trace.go:171","msg":"trace[613358362] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"122.397713ms","start":"2026-04-28T04:56:42.016054Z","end":"2026-04-28T04:56:42.138451Z","steps":["trace[613358362] 'agreement among raft nodes before linearized reading' (duration: 122.120285ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:44.892223Z","caller":"traceutil/trace.go:171","msg":"trace[1250395728] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"146.080524ms","start":"2026-04-28T04:56:44.746101Z","end":"2026-04-28T04:56:44.892181Z","steps":["trace[1250395728] 'process raft request' (duration: 145.574053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:48.998909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.787497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-965ck\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-28T04:56:48.999066Z","caller":"traceutil/trace.go:171","msg":"trace[380660798] range","detail":"{range_begin:/registry/pods/kube-system/cilium-965ck; range_end:; response_count:1; response_revision:622; }","duration":"204.009662ms","start":"2026-04-28T04:56:48.795036Z","end":"2026-04-28T04:56:48.999046Z","steps":["trace[380660798] 'range keys from in-memory index tree' (duration: 203.597013ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:49.547795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.003218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:49.54797Z","caller":"traceutil/trace.go:171","msg":"trace[1647426655] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:643; }","duration":"538.529995ms","start":"2026-04-28T04:56:49.009424Z","end":"2026-04-28T04:56:49.547954Z","steps":["trace[1647426655] 'read index received' (duration: 14.699488ms)","trace[1647426655] 'applied index is now lower than readState.Index' (duration: 523.829617ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:49.548179Z","caller":"traceutil/trace.go:171","msg":"trace[1399938835] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"543.816287ms","start":"2026-04-28T04:56:49.00435Z","end":"2026-04-28T04:56:49.548166Z","steps":["trace[1399938835] 'process raft request' (duration: 371.360068ms)","trace[1399938835] 'compare' (duration: 171.863085ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:49.548243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:49.004333Z","time spent":"543.874649ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:49.548557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.608761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:49.548605Z","caller":"traceutil/trace.go:171","msg":"trace[156541889] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"531.929543ms","start":"2026-04-28T04:56:49.016626Z","end":"2026-04-28T04:56:49.548555Z","steps":["trace[156541889] 'process raft request' (duration: 531.268708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:49.548711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.271902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-28T04:56:49.548661Z","caller":"traceutil/trace.go:171","msg":"trace[1733122996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"533.740854ms","start":"2026-04-28T04:56:49.014894Z","end":"2026-04-28T04:56:49.548635Z","steps":["trace[1733122996] 'agreement among raft nodes before linearized reading' (duration: 533.593151ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:49.548826Z","caller":"traceutil/trace.go:171","msg":"trace[1713812895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"539.424004ms","start":"2026-04-28T04:56:49.009383Z","end":"2026-04-28T04:56:49.548807Z","steps":["trace[1713812895] 'agreement among raft nodes before linearized reading' (duration: 539.199739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:49.548881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:49.00937Z","time spent":"539.494586ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T04:56:49.548916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:49.014885Z","time spent":"533.969219ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T04:56:49.548942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:49.016609Z","time spent":"532.112836ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:50.515827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513118500749827,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T04:56:51.016158Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513118500749827,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T04:56:51.152813Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.515631592s","expected-duration":"1s"} {"level":"info","ts":"2026-04-28T04:56:51.153418Z","caller":"traceutil/trace.go:171","msg":"trace[1358614996] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"1.138102145s","start":"2026-04-28T04:56:50.015271Z","end":"2026-04-28T04:56:51.153373Z","steps":["trace[1358614996] 'read index received' (duration: 1.137645845s)","trace[1358614996] 'applied index is now lower than readState.Index' (duration: 455.32µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:51.153472Z","caller":"traceutil/trace.go:171","msg":"trace[802509105] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.516369718s","start":"2026-04-28T04:56:49.637074Z","end":"2026-04-28T04:56:51.153444Z","steps":["trace[802509105] 'process raft request' (duration: 1.515931529s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:51.153528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138238777s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:56:51.153575Z","caller":"traceutil/trace.go:171","msg":"trace[1370678633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"1.13833176s","start":"2026-04-28T04:56:50.015228Z","end":"2026-04-28T04:56:51.153559Z","steps":["trace[1370678633] 'agreement among raft nodes before linearized reading' (duration: 1.138249888s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:51.153613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.582792ms","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-04-28T04:56:51.153606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:49.637058Z","time spent":"1.516471651s","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:51.153617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:50.015208Z","time spent":"1.138399821s","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T04:56:51.153642Z","caller":"traceutil/trace.go:171","msg":"trace[1055451266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"124.649353ms","start":"2026-04-28T04:56:51.028985Z","end":"2026-04-28T04:56:51.153634Z","steps":["trace[1055451266] 'agreement among raft nodes before linearized reading' (duration: 124.532161ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:51.726184Z","caller":"traceutil/trace.go:171","msg":"trace[630065452] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"163.727365ms","start":"2026-04-28T04:56:51.562442Z","end":"2026-04-28T04:56:51.726169Z","steps":["trace[630065452] 'read index received' (duration: 163.581872ms)","trace[630065452] 'applied index is now lower than readState.Index' (duration: 144.803µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:51.726332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.873079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T04:56:51.726355Z","caller":"traceutil/trace.go:171","msg":"trace[1081145234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"163.95219ms","start":"2026-04-28T04:56:51.562395Z","end":"2026-04-28T04:56:51.726348Z","steps":["trace[1081145234] 'agreement among raft nodes before linearized reading' (duration: 163.839187ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:51.72634Z","caller":"traceutil/trace.go:171","msg":"trace[1525688689] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"169.891524ms","start":"2026-04-28T04:56:51.55641Z","end":"2026-04-28T04:56:51.726301Z","steps":["trace[1525688689] 'process raft request' (duration: 169.652679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:52.052019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.930414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T04:56:52.052131Z","caller":"traceutil/trace.go:171","msg":"trace[1116739001] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"245.280605ms","start":"2026-04-28T04:56:51.806833Z","end":"2026-04-28T04:56:52.052114Z","steps":["trace[1116739001] 'read index received' (duration: 107.193548ms)","trace[1116739001] 'applied index is now lower than readState.Index' (duration: 138.086037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:52.052274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.458019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-965ck\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-04-28T04:56:52.052308Z","caller":"traceutil/trace.go:171","msg":"trace[166210776] range","detail":"{range_begin:/registry/pods/kube-system/cilium-965ck; range_end:; response_count:1; response_revision:630; }","duration":"245.49207ms","start":"2026-04-28T04:56:51.806807Z","end":"2026-04-28T04:56:52.052299Z","steps":["trace[166210776] 'agreement among raft nodes before linearized reading' (duration: 245.352307ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:52.052373Z","caller":"traceutil/trace.go:171","msg":"trace[2016410334] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"321.895645ms","start":"2026-04-28T04:56:51.730415Z","end":"2026-04-28T04:56:52.052311Z","steps":["trace[2016410334] 'process raft request' (duration: 183.576683ms)","trace[2016410334] 'compare' (duration: 137.790081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:52.052548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:51.7304Z","time spent":"322.058938ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:52.07077Z","caller":"traceutil/trace.go:171","msg":"trace[69596049] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"262.706784ms","start":"2026-04-28T04:56:51.808049Z","end":"2026-04-28T04:56:52.070755Z","steps":["trace[69596049] 'process raft request' (duration: 262.623112ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:52.209432Z","caller":"traceutil/trace.go:171","msg":"trace[2127230717] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"142.563356ms","start":"2026-04-28T04:56:52.066841Z","end":"2026-04-28T04:56:52.209405Z","steps":["trace[2127230717] 'process raft request' (duration: 98.443001ms)","trace[2127230717] 'compare' (duration: 43.925381ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:52.993095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.717026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-965ck\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-04-28T04:56:52.99318Z","caller":"traceutil/trace.go:171","msg":"trace[2059884487] range","detail":"{range_begin:/registry/pods/kube-system/cilium-965ck; range_end:; response_count:1; response_revision:634; }","duration":"179.871499ms","start":"2026-04-28T04:56:52.81329Z","end":"2026-04-28T04:56:52.993162Z","steps":["trace[2059884487] 'range keys from in-memory index tree' (duration: 179.523402ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:53.40281Z","caller":"traceutil/trace.go:171","msg":"trace[1206068152] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"228.188261ms","start":"2026-04-28T04:56:53.174603Z","end":"2026-04-28T04:56:53.402791Z","steps":["trace[1206068152] 'read index received' (duration: 228.104729ms)","trace[1206068152] 'applied index is now lower than readState.Index' (duration: 82.632µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:53.402998Z","caller":"traceutil/trace.go:171","msg":"trace[1124557363] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"242.467297ms","start":"2026-04-28T04:56:53.160512Z","end":"2026-04-28T04:56:53.402979Z","steps":["trace[1124557363] 'process raft request' (duration: 242.14376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:53.403139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.523698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T04:56:53.403219Z","caller":"traceutil/trace.go:171","msg":"trace[1173484156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"228.636941ms","start":"2026-04-28T04:56:53.17457Z","end":"2026-04-28T04:56:53.403207Z","steps":["trace[1173484156] 'agreement among raft nodes before linearized reading' (duration: 228.418676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:53.945455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.423589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-965ck\" ","response":"range_response_count:1 size:17427"} {"level":"info","ts":"2026-04-28T04:56:53.945501Z","caller":"traceutil/trace.go:171","msg":"trace[1000189655] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"124.71724ms","start":"2026-04-28T04:56:53.820746Z","end":"2026-04-28T04:56:53.945463Z","steps":["trace[1000189655] 'process raft request' (duration: 123.412131ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:53.945545Z","caller":"traceutil/trace.go:171","msg":"trace[1091685015] range","detail":"{range_begin:/registry/pods/kube-system/cilium-965ck; range_end:; response_count:1; response_revision:638; }","duration":"126.558821ms","start":"2026-04-28T04:56:53.818966Z","end":"2026-04-28T04:56:53.945525Z","steps":["trace[1091685015] 'range keys from in-memory index tree' (duration: 126.239094ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:54.081277Z","caller":"traceutil/trace.go:171","msg":"trace[1964711280] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"122.820522ms","start":"2026-04-28T04:56:53.958433Z","end":"2026-04-28T04:56:54.081254Z","steps":["trace[1964711280] 'process raft request' (duration: 121.855312ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:54.474226Z","caller":"traceutil/trace.go:171","msg":"trace[539454159] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"388.160419ms","start":"2026-04-28T04:56:54.086003Z","end":"2026-04-28T04:56:54.474164Z","steps":["trace[539454159] 'process raft request' (duration: 345.563696ms)","trace[539454159] 'compare' (duration: 42.345438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:54.474885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:54.08598Z","time spent":"388.826264ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:56:56.978992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513118500749946,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T04:56:57.060814Z","caller":"traceutil/trace.go:171","msg":"trace[1545577689] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"581.897105ms","start":"2026-04-28T04:56:56.478833Z","end":"2026-04-28T04:56:57.06073Z","steps":["trace[1545577689] 'read index received' (duration: 581.744172ms)","trace[1545577689] 'applied index is now lower than readState.Index' (duration: 151.903µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:57.061092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.514757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-965ck\" ","response":"range_response_count:1 size:17786"} {"level":"info","ts":"2026-04-28T04:56:57.061131Z","caller":"traceutil/trace.go:171","msg":"trace[1124755882] range","detail":"{range_begin:/registry/pods/kube-system/cilium-965ck; range_end:; response_count:1; response_revision:652; }","duration":"223.584948ms","start":"2026-04-28T04:56:56.837536Z","end":"2026-04-28T04:56:57.061121Z","steps":["trace[1124755882] 'agreement among raft nodes before linearized reading' (duration: 223.404783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:57.061296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.453508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T04:56:57.060797Z","caller":"traceutil/trace.go:171","msg":"trace[102266497] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"834.837597ms","start":"2026-04-28T04:56:56.225933Z","end":"2026-04-28T04:56:57.06077Z","steps":["trace[102266497] 'process raft request' (duration: 834.664383ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:57.061496Z","caller":"traceutil/trace.go:171","msg":"trace[1564093293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"582.706713ms","start":"2026-04-28T04:56:56.478746Z","end":"2026-04-28T04:56:57.061453Z","steps":["trace[1564093293] 'agreement among raft nodes before linearized reading' (duration: 582.076689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:57.061557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:56.22591Z","time spent":"835.573043ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:57.061657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:56.478698Z","time spent":"582.944858ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T04:56:59.766164Z","caller":"traceutil/trace.go:171","msg":"trace[1462860669] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"479.51723ms","start":"2026-04-28T04:56:59.286609Z","end":"2026-04-28T04:56:59.766126Z","steps":["trace[1462860669] 'process raft request' (duration: 460.635324ms)","trace[1462860669] 'compare' (duration: 18.772203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:56:59.766367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:59.286583Z","time spent":"479.694623ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:56:59.823833Z","caller":"traceutil/trace.go:171","msg":"trace[2045932580] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:720; }","duration":"408.735979ms","start":"2026-04-28T04:56:59.415068Z","end":"2026-04-28T04:56:59.823804Z","steps":["trace[2045932580] 'read index received' (duration: 332.181388ms)","trace[2045932580] 'applied index is now lower than readState.Index' (duration: 76.553551ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:56:59.82385Z","caller":"traceutil/trace.go:171","msg":"trace[473686947] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"497.042285ms","start":"2026-04-28T04:56:59.326785Z","end":"2026-04-28T04:56:59.823827Z","steps":["trace[473686947] 'process raft request' (duration: 496.925803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:59.823963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:59.326757Z","time spent":"497.147178ms","remote":"127.0.0.1:52196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:56:59.824038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.963713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-28T04:56:59.824081Z","caller":"traceutil/trace.go:171","msg":"trace[522439841] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:698; }","duration":"409.071415ms","start":"2026-04-28T04:56:59.414997Z","end":"2026-04-28T04:56:59.824069Z","steps":["trace[522439841] 'agreement among raft nodes before linearized reading' (duration: 408.913992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:59.824113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:59.414969Z","time spent":"409.136157ms","remote":"127.0.0.1:52178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-28T04:56:59.86519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.11591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T04:56:59.865302Z","caller":"traceutil/trace.go:171","msg":"trace[1079738150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:699; }","duration":"239.262543ms","start":"2026-04-28T04:56:59.62602Z","end":"2026-04-28T04:56:59.865282Z","steps":["trace[1079738150] 'agreement among raft nodes before linearized reading' (duration: 239.038018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:59.865285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.253176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T04:56:59.865568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.594201ms","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-04-28T04:56:59.865655Z","caller":"traceutil/trace.go:171","msg":"trace[319673911] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:699; }","duration":"250.731254ms","start":"2026-04-28T04:56:59.614909Z","end":"2026-04-28T04:56:59.86564Z","steps":["trace[319673911] 'agreement among raft nodes before linearized reading' (duration: 250.462098ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:56:59.865654Z","caller":"traceutil/trace.go:171","msg":"trace[862245571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"407.589455ms","start":"2026-04-28T04:56:59.457961Z","end":"2026-04-28T04:56:59.865551Z","steps":["trace[862245571] 'agreement among raft nodes before linearized reading' (duration: 407.093624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:59.865702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.202289ms","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-04-28T04:56:59.865817Z","caller":"traceutil/trace.go:171","msg":"trace[1389239999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"365.380342ms","start":"2026-04-28T04:56:59.500417Z","end":"2026-04-28T04:56:59.865798Z","steps":["trace[1389239999] 'agreement among raft nodes before linearized reading' (duration: 365.184878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:56:59.865854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:59.457944Z","time spent":"407.893652ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T04:56:59.865874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:56:59.500397Z","time spent":"365.462554ms","remote":"127.0.0.1:52382","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-04-28T04:57:00.90029Z","caller":"traceutil/trace.go:171","msg":"trace[633681554] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"105.914905ms","start":"2026-04-28T04:57:00.794356Z","end":"2026-04-28T04:57:00.900271Z","steps":["trace[633681554] 'process raft request' (duration: 105.790493ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:02.393616Z","caller":"traceutil/trace.go:171","msg":"trace[1552667152] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"110.510329ms","start":"2026-04-28T04:57:02.283085Z","end":"2026-04-28T04:57:02.393595Z","steps":["trace[1552667152] 'process raft request' (duration: 110.337115ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:02.417492Z","caller":"traceutil/trace.go:171","msg":"trace[1394163721] transaction","detail":"{read_only:false; number_of_response:0; response_revision:736; }","duration":"134.148479ms","start":"2026-04-28T04:57:02.283321Z","end":"2026-04-28T04:57:02.41747Z","steps":["trace[1394163721] 'process raft request' (duration: 134.058538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:02.458144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.405646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:02.45822Z","caller":"traceutil/trace.go:171","msg":"trace[454917437] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:737; }","duration":"109.506038ms","start":"2026-04-28T04:57:02.348697Z","end":"2026-04-28T04:57:02.458203Z","steps":["trace[454917437] 'agreement among raft nodes before linearized reading' (duration: 109.400986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:02.458591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.226463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:02.458732Z","caller":"traceutil/trace.go:171","msg":"trace[1754968034] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:737; }","duration":"110.403496ms","start":"2026-04-28T04:57:02.348313Z","end":"2026-04-28T04:57:02.458716Z","steps":["trace[1754968034] 'agreement among raft nodes before linearized reading' (duration: 110.234773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:02.458904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.837052ms","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-04-28T04:57:02.458935Z","caller":"traceutil/trace.go:171","msg":"trace[8439778] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:737; }","duration":"128.904812ms","start":"2026-04-28T04:57:02.330023Z","end":"2026-04-28T04:57:02.458927Z","steps":["trace[8439778] 'agreement among raft nodes before linearized reading' (duration: 128.856951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:02.458913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.27178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:02.459045Z","caller":"traceutil/trace.go:171","msg":"trace[1314269612] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:737; }","duration":"128.418183ms","start":"2026-04-28T04:57:02.330587Z","end":"2026-04-28T04:57:02.459005Z","steps":["trace[1314269612] 'agreement among raft nodes before linearized reading' (duration: 128.220099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:02.459284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.954777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:02.45931Z","caller":"traceutil/trace.go:171","msg":"trace[1773527749] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:737; }","duration":"110.994368ms","start":"2026-04-28T04:57:02.348309Z","end":"2026-04-28T04:57:02.459303Z","steps":["trace[1773527749] 'agreement among raft nodes before linearized reading' (duration: 110.950247ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:03.11771Z","caller":"traceutil/trace.go:171","msg":"trace[1592964230] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"130.708488ms","start":"2026-04-28T04:57:02.986981Z","end":"2026-04-28T04:57:03.117689Z","steps":["trace[1592964230] 'process raft request' (duration: 130.577946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:21.650196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.071452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T04:57:21.650309Z","caller":"traceutil/trace.go:171","msg":"trace[1360553894] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:825; }","duration":"420.250346ms","start":"2026-04-28T04:57:21.230035Z","end":"2026-04-28T04:57:21.650285Z","steps":["trace[1360553894] 'count revisions from in-memory index tree' (duration: 419.948709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:21.650367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:21.230008Z","time spent":"420.343097ms","remote":"127.0.0.1:52446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:57:21.650556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.507505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T04:57:21.650563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.540347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T04:57:21.650605Z","caller":"traceutil/trace.go:171","msg":"trace[239806734] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:825; }","duration":"711.571136ms","start":"2026-04-28T04:57:20.93902Z","end":"2026-04-28T04:57:21.650591Z","steps":["trace[239806734] 'count revisions from in-memory index tree' (duration: 711.404514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:21.6506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.491764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-28T04:57:21.650644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:20.938962Z","time spent":"711.672288ms","remote":"127.0.0.1:52550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-28T04:57:21.650695Z","caller":"traceutil/trace.go:171","msg":"trace[430049809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:825; }","duration":"407.657736ms","start":"2026-04-28T04:57:21.243016Z","end":"2026-04-28T04:57:21.650673Z","steps":["trace[430049809] 'range keys from in-memory index tree' (duration: 407.2239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:21.650739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:21.24296Z","time spent":"407.769898ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T04:57:21.650712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.507903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:21.650643Z","caller":"traceutil/trace.go:171","msg":"trace[984947893] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:825; }","duration":"618.695829ms","start":"2026-04-28T04:57:21.031927Z","end":"2026-04-28T04:57:21.650622Z","steps":["trace[984947893] 'count revisions from in-memory index tree' (duration: 618.445985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:21.650778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.585703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7mlfr\" ","response":"range_response_count:1 size:4125"} {"level":"info","ts":"2026-04-28T04:57:21.650799Z","caller":"traceutil/trace.go:171","msg":"trace[559961261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"634.601785ms","start":"2026-04-28T04:57:21.016175Z","end":"2026-04-28T04:57:21.650777Z","steps":["trace[559961261] 'range keys from in-memory index tree' (duration: 634.408571ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:21.650826Z","caller":"traceutil/trace.go:171","msg":"trace[668949969] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7mlfr; range_end:; response_count:1; response_revision:825; }","duration":"693.657274ms","start":"2026-04-28T04:57:20.957155Z","end":"2026-04-28T04:57:21.650812Z","steps":["trace[668949969] 'range keys from in-memory index tree' (duration: 693.38839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:21.650846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:21.016116Z","time spent":"634.721587ms","remote":"127.0.0.1:52116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T04:57:21.650871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:20.957145Z","time spent":"693.718004ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":4148,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7mlfr\" "} {"level":"warn","ts":"2026-04-28T04:57:21.650858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:21.031908Z","time spent":"618.911254ms","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-04-28T04:57:21.764526Z","caller":"traceutil/trace.go:171","msg":"trace[1569044938] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"105.953543ms","start":"2026-04-28T04:57:21.658544Z","end":"2026-04-28T04:57:21.764498Z","steps":["trace[1569044938] 'process raft request' (duration: 105.846951ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:21.765275Z","caller":"traceutil/trace.go:171","msg":"trace[305388165] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"109.538596ms","start":"2026-04-28T04:57:21.655703Z","end":"2026-04-28T04:57:21.765242Z","steps":["trace[305388165] 'process raft request' (duration: 108.496397ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:25.141855Z","caller":"traceutil/trace.go:171","msg":"trace[976854312] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:870; }","duration":"125.695728ms","start":"2026-04-28T04:57:25.016137Z","end":"2026-04-28T04:57:25.141833Z","steps":["trace[976854312] 'read index received' (duration: 125.472635ms)","trace[976854312] 'applied index is now lower than readState.Index' (duration: 222.123µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:57:25.141937Z","caller":"traceutil/trace.go:171","msg":"trace[851302286] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"151.823319ms","start":"2026-04-28T04:57:24.990082Z","end":"2026-04-28T04:57:25.141905Z","steps":["trace[851302286] 'process raft request' (duration: 151.604276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:25.142023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.869901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:25.142059Z","caller":"traceutil/trace.go:171","msg":"trace[2127060487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:840; }","duration":"125.963834ms","start":"2026-04-28T04:57:25.016086Z","end":"2026-04-28T04:57:25.14205Z","steps":["trace[2127060487] 'agreement among raft nodes before linearized reading' (duration: 125.836721ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:25.643303Z","caller":"traceutil/trace.go:171","msg":"trace[2094221506] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"353.934389ms","start":"2026-04-28T04:57:25.289349Z","end":"2026-04-28T04:57:25.643284Z","steps":["trace[2094221506] 'process raft request' (duration: 346.364961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:25.643435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:25.289335Z","time spent":"354.05216ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1929,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T04:57:32.312006Z","caller":"traceutil/trace.go:171","msg":"trace[1942084958] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"379.35439ms","start":"2026-04-28T04:57:31.93261Z","end":"2026-04-28T04:57:32.311964Z","steps":["trace[1942084958] 'read index received' (duration: 378.881973ms)","trace[1942084958] 'applied index is now lower than readState.Index' (duration: 470.607µs)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:57:32.312262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.630025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T04:57:32.312277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.798771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:32.312322Z","caller":"traceutil/trace.go:171","msg":"trace[567703872] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:943; }","duration":"379.715596ms","start":"2026-04-28T04:57:31.932597Z","end":"2026-04-28T04:57:32.312313Z","steps":["trace[567703872] 'agreement among raft nodes before linearized reading' (duration: 379.538394ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:32.312333Z","caller":"traceutil/trace.go:171","msg":"trace[2110083169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"295.893132ms","start":"2026-04-28T04:57:32.016425Z","end":"2026-04-28T04:57:32.312318Z","steps":["trace[2110083169] 'agreement among raft nodes before linearized reading' (duration: 295.76423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:32.312354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:31.932551Z","time spent":"379.796408ms","remote":"127.0.0.1:52456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-28T04:57:32.312371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.06272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T04:57:32.312419Z","caller":"traceutil/trace.go:171","msg":"trace[19625842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"268.129581ms","start":"2026-04-28T04:57:32.044275Z","end":"2026-04-28T04:57:32.312404Z","steps":["trace[19625842] 'agreement among raft nodes before linearized reading' (duration: 267.960808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:32.312459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.939021ms","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-04-28T04:57:32.312182Z","caller":"traceutil/trace.go:171","msg":"trace[1547253067] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"495.707477ms","start":"2026-04-28T04:57:31.816386Z","end":"2026-04-28T04:57:32.312093Z","steps":["trace[1547253067] 'process raft request' (duration: 495.261149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:32.312496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.154128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T04:57:32.312538Z","caller":"traceutil/trace.go:171","msg":"trace[1141154602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"262.053363ms","start":"2026-04-28T04:57:32.050465Z","end":"2026-04-28T04:57:32.312519Z","steps":["trace[1141154602] 'agreement among raft nodes before linearized reading' (duration: 261.85733ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:32.312581Z","caller":"traceutil/trace.go:171","msg":"trace[1357817988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"283.314492ms","start":"2026-04-28T04:57:32.029247Z","end":"2026-04-28T04:57:32.312561Z","steps":["trace[1357817988] 'agreement among raft nodes before linearized reading' (duration: 283.044258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:32.312842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.325078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-28T04:57:32.312879Z","caller":"traceutil/trace.go:171","msg":"trace[328085336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"269.39118ms","start":"2026-04-28T04:57:32.043477Z","end":"2026-04-28T04:57:32.312868Z","steps":["trace[328085336] 'agreement among raft nodes before linearized reading' (duration: 269.287208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:32.312912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:31.816353Z","time spent":"496.199495ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:57:56.515622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513118500750807,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T04:57:56.666707Z","caller":"traceutil/trace.go:171","msg":"trace[2030919807] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"651.174039ms","start":"2026-04-28T04:57:56.015511Z","end":"2026-04-28T04:57:56.666685Z","steps":["trace[2030919807] 'read index received' (duration: 651.015267ms)","trace[2030919807] 'applied index is now lower than readState.Index' (duration: 157.582µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:57:56.666899Z","caller":"traceutil/trace.go:171","msg":"trace[658214051] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"895.932952ms","start":"2026-04-28T04:57:55.77093Z","end":"2026-04-28T04:57:56.666863Z","steps":["trace[658214051] 'process raft request' (duration: 895.648659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:56.667062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:55.770904Z","time spent":"896.074334ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T04:57:56.667118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.002805ms","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-04-28T04:57:56.667134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.765854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-28T04:57:56.66714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.989876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T04:57:56.667225Z","caller":"traceutil/trace.go:171","msg":"trace[2008445291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1070; }","duration":"219.904375ms","start":"2026-04-28T04:57:56.447306Z","end":"2026-04-28T04:57:56.66721Z","steps":["trace[2008445291] 'agreement among raft nodes before linearized reading' (duration: 219.649402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:56.667116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.989975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-28T04:57:56.667245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.069412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T04:57:56.667272Z","caller":"traceutil/trace.go:171","msg":"trace[2134335011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"238.137224ms","start":"2026-04-28T04:57:56.429127Z","end":"2026-04-28T04:57:56.667264Z","steps":["trace[2134335011] 'agreement among raft nodes before linearized reading' (duration: 238.070423ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.667224Z","caller":"traceutil/trace.go:171","msg":"trace[1121637783] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"173.129988ms","start":"2026-04-28T04:57:56.494076Z","end":"2026-04-28T04:57:56.667206Z","steps":["trace[1121637783] 'agreement among raft nodes before linearized reading' (duration: 172.864684ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.667367Z","caller":"traceutil/trace.go:171","msg":"trace[994909468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"167.185607ms","start":"2026-04-28T04:57:56.500076Z","end":"2026-04-28T04:57:56.667261Z","steps":["trace[994909468] 'agreement among raft nodes before linearized reading' (duration: 166.958564ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.667164Z","caller":"traceutil/trace.go:171","msg":"trace[854211199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"167.065175ms","start":"2026-04-28T04:57:56.500088Z","end":"2026-04-28T04:57:56.667153Z","steps":["trace[854211199] 'agreement among raft nodes before linearized reading' (duration: 166.963754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:56.667025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.28801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-28T04:57:56.66694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.413472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:56.667514Z","caller":"traceutil/trace.go:171","msg":"trace[1668878799] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1070; }","duration":"651.827739ms","start":"2026-04-28T04:57:56.015675Z","end":"2026-04-28T04:57:56.667503Z","steps":["trace[1668878799] 'agreement among raft nodes before linearized reading' (duration: 651.23033ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.667533Z","caller":"traceutil/trace.go:171","msg":"trace[599622505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"652.063942ms","start":"2026-04-28T04:57:56.015452Z","end":"2026-04-28T04:57:56.667516Z","steps":["trace[599622505] 'agreement among raft nodes before linearized reading' (duration: 651.358582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:56.667547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:56.015655Z","time spent":"651.882169ms","remote":"127.0.0.1:52160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-04-28T04:57:56.667593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:57:56.015431Z","time spent":"652.139703ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T04:57:56.834568Z","caller":"traceutil/trace.go:171","msg":"trace[917098242] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"165.048138ms","start":"2026-04-28T04:57:56.66949Z","end":"2026-04-28T04:57:56.834538Z","steps":["trace[917098242] 'read index received' (duration: 146.878431ms)","trace[917098242] 'applied index is now lower than readState.Index' (duration: 18.167927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:57:56.834726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.218411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:56.834802Z","caller":"traceutil/trace.go:171","msg":"trace[1990924329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"165.320012ms","start":"2026-04-28T04:57:56.669466Z","end":"2026-04-28T04:57:56.834786Z","steps":["trace[1990924329] 'agreement among raft nodes before linearized reading' (duration: 165.20125ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.870688Z","caller":"traceutil/trace.go:171","msg":"trace[654965778] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"198.555214ms","start":"2026-04-28T04:57:56.672107Z","end":"2026-04-28T04:57:56.870663Z","steps":["trace[654965778] 'process raft request' (duration: 198.485014ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.870966Z","caller":"traceutil/trace.go:171","msg":"trace[573077425] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"198.760507ms","start":"2026-04-28T04:57:56.672187Z","end":"2026-04-28T04:57:56.870947Z","steps":["trace[573077425] 'process raft request' (duration: 198.443413ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.870993Z","caller":"traceutil/trace.go:171","msg":"trace[1133276495] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"198.96263ms","start":"2026-04-28T04:57:56.672016Z","end":"2026-04-28T04:57:56.870978Z","steps":["trace[1133276495] 'process raft request' (duration: 198.423863ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.871284Z","caller":"traceutil/trace.go:171","msg":"trace[1751856717] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"198.703026ms","start":"2026-04-28T04:57:56.672566Z","end":"2026-04-28T04:57:56.871269Z","steps":["trace[1751856717] 'process raft request' (duration: 198.305471ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.871547Z","caller":"traceutil/trace.go:171","msg":"trace[781744866] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"198.709887ms","start":"2026-04-28T04:57:56.672825Z","end":"2026-04-28T04:57:56.871535Z","steps":["trace[781744866] 'process raft request' (duration: 198.394663ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:56.871715Z","caller":"traceutil/trace.go:171","msg":"trace[356612634] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"198.534434ms","start":"2026-04-28T04:57:56.673145Z","end":"2026-04-28T04:57:56.871679Z","steps":["trace[356612634] 'process raft request' (duration: 198.313141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:56.871993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.530848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-04-28T04:57:56.872045Z","caller":"traceutil/trace.go:171","msg":"trace[517646843] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1078; }","duration":"199.630819ms","start":"2026-04-28T04:57:56.672402Z","end":"2026-04-28T04:57:56.872032Z","steps":["trace[517646843] 'agreement among raft nodes before linearized reading' (duration: 199.375436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:56.872294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.767442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:56.872334Z","caller":"traceutil/trace.go:171","msg":"trace[1081565148] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1078; }","duration":"194.836994ms","start":"2026-04-28T04:57:56.677487Z","end":"2026-04-28T04:57:56.872324Z","steps":["trace[1081565148] 'agreement among raft nodes before linearized reading' (duration: 194.773613ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:58.763439Z","caller":"traceutil/trace.go:171","msg":"trace[863242701] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"166.952639ms","start":"2026-04-28T04:57:58.596466Z","end":"2026-04-28T04:57:58.763419Z","steps":["trace[863242701] 'process raft request' (duration: 166.789516ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:59.069848Z","caller":"traceutil/trace.go:171","msg":"trace[573243768] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"189.347994ms","start":"2026-04-28T04:57:58.880478Z","end":"2026-04-28T04:57:59.069826Z","steps":["trace[573243768] 'process raft request' (duration: 189.232213ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:59.069927Z","caller":"traceutil/trace.go:171","msg":"trace[1388399465] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"189.438106ms","start":"2026-04-28T04:57:58.880437Z","end":"2026-04-28T04:57:59.069875Z","steps":["trace[1388399465] 'process raft request' (duration: 171.133881ms)","trace[1388399465] 'compare' (duration: 17.990061ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:57:59.070137Z","caller":"traceutil/trace.go:171","msg":"trace[299699315] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"188.789238ms","start":"2026-04-28T04:57:58.881305Z","end":"2026-04-28T04:57:59.070094Z","steps":["trace[299699315] 'process raft request' (duration: 188.457702ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:59.070288Z","caller":"traceutil/trace.go:171","msg":"trace[1861221448] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"188.090648ms","start":"2026-04-28T04:57:58.88215Z","end":"2026-04-28T04:57:59.070241Z","steps":["trace[1861221448] 'process raft request' (duration: 187.947817ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:59.070187Z","caller":"traceutil/trace.go:171","msg":"trace[1409680454] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1129; }","duration":"143.786971ms","start":"2026-04-28T04:57:58.926383Z","end":"2026-04-28T04:57:59.07017Z","steps":["trace[1409680454] 'read index received' (duration: 125.093921ms)","trace[1409680454] 'applied index is now lower than readState.Index' (duration: 18.6921ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:57:59.070308Z","caller":"traceutil/trace.go:171","msg":"trace[1140796357] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"188.94449ms","start":"2026-04-28T04:57:58.881321Z","end":"2026-04-28T04:57:59.070266Z","steps":["trace[1140796357] 'process raft request' (duration: 188.671336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:57:59.070417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.950584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6069caa1-2c32-4c13-830a-5828e55e4c30\" ","response":"range_response_count:1 size:3578"} {"level":"warn","ts":"2026-04-28T04:57:59.070496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.486945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:57:59.070517Z","caller":"traceutil/trace.go:171","msg":"trace[1734449711] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6069caa1-2c32-4c13-830a-5828e55e4c30; range_end:; response_count:1; response_revision:1096; }","duration":"144.147347ms","start":"2026-04-28T04:57:58.926355Z","end":"2026-04-28T04:57:59.070502Z","steps":["trace[1734449711] 'agreement among raft nodes before linearized reading' (duration: 143.867853ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:57:59.070543Z","caller":"traceutil/trace.go:171","msg":"trace[362827821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1096; }","duration":"125.573546ms","start":"2026-04-28T04:57:58.944953Z","end":"2026-04-28T04:57:59.070527Z","steps":["trace[362827821] 'agreement among raft nodes before linearized reading' (duration: 125.477865ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:06.204987Z","caller":"traceutil/trace.go:171","msg":"trace[854888543] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"131.374432ms","start":"2026-04-28T04:58:06.073584Z","end":"2026-04-28T04:58:06.204958Z","steps":["trace[854888543] 'process raft request' (duration: 130.698814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:07.233027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.13048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-28T04:58:07.233071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.438962ms","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-04-28T04:58:07.233097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.928556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-28T04:58:07.233126Z","caller":"traceutil/trace.go:171","msg":"trace[631744998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1134; }","duration":"122.987386ms","start":"2026-04-28T04:58:07.110128Z","end":"2026-04-28T04:58:07.233116Z","steps":["trace[631744998] 'range keys from in-memory index tree' (duration: 122.697354ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.233125Z","caller":"traceutil/trace.go:171","msg":"trace[43376870] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1134; }","duration":"126.532643ms","start":"2026-04-28T04:58:07.106577Z","end":"2026-04-28T04:58:07.23311Z","steps":["trace[43376870] 'range keys from in-memory index tree' (duration: 126.173547ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.233125Z","caller":"traceutil/trace.go:171","msg":"trace[57634336] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1134; }","duration":"127.276782ms","start":"2026-04-28T04:58:07.105827Z","end":"2026-04-28T04:58:07.233103Z","steps":["trace[57634336] 'range keys from in-memory index tree' (duration: 126.944738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:07.233325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.417879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-28T04:58:07.23331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.983053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T04:58:07.233383Z","caller":"traceutil/trace.go:171","msg":"trace[802994854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"129.52958ms","start":"2026-04-28T04:58:07.10384Z","end":"2026-04-28T04:58:07.23337Z","steps":["trace[802994854] 'range keys from in-memory index tree' (duration: 128.947143ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.233398Z","caller":"traceutil/trace.go:171","msg":"trace[1907864064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1134; }","duration":"122.109026ms","start":"2026-04-28T04:58:07.111271Z","end":"2026-04-28T04:58:07.23338Z","steps":["trace[1907864064] 'range keys from in-memory index tree' (duration: 121.482168ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.359744Z","caller":"traceutil/trace.go:171","msg":"trace[270410951] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"122.050474ms","start":"2026-04-28T04:58:07.237669Z","end":"2026-04-28T04:58:07.359719Z","steps":["trace[270410951] 'process raft request' (duration: 121.916593ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.359731Z","caller":"traceutil/trace.go:171","msg":"trace[2062232682] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"122.901116ms","start":"2026-04-28T04:58:07.236804Z","end":"2026-04-28T04:58:07.359705Z","steps":["trace[2062232682] 'process raft request' (duration: 122.666553ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.359924Z","caller":"traceutil/trace.go:171","msg":"trace[1215186434] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"121.757502ms","start":"2026-04-28T04:58:07.238143Z","end":"2026-04-28T04:58:07.359901Z","steps":["trace[1215186434] 'process raft request' (duration: 121.511579ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:07.359958Z","caller":"traceutil/trace.go:171","msg":"trace[1352786074] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"122.135206ms","start":"2026-04-28T04:58:07.237806Z","end":"2026-04-28T04:58:07.359941Z","steps":["trace[1352786074] 'process raft request' (duration: 121.819302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:13.516537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011513118500751024,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T04:58:13.581762Z","caller":"traceutil/trace.go:171","msg":"trace[1097357322] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"565.751949ms","start":"2026-04-28T04:58:13.015989Z","end":"2026-04-28T04:58:13.581741Z","steps":["trace[1097357322] 'read index received' (duration: 565.558257ms)","trace[1097357322] 'applied index is now lower than readState.Index' (duration: 192.812µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:58:13.58185Z","caller":"traceutil/trace.go:171","msg":"trace[2101480066] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"588.028927ms","start":"2026-04-28T04:58:12.993791Z","end":"2026-04-28T04:58:13.58182Z","steps":["trace[2101480066] 'process raft request' (duration: 587.810574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:13.581891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.882531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:58:13.582121Z","caller":"traceutil/trace.go:171","msg":"trace[683847975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1160; }","duration":"566.149413ms","start":"2026-04-28T04:58:13.015946Z","end":"2026-04-28T04:58:13.582095Z","steps":["trace[683847975] 'agreement among raft nodes before linearized reading' (duration: 565.89294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:13.582183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.697966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-28T04:58:13.582208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.015926Z","time spent":"566.263354ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-28T04:58:13.582232Z","caller":"traceutil/trace.go:171","msg":"trace[1257551187] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1160; }","duration":"122.773788ms","start":"2026-04-28T04:58:13.459447Z","end":"2026-04-28T04:58:13.582221Z","steps":["trace[1257551187] 'agreement among raft nodes before linearized reading' (duration: 122.658295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:13.582293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.685436ms","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-04-28T04:58:13.582353Z","caller":"traceutil/trace.go:171","msg":"trace[174809816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1160; }","duration":"138.772197ms","start":"2026-04-28T04:58:13.443566Z","end":"2026-04-28T04:58:13.582339Z","steps":["trace[174809816] 'agreement among raft nodes before linearized reading' (duration: 138.625364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:13.582092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:12.993767Z","time spent":"588.208649ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:58:13.582632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.769538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T04:58:13.582675Z","caller":"traceutil/trace.go:171","msg":"trace[770445330] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1160; }","duration":"245.849449ms","start":"2026-04-28T04:58:13.336816Z","end":"2026-04-28T04:58:13.582665Z","steps":["trace[770445330] 'agreement among raft nodes before linearized reading' (duration: 245.18748ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:14.212371Z","caller":"traceutil/trace.go:171","msg":"trace[690622120] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"625.484659ms","start":"2026-04-28T04:58:13.586854Z","end":"2026-04-28T04:58:14.212338Z","steps":["trace[690622120] 'process raft request' (duration: 550.922655ms)","trace[690622120] 'compare' (duration: 74.27581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:58:14.212535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.586821Z","time spent":"625.633911ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:58:14.451327Z","caller":"traceutil/trace.go:171","msg":"trace[1967010330] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"864.345276ms","start":"2026-04-28T04:58:13.586957Z","end":"2026-04-28T04:58:14.451302Z","steps":["trace[1967010330] 'process raft request' (duration: 864.260485ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:14.451376Z","caller":"traceutil/trace.go:171","msg":"trace[762690447] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"864.409677ms","start":"2026-04-28T04:58:13.58694Z","end":"2026-04-28T04:58:14.45135Z","steps":["trace[762690447] 'process raft request' (duration: 816.389843ms)","trace[762690447] 'compare' (duration: 47.737401ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:58:14.451483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.586918Z","time spent":"864.508848ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:58:14.451484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.586931Z","time spent":"864.472748ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:58:14.451839Z","caller":"traceutil/trace.go:171","msg":"trace[1393781903] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"864.794192ms","start":"2026-04-28T04:58:13.587025Z","end":"2026-04-28T04:58:14.451819Z","steps":["trace[1393781903] 'process raft request' (duration: 864.233815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:14.451945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.587016Z","time spent":"864.873953ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T04:58:14.452817Z","caller":"traceutil/trace.go:171","msg":"trace[2146853277] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1202; }","duration":"695.06704ms","start":"2026-04-28T04:58:13.757734Z","end":"2026-04-28T04:58:14.452801Z","steps":["trace[2146853277] 'read index received' (duration: 380.12469ms)","trace[2146853277] 'applied index is now lower than readState.Index' (duration: 314.94096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T04:58:14.453069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.475622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:58:14.453105Z","caller":"traceutil/trace.go:171","msg":"trace[1541539184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"435.551703ms","start":"2026-04-28T04:58:14.017546Z","end":"2026-04-28T04:58:14.453097Z","steps":["trace[1541539184] 'agreement among raft nodes before linearized reading' (duration: 435.483832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:14.453129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:14.017526Z","time spent":"435.598205ms","remote":"127.0.0.1:52124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T04:58:14.453431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.750416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T04:58:14.45346Z","caller":"traceutil/trace.go:171","msg":"trace[534392977] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1165; }","duration":"410.805288ms","start":"2026-04-28T04:58:14.042649Z","end":"2026-04-28T04:58:14.453454Z","steps":["trace[534392977] 'agreement among raft nodes before linearized reading' (duration: 410.755337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:14.453479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:14.042633Z","time spent":"410.842308ms","remote":"127.0.0.1:52270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-28T04:58:14.453498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.718838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T04:58:14.453569Z","caller":"traceutil/trace.go:171","msg":"trace[1011019818] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1165; }","duration":"695.870191ms","start":"2026-04-28T04:58:13.757685Z","end":"2026-04-28T04:58:14.453555Z","steps":["trace[1011019818] 'agreement among raft nodes before linearized reading' (duration: 695.688309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:14.453613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.75766Z","time spent":"695.944011ms","remote":"127.0.0.1:52304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-28T04:58:14.453644Z","caller":"traceutil/trace.go:171","msg":"trace[2139163332] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"866.16562ms","start":"2026-04-28T04:58:13.587311Z","end":"2026-04-28T04:58:14.453477Z","steps":["trace[2139163332] 'process raft request' (duration: 864.386638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:14.453899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T04:58:13.587289Z","time spent":"866.476823ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T04:58:14.810592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.897085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T04:58:14.810676Z","caller":"traceutil/trace.go:171","msg":"trace[370958818] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1165; }","duration":"183.049346ms","start":"2026-04-28T04:58:14.627604Z","end":"2026-04-28T04:58:14.810654Z","steps":["trace[370958818] 'count revisions from in-memory index tree' (duration: 182.716463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:27.79179Z","caller":"traceutil/trace.go:171","msg":"trace[483355366] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"109.064074ms","start":"2026-04-28T04:58:27.682702Z","end":"2026-04-28T04:58:27.791766Z","steps":["trace[483355366] 'read index received' (duration: 108.874792ms)","trace[483355366] 'applied index is now lower than readState.Index' (duration: 188.332µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:58:27.791881Z","caller":"traceutil/trace.go:171","msg":"trace[194157907] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"144.484027ms","start":"2026-04-28T04:58:27.647364Z","end":"2026-04-28T04:58:27.791848Z","steps":["trace[194157907] 'process raft request' (duration: 144.262005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:27.79202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.290537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-04-28T04:58:27.792122Z","caller":"traceutil/trace.go:171","msg":"trace[1698944593] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1211; }","duration":"109.440219ms","start":"2026-04-28T04:58:27.682661Z","end":"2026-04-28T04:58:27.792102Z","steps":["trace[1698944593] 'agreement among raft nodes before linearized reading' (duration: 109.236666ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T04:58:46.013776Z","caller":"traceutil/trace.go:171","msg":"trace[553466306] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"106.366632ms","start":"2026-04-28T04:58:45.907384Z","end":"2026-04-28T04:58:46.013751Z","steps":["trace[553466306] 'read index received' (duration: 106.130339ms)","trace[553466306] 'applied index is now lower than readState.Index' (duration: 235.293µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T04:58:46.013844Z","caller":"traceutil/trace.go:171","msg":"trace[421752752] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"127.880716ms","start":"2026-04-28T04:58:45.885931Z","end":"2026-04-28T04:58:46.013812Z","steps":["trace[421752752] 'process raft request' (duration: 127.672924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T04:58:46.01404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.630064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:6 size:2185"} {"level":"info","ts":"2026-04-28T04:58:46.014091Z","caller":"traceutil/trace.go:171","msg":"trace[762172201] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:6; response_revision:1270; }","duration":"106.735085ms","start":"2026-04-28T04:58:45.907345Z","end":"2026-04-28T04:58:46.01408Z","steps":["trace[762172201] 'agreement among raft nodes before linearized reading' (duration: 106.507793ms)"],"step_count":1}