{"level":"warn","ts":"2026-06-13T01:10:07.133638Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T01:10:07.133718Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144: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.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-13T01:10:07.13378Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-13T01:10:07.133793Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-06-13T01:10:07.133811Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-13T01:10:07.134795Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-06-13T01:10:07.13489Z","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.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-13T01:10:07.140033Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.813998ms"} {"level":"info","ts":"2026-06-13T01:10:07.148556Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-06-13T01:10:07.148974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-06-13T01:10:07.149012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-06-13T01:10:07.149139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-13T01:10:07.149306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-06-13T01:10:07.149476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-06-13T01:10:07.159698Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-13T01:10:07.161189Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-13T01:10:07.162602Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-13T01:10:07.165311Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-13T01:10:07.16619Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-13T01:10:07.16623Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T01:10:07.166562Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T01:10:07.16659Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-13T01:10:07.172134Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-13T01:10:07.172266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-06-13T01:10:07.172293Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-06-13T01:10:07.172352Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-06-13T01:10:07.172367Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-06-13T01:10:07.172906Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-13T01:10:07.172932Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-13T01:10:08.050271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-06-13T01:10:08.050406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-13T01:10:08.050434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-06-13T01:10:08.050456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-06-13T01:10:08.050475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-06-13T01:10:08.050491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-06-13T01:10:08.050512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-06-13T01:10:08.051619Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:10:08.05292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T01:10:08.052889Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-06-13T01:10:08.05302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-13T01:10:08.053374Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-13T01:10:08.05341Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:10:08.053444Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-13T01:10:08.053555Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:10:08.053613Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-13T01:10:08.054734Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-06-13T01:10:08.056093Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-13T01:10:11.980604Z","caller":"traceutil/trace.go:171","msg":"trace[1670495360] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"233.9899ms","start":"2026-06-13T01:10:11.746586Z","end":"2026-06-13T01:10:11.980576Z","steps":["trace[1670495360] 'read index received' (duration: 233.826906ms)","trace[1670495360] 'applied index is now lower than readState.Index' (duration: 162.074µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:11.980744Z","caller":"traceutil/trace.go:171","msg":"trace[467467346] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"401.839645ms","start":"2026-06-13T01:10:11.578891Z","end":"2026-06-13T01:10:11.98073Z","steps":["trace[467467346] 'process raft request' (duration: 401.537578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:11.981128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.508712ms","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-06-13T01:10:11.981265Z","caller":"traceutil/trace.go:171","msg":"trace[479036916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:239; }","duration":"234.710786ms","start":"2026-06-13T01:10:11.746535Z","end":"2026-06-13T01:10:11.981245Z","steps":["trace[479036916] 'agreement among raft nodes before linearized reading' (duration: 234.465711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:11.981388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:11.578869Z","time spent":"401.909716ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1069,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:10:12.867733Z","caller":"traceutil/trace.go:171","msg":"trace[1043571194] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"882.502816ms","start":"2026-06-13T01:10:11.985196Z","end":"2026-06-13T01:10:12.867699Z","steps":["trace[1043571194] 'process raft request' (duration: 846.016383ms)","trace[1043571194] 'compare' (duration: 36.001812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:10:12.867885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:11.985175Z","time spent":"882.642848ms","remote":"127.0.0.1:52324","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-06-13T01:10:13.373271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202913639444268,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T01:10:13.462509Z","caller":"traceutil/trace.go:171","msg":"trace[1170753235] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"1.476732707s","start":"2026-06-13T01:10:11.98575Z","end":"2026-06-13T01:10:13.462483Z","steps":["trace[1170753235] 'process raft request' (duration: 1.476616585s)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:13.462504Z","caller":"traceutil/trace.go:171","msg":"trace[539871416] linearizableReadLoop","detail":"{readStateIndex:249; appliedIndex:246; }","duration":"589.870106ms","start":"2026-06-13T01:10:12.872579Z","end":"2026-06-13T01:10:13.462449Z","steps":["trace[539871416] 'read index received' (duration: 589.59913ms)","trace[539871416] 'applied index is now lower than readState.Index' (duration: 269.945µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:13.462622Z","caller":"traceutil/trace.go:171","msg":"trace[1383430554] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"1.089666683s","start":"2026-06-13T01:10:12.372944Z","end":"2026-06-13T01:10:13.462611Z","steps":["trace[1383430554] 'process raft request' (duration: 1.089467539s)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:13.462608Z","caller":"traceutil/trace.go:171","msg":"trace[114411316] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"1.477358041s","start":"2026-06-13T01:10:11.985227Z","end":"2026-06-13T01:10:13.462585Z","steps":["trace[114411316] 'process raft request' (duration: 1.476980963s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:13.462619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:11.985738Z","time spent":"1.47682428s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:13.462706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:11.985211Z","time spent":"1.477443923s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:13.462704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:12.372918Z","time spent":"1.089731735s","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:13.462797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.211734ms","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-06-13T01:10:13.462818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.710971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:13.462847Z","caller":"traceutil/trace.go:171","msg":"trace[1162571548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:243; }","duration":"590.301355ms","start":"2026-06-13T01:10:12.872534Z","end":"2026-06-13T01:10:13.462836Z","steps":["trace[1162571548] 'agreement among raft nodes before linearized reading' (duration: 590.195853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:13.462887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:12.872514Z","time spent":"590.364827ms","remote":"127.0.0.1:52324","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-06-13T01:10:13.462889Z","caller":"traceutil/trace.go:171","msg":"trace[1020541869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:243; }","duration":"108.828804ms","start":"2026-06-13T01:10:13.354042Z","end":"2026-06-13T01:10:13.46287Z","steps":["trace[1020541869] 'agreement among raft nodes before linearized reading' (duration: 108.709021ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.52468Z","caller":"traceutil/trace.go:171","msg":"trace[437626392] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"445.613533ms","start":"2026-06-13T01:10:46.079029Z","end":"2026-06-13T01:10:46.524643Z","steps":["trace[437626392] 'process raft request' (duration: 445.412768ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.524758Z","caller":"traceutil/trace.go:171","msg":"trace[1124546915] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"445.632764ms","start":"2026-06-13T01:10:46.079101Z","end":"2026-06-13T01:10:46.524734Z","steps":["trace[1124546915] 'process raft request' (duration: 445.47547ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:46.524835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.079008Z","time spent":"445.757626ms","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:46.52488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.079093Z","time spent":"445.721556ms","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:10:46.524926Z","caller":"traceutil/trace.go:171","msg":"trace[2081990799] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"434.190028ms","start":"2026-06-13T01:10:46.090714Z","end":"2026-06-13T01:10:46.524904Z","steps":["trace[2081990799] 'process raft request' (duration: 434.075006ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.524987Z","caller":"traceutil/trace.go:171","msg":"trace[349561082] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"439.445426ms","start":"2026-06-13T01:10:46.085532Z","end":"2026-06-13T01:10:46.524977Z","steps":["trace[349561082] 'process raft request' (duration: 439.221031ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.524991Z","caller":"traceutil/trace.go:171","msg":"trace[779624238] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"445.433869ms","start":"2026-06-13T01:10:46.079541Z","end":"2026-06-13T01:10:46.524975Z","steps":["trace[779624238] 'process raft request' (duration: 445.132112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:46.52502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.0855Z","time spent":"439.502188ms","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:46.52504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.079514Z","time spent":"445.501521ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:46.525204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.090702Z","time spent":"434.276891ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:10:46.525271Z","caller":"traceutil/trace.go:171","msg":"trace[1436380731] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"434.329752ms","start":"2026-06-13T01:10:46.090881Z","end":"2026-06-13T01:10:46.525211Z","steps":["trace[1436380731] 'process raft request' (duration: 434.130228ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.52534Z","caller":"traceutil/trace.go:171","msg":"trace[928707994] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"434.512426ms","start":"2026-06-13T01:10:46.090817Z","end":"2026-06-13T01:10:46.525329Z","steps":["trace[928707994] 'process raft request' (duration: 434.037895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:46.525373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.090875Z","time spent":"434.450595ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:10:46.52541Z","caller":"traceutil/trace.go:171","msg":"trace[1135750648] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"408.624708ms","start":"2026-06-13T01:10:46.11677Z","end":"2026-06-13T01:10:46.525394Z","steps":["trace[1135750648] 'process raft request' (duration: 408.560447ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:46.525422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.090811Z","time spent":"434.566487ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:10:46.525495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.116755Z","time spent":"408.701381ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:10:46.525518Z","caller":"traceutil/trace.go:171","msg":"trace[1998737148] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"424.547444ms","start":"2026-06-13T01:10:46.100959Z","end":"2026-06-13T01:10:46.525507Z","steps":["trace[1998737148] 'process raft request' (duration: 424.312699ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.525533Z","caller":"traceutil/trace.go:171","msg":"trace[1637241664] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"434.553677ms","start":"2026-06-13T01:10:46.09097Z","end":"2026-06-13T01:10:46.525524Z","steps":["trace[1637241664] 'process raft request' (duration: 434.120628ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:46.525497Z","caller":"traceutil/trace.go:171","msg":"trace[1626741005] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"433.239087ms","start":"2026-06-13T01:10:46.092244Z","end":"2026-06-13T01:10:46.525483Z","steps":["trace[1626741005] 'process raft request' (duration: 432.90813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:46.525568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.100945Z","time spent":"424.593774ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:10:46.525612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.090964Z","time spent":"434.605588ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:10:46.525616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.092231Z","time spent":"433.354989ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:10:46.52915Z","caller":"traceutil/trace.go:171","msg":"trace[1288286667] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"322.561413ms","start":"2026-06-13T01:10:46.206567Z","end":"2026-06-13T01:10:46.529128Z","steps":["trace[1288286667] 'process raft request' (duration: 321.896508ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:46.529369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:46.206532Z","time spent":"322.674115ms","remote":"127.0.0.1:52100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:10:46.535275Z","caller":"traceutil/trace.go:171","msg":"trace[1750298431] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:560; }","duration":"208.276767ms","start":"2026-06-13T01:10:46.326978Z","end":"2026-06-13T01:10:46.535254Z","steps":["trace[1750298431] 'read index received' (duration: 197.358774ms)","trace[1750298431] 'applied index is now lower than readState.Index' (duration: 10.916343ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:10:46.535367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.37611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:46.53542Z","caller":"traceutil/trace.go:171","msg":"trace[505612055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"208.483141ms","start":"2026-06-13T01:10:46.32693Z","end":"2026-06-13T01:10:46.535413Z","steps":["trace[505612055] 'agreement among raft nodes before linearized reading' (duration: 208.3957ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:48.254328Z","caller":"traceutil/trace.go:171","msg":"trace[896884880] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"247.679966ms","start":"2026-06-13T01:10:48.006639Z","end":"2026-06-13T01:10:48.254319Z","steps":["trace[896884880] 'process raft request' (duration: 247.411879ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:48.254178Z","caller":"traceutil/trace.go:171","msg":"trace[1736811374] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"179.61534ms","start":"2026-06-13T01:10:48.074548Z","end":"2026-06-13T01:10:48.254164Z","steps":["trace[1736811374] 'read index received' (duration: 179.496256ms)","trace[1736811374] 'applied index is now lower than readState.Index' (duration: 118.364µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:10:48.254684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.121452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.25471Z","caller":"traceutil/trace.go:171","msg":"trace[1499084940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:606; }","duration":"180.215644ms","start":"2026-06-13T01:10:48.074484Z","end":"2026-06-13T01:10:48.2547Z","steps":["trace[1499084940] 'agreement among raft nodes before linearized reading' (duration: 180.165553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.255384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.143587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:10:48.255409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.473471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.255435Z","caller":"traceutil/trace.go:171","msg":"trace[1600736749] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:606; }","duration":"116.218209ms","start":"2026-06-13T01:10:48.13921Z","end":"2026-06-13T01:10:48.255428Z","steps":["trace[1600736749] 'agreement among raft nodes before linearized reading' (duration: 116.154827ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:48.255452Z","caller":"traceutil/trace.go:171","msg":"trace[1309006077] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:606; }","duration":"115.532642ms","start":"2026-06-13T01:10:48.139909Z","end":"2026-06-13T01:10:48.255442Z","steps":["trace[1309006077] 'agreement among raft nodes before linearized reading' (duration: 115.470911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.255562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.299261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.255591Z","caller":"traceutil/trace.go:171","msg":"trace[263015405] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:606; }","duration":"116.336901ms","start":"2026-06-13T01:10:48.13925Z","end":"2026-06-13T01:10:48.255586Z","steps":["trace[263015405] 'agreement among raft nodes before linearized reading' (duration: 116.29504ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.255658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.009219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.255721Z","caller":"traceutil/trace.go:171","msg":"trace[1330858562] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:606; }","duration":"167.095651ms","start":"2026-06-13T01:10:48.08861Z","end":"2026-06-13T01:10:48.255706Z","steps":["trace[1330858562] 'agreement among raft nodes before linearized reading' (duration: 167.006839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.255792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.206094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.255829Z","caller":"traceutil/trace.go:171","msg":"trace[1145016975] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:606; }","duration":"167.269465ms","start":"2026-06-13T01:10:48.088553Z","end":"2026-06-13T01:10:48.255822Z","steps":["trace[1145016975] 'agreement among raft nodes before linearized reading' (duration: 167.220924ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.255838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.366252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.255868Z","caller":"traceutil/trace.go:171","msg":"trace[275935569] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:606; }","duration":"165.401153ms","start":"2026-06-13T01:10:48.09046Z","end":"2026-06-13T01:10:48.255861Z","steps":["trace[275935569] 'agreement among raft nodes before linearized reading' (duration: 165.356052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.255949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.940249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.255974Z","caller":"traceutil/trace.go:171","msg":"trace[1949118562] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:606; }","duration":"180.98248ms","start":"2026-06-13T01:10:48.074984Z","end":"2026-06-13T01:10:48.255967Z","steps":["trace[1949118562] 'agreement among raft nodes before linearized reading' (duration: 180.948119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:48.256308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.597704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:48.256354Z","caller":"traceutil/trace.go:171","msg":"trace[2146276122] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:606; }","duration":"181.663836ms","start":"2026-06-13T01:10:48.074679Z","end":"2026-06-13T01:10:48.256343Z","steps":["trace[2146276122] 'agreement among raft nodes before linearized reading' (duration: 180.263065ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:49.543391Z","caller":"traceutil/trace.go:171","msg":"trace[515565645] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"332.222278ms","start":"2026-06-13T01:10:49.211148Z","end":"2026-06-13T01:10:49.54337Z","steps":["trace[515565645] 'read index received' (duration: 332.036444ms)","trace[515565645] 'applied index is now lower than readState.Index' (duration: 185.074µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:49.543471Z","caller":"traceutil/trace.go:171","msg":"trace[17085899] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"333.459246ms","start":"2026-06-13T01:10:49.210007Z","end":"2026-06-13T01:10:49.543466Z","steps":["trace[17085899] 'process raft request' (duration: 333.241171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.543545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.209997Z","time spent":"333.491236ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:10:49.543688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.518905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.543768Z","caller":"traceutil/trace.go:171","msg":"trace[1388465263] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:607; }","duration":"332.640228ms","start":"2026-06-13T01:10:49.211113Z","end":"2026-06-13T01:10:49.543754Z","steps":["trace[1388465263] 'agreement among raft nodes before linearized reading' (duration: 332.475374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.54382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.211099Z","time spent":"332.692899ms","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-06-13T01:10:49.543743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.370062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:10:49.544119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.133678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:10:49.54411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.239837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.544156Z","caller":"traceutil/trace.go:171","msg":"trace[1927957665] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:607; }","duration":"315.18844ms","start":"2026-06-13T01:10:49.22896Z","end":"2026-06-13T01:10:49.544148Z","steps":["trace[1927957665] 'agreement among raft nodes before linearized reading' (duration: 315.126568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.54418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.22895Z","time spent":"315.22341ms","remote":"127.0.0.1:56846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-06-13T01:10:49.544181Z","caller":"traceutil/trace.go:171","msg":"trace[1666793273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:607; }","duration":"296.314019ms","start":"2026-06-13T01:10:49.247841Z","end":"2026-06-13T01:10:49.544155Z","steps":["trace[1666793273] 'agreement among raft nodes before linearized reading' (duration: 296.216617ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:49.544128Z","caller":"traceutil/trace.go:171","msg":"trace[235368192] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:607; }","duration":"332.775291ms","start":"2026-06-13T01:10:49.211332Z","end":"2026-06-13T01:10:49.544107Z","steps":["trace[235368192] 'agreement among raft nodes before linearized reading' (duration: 332.347391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.544353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.434575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-13T01:10:49.544579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.954185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.544709Z","caller":"traceutil/trace.go:171","msg":"trace[260729076] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:607; }","duration":"281.14578ms","start":"2026-06-13T01:10:49.263537Z","end":"2026-06-13T01:10:49.544682Z","steps":["trace[260729076] 'agreement among raft nodes before linearized reading' (duration: 280.865814ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:49.544629Z","caller":"traceutil/trace.go:171","msg":"trace[1549791881] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:607; }","duration":"315.68606ms","start":"2026-06-13T01:10:49.228889Z","end":"2026-06-13T01:10:49.544575Z","steps":["trace[1549791881] 'agreement among raft nodes before linearized reading' (duration: 315.440224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.544783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.228873Z","time spent":"315.897765ms","remote":"127.0.0.1:56846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-06-13T01:10:49.544391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.850444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.544847Z","caller":"traceutil/trace.go:171","msg":"trace[1009481818] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:607; }","duration":"316.312083ms","start":"2026-06-13T01:10:49.228518Z","end":"2026-06-13T01:10:49.54483Z","steps":["trace[1009481818] 'agreement among raft nodes before linearized reading' (duration: 315.850073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.544912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.22851Z","time spent":"316.385246ms","remote":"127.0.0.1:56846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-06-13T01:10:49.544388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.110595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.545018Z","caller":"traceutil/trace.go:171","msg":"trace[613941203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:607; }","duration":"296.756768ms","start":"2026-06-13T01:10:49.248253Z","end":"2026-06-13T01:10:49.545009Z","steps":["trace[613941203] 'agreement among raft nodes before linearized reading' (duration: 296.106444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.544394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.730121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.54513Z","caller":"traceutil/trace.go:171","msg":"trace[1357535590] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:607; }","duration":"281.478318ms","start":"2026-06-13T01:10:49.263645Z","end":"2026-06-13T01:10:49.545123Z","steps":["trace[1357535590] 'agreement among raft nodes before linearized reading' (duration: 280.735002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.544423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.539665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.545212Z","caller":"traceutil/trace.go:171","msg":"trace[872270195] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:607; }","duration":"333.329983ms","start":"2026-06-13T01:10:49.211876Z","end":"2026-06-13T01:10:49.545206Z","steps":["trace[872270195] 'agreement among raft nodes before linearized reading' (duration: 332.530015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.545262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.211848Z","time spent":"333.404175ms","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-06-13T01:10:49.544362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.846214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:49.545298Z","caller":"traceutil/trace.go:171","msg":"trace[177790006] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:607; }","duration":"281.803944ms","start":"2026-06-13T01:10:49.263482Z","end":"2026-06-13T01:10:49.545286Z","steps":["trace[177790006] 'agreement among raft nodes before linearized reading' (duration: 280.830963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:49.544641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:49.211324Z","time spent":"333.133068ms","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-06-13T01:10:53.928276Z","caller":"traceutil/trace.go:171","msg":"trace[1446582899] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"223.061228ms","start":"2026-06-13T01:10:53.705181Z","end":"2026-06-13T01:10:53.928242Z","steps":["trace[1446582899] 'read index received' (duration: 220.595143ms)","trace[1446582899] 'applied index is now lower than readState.Index' (duration: 2.465235ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:53.928432Z","caller":"traceutil/trace.go:171","msg":"trace[966115217] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"297.147277ms","start":"2026-06-13T01:10:53.63125Z","end":"2026-06-13T01:10:53.928397Z","steps":["trace[966115217] 'process raft request' (duration: 295.302536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:53.928537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.330274ms","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-06-13T01:10:53.928576Z","caller":"traceutil/trace.go:171","msg":"trace[3855827] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"223.433986ms","start":"2026-06-13T01:10:53.705129Z","end":"2026-06-13T01:10:53.928563Z","steps":["trace[3855827] 'agreement among raft nodes before linearized reading' (duration: 223.270672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:53.928769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.81718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-13T01:10:53.928763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.833465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T01:10:53.929291Z","caller":"traceutil/trace.go:171","msg":"trace[1975181903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"220.439658ms","start":"2026-06-13T01:10:53.708792Z","end":"2026-06-13T01:10:53.929232Z","steps":["trace[1975181903] 'agreement among raft nodes before linearized reading' (duration: 219.724852ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:53.928876Z","caller":"traceutil/trace.go:171","msg":"trace[208909859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"212.988063ms","start":"2026-06-13T01:10:53.715862Z","end":"2026-06-13T01:10:53.92885Z","steps":["trace[208909859] 'agreement among raft nodes before linearized reading' (duration: 212.721327ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:54.056264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.620338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:10:54.056503Z","caller":"traceutil/trace.go:171","msg":"trace[236442008] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"122.404475ms","start":"2026-06-13T01:10:53.934062Z","end":"2026-06-13T01:10:54.056467Z","steps":["trace[236442008] 'process raft request' (duration: 18.246726ms)","trace[236442008] 'compare' (duration: 103.412972ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:54.056575Z","caller":"traceutil/trace.go:171","msg":"trace[494548494] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"122.144961ms","start":"2026-06-13T01:10:53.934371Z","end":"2026-06-13T01:10:54.056516Z","steps":["trace[494548494] 'process raft request' (duration: 122.033778ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:10:54.05698Z","caller":"traceutil/trace.go:171","msg":"trace[1246467842] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"116.217259ms","start":"2026-06-13T01:10:53.940753Z","end":"2026-06-13T01:10:54.05697Z","steps":["trace[1246467842] 'process raft request' (duration: 115.725488ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:54.777118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202913639445117,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-13T01:10:55.139508Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.064527606s","expected-duration":"1s"} {"level":"info","ts":"2026-06-13T01:10:55.146096Z","caller":"traceutil/trace.go:171","msg":"trace[100371289] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"869.80273ms","start":"2026-06-13T01:10:54.276267Z","end":"2026-06-13T01:10:55.14607Z","steps":["trace[100371289] 'read index received' (duration: 863.369866ms)","trace[100371289] 'applied index is now lower than readState.Index' (duration: 6.431724ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:10:55.146135Z","caller":"traceutil/trace.go:171","msg":"trace[1445707327] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.071208865s","start":"2026-06-13T01:10:54.07488Z","end":"2026-06-13T01:10:55.146089Z","steps":["trace[1445707327] 'process raft request' (duration: 1.064829632s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:55.146235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.65478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:10:55.146273Z","caller":"traceutil/trace.go:171","msg":"trace[1378468423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"818.748642ms","start":"2026-06-13T01:10:54.327512Z","end":"2026-06-13T01:10:55.14626Z","steps":["trace[1378468423] 'agreement among raft nodes before linearized reading' (duration: 818.66623ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:55.146275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.000085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-13T01:10:55.146354Z","caller":"traceutil/trace.go:171","msg":"trace[1621751135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:642; }","duration":"870.111967ms","start":"2026-06-13T01:10:54.276205Z","end":"2026-06-13T01:10:55.146317Z","steps":["trace[1621751135] 'agreement among raft nodes before linearized reading' (duration: 869.935323ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:10:55.146387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:54.276179Z","time spent":"870.202509ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-13T01:10:55.146303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:54.327499Z","time spent":"818.796774ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T01:10:55.146392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:10:54.074861Z","time spent":"1.07138835s","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-13T01:11:07.076092Z","caller":"traceutil/trace.go:171","msg":"trace[2142355085] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"128.568733ms","start":"2026-06-13T01:11:06.947498Z","end":"2026-06-13T01:11:07.076066Z","steps":["trace[2142355085] 'process raft request' (duration: 128.398769ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:11:10.389984Z","caller":"traceutil/trace.go:171","msg":"trace[1367031477] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"203.333417ms","start":"2026-06-13T01:11:10.186623Z","end":"2026-06-13T01:11:10.389957Z","steps":["trace[1367031477] 'process raft request' (duration: 203.142903ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:11:11.002377Z","caller":"traceutil/trace.go:171","msg":"trace[1277004124] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"814.536718ms","start":"2026-06-13T01:11:10.187768Z","end":"2026-06-13T01:11:11.002305Z","steps":["trace[1277004124] 'process raft request' (duration: 814.422835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:11.002577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:11:10.187759Z","time spent":"814.717502ms","remote":"127.0.0.1:52324","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-06-13T01:11:11.002294Z","caller":"traceutil/trace.go:171","msg":"trace[142751966] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"814.840464ms","start":"2026-06-13T01:11:10.187432Z","end":"2026-06-13T01:11:11.002272Z","steps":["trace[142751966] 'process raft request' (duration: 814.613659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:11.002814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:11:10.187415Z","time spent":"815.260504ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:11:11.004005Z","caller":"traceutil/trace.go:171","msg":"trace[167755953] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:829; }","duration":"676.257088ms","start":"2026-06-13T01:11:10.327727Z","end":"2026-06-13T01:11:11.003984Z","steps":["trace[167755953] 'read index received' (duration: 62.073822ms)","trace[167755953] 'applied index is now lower than readState.Index' (duration: 614.182176ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:11:11.004032Z","caller":"traceutil/trace.go:171","msg":"trace[1220294833] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"633.085957ms","start":"2026-06-13T01:11:10.370921Z","end":"2026-06-13T01:11:11.004007Z","steps":["trace[1220294833] 'process raft request' (duration: 631.307888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:11.004099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.374692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:11:11.004137Z","caller":"traceutil/trace.go:171","msg":"trace[1856232368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"676.466304ms","start":"2026-06-13T01:11:10.327661Z","end":"2026-06-13T01:11:11.004127Z","steps":["trace[1856232368] 'agreement among raft nodes before linearized reading' (duration: 676.398271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:11.004148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:11:10.370901Z","time spent":"633.18436ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:11:11.004171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:11:10.327615Z","time spent":"676.549016ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-13T01:11:40.976282Z","caller":"traceutil/trace.go:171","msg":"trace[6466030] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"395.932516ms","start":"2026-06-13T01:11:40.580306Z","end":"2026-06-13T01:11:40.976239Z","steps":["trace[6466030] 'process raft request' (duration: 395.737102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:11:40.976482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:11:40.580285Z","time spent":"396.089499ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:11:43.308875Z","caller":"traceutil/trace.go:171","msg":"trace[669366377] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"106.128873ms","start":"2026-06-13T01:11:43.202726Z","end":"2026-06-13T01:11:43.308855Z","steps":["trace[669366377] 'process raft request' (duration: 34.632852ms)","trace[669366377] 'compare' (duration: 71.406289ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:12:07.536553Z","caller":"traceutil/trace.go:171","msg":"trace[436542676] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"115.143313ms","start":"2026-06-13T01:12:07.421389Z","end":"2026-06-13T01:12:07.536533Z","steps":["trace[436542676] 'read index received' (duration: 115.01418ms)","trace[436542676] 'applied index is now lower than readState.Index' (duration: 128.273µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:12:07.536661Z","caller":"traceutil/trace.go:171","msg":"trace[750866523] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"180.549141ms","start":"2026-06-13T01:12:07.356098Z","end":"2026-06-13T01:12:07.536647Z","steps":["trace[750866523] 'process raft request' (duration: 180.315716ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:07.537088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.626945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T01:12:07.537187Z","caller":"traceutil/trace.go:171","msg":"trace[631220415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"115.813458ms","start":"2026-06-13T01:12:07.42135Z","end":"2026-06-13T01:12:07.537164Z","steps":["trace[631220415] 'agreement among raft nodes before linearized reading' (duration: 115.4195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:08.46897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.808454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-13T01:12:08.469064Z","caller":"traceutil/trace.go:171","msg":"trace[1403799337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1089; }","duration":"828.971038ms","start":"2026-06-13T01:12:07.640071Z","end":"2026-06-13T01:12:08.469042Z","steps":["trace[1403799337] 'range keys from in-memory index tree' (duration: 828.607459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:08.469107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:07.640045Z","time spent":"829.053919ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-13T01:12:08.469235Z","caller":"traceutil/trace.go:171","msg":"trace[1861527251] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"140.99665ms","start":"2026-06-13T01:12:08.328195Z","end":"2026-06-13T01:12:08.469191Z","steps":["trace[1861527251] 'read index received' (duration: 42.408356ms)","trace[1861527251] 'applied index is now lower than readState.Index' (duration: 98.586914ms)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:12:08.469308Z","caller":"traceutil/trace.go:171","msg":"trace[1913755543] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"606.117866ms","start":"2026-06-13T01:12:07.863138Z","end":"2026-06-13T01:12:08.469256Z","steps":["trace[1913755543] 'process raft request' (duration: 507.47664ms)","trace[1913755543] 'compare' (duration: 97.612002ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:12:08.469411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.201275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:12:08.46945Z","caller":"traceutil/trace.go:171","msg":"trace[70910593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"141.284967ms","start":"2026-06-13T01:12:08.328153Z","end":"2026-06-13T01:12:08.469438Z","steps":["trace[70910593] 'agreement among raft nodes before linearized reading' (duration: 141.171964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:08.469521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:07.863114Z","time spent":"606.28794ms","remote":"127.0.0.1:52324","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-06-13T01:12:10.157665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.299322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T01:12:10.157749Z","caller":"traceutil/trace.go:171","msg":"trace[6650030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"616.439885ms","start":"2026-06-13T01:12:09.541289Z","end":"2026-06-13T01:12:10.157728Z","steps":["trace[6650030] 'range keys from in-memory index tree' (duration: 616.084697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:10.157785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:09.541244Z","time spent":"616.532287ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-13T01:12:10.158032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.673074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:12:10.158073Z","caller":"traceutil/trace.go:171","msg":"trace[2112922282] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1092; }","duration":"286.716185ms","start":"2026-06-13T01:12:09.871345Z","end":"2026-06-13T01:12:10.158061Z","steps":["trace[2112922282] 'count revisions from in-memory index tree' (duration: 286.580721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:10.158255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.845228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T01:12:10.158317Z","caller":"traceutil/trace.go:171","msg":"trace[1142365328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1092; }","duration":"600.93576ms","start":"2026-06-13T01:12:09.557365Z","end":"2026-06-13T01:12:10.158301Z","steps":["trace[1142365328] 'range keys from in-memory index tree' (duration: 600.722346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:10.15838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:09.557354Z","time spent":"601.018522ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-13T01:12:10.158962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.406179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T01:12:10.159077Z","caller":"traceutil/trace.go:171","msg":"trace[1915872749] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1092; }","duration":"605.764097ms","start":"2026-06-13T01:12:09.553288Z","end":"2026-06-13T01:12:10.159052Z","steps":["trace[1915872749] 'range keys from in-memory index tree' (duration: 605.225756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:10.159142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:09.553277Z","time spent":"605.853399ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-13T01:12:10.450946Z","caller":"traceutil/trace.go:171","msg":"trace[1850228529] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"288.410832ms","start":"2026-06-13T01:12:10.162502Z","end":"2026-06-13T01:12:10.450913Z","steps":["trace[1850228529] 'process raft request' (duration: 288.220188ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:10.451949Z","caller":"traceutil/trace.go:171","msg":"trace[1944932194] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1136; }","duration":"127.0817ms","start":"2026-06-13T01:12:10.324843Z","end":"2026-06-13T01:12:10.451924Z","steps":["trace[1944932194] 'read index received' (duration: 125.856333ms)","trace[1944932194] 'applied index is now lower than readState.Index' (duration: 1.223947ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:12:10.452092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.242764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:12:10.452123Z","caller":"traceutil/trace.go:171","msg":"trace[9752869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"127.386727ms","start":"2026-06-13T01:12:10.32473Z","end":"2026-06-13T01:12:10.452116Z","steps":["trace[9752869] 'agreement among raft nodes before linearized reading' (duration: 127.284065ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:10.452458Z","caller":"traceutil/trace.go:171","msg":"trace[637863954] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"289.489387ms","start":"2026-06-13T01:12:10.162939Z","end":"2026-06-13T01:12:10.452429Z","steps":["trace[637863954] 'process raft request' (duration: 288.823031ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:10.452409Z","caller":"traceutil/trace.go:171","msg":"trace[1931210449] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"289.064557ms","start":"2026-06-13T01:12:10.16331Z","end":"2026-06-13T01:12:10.452375Z","steps":["trace[1931210449] 'process raft request' (duration: 288.572795ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:25.706468Z","caller":"traceutil/trace.go:171","msg":"trace[1123657725] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"381.43509ms","start":"2026-06-13T01:12:25.324999Z","end":"2026-06-13T01:12:25.706434Z","steps":["trace[1123657725] 'read index received' (duration: 381.108273ms)","trace[1123657725] 'applied index is now lower than readState.Index' (duration: 325.457µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:12:25.706613Z","caller":"traceutil/trace.go:171","msg":"trace[1561310958] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"396.315973ms","start":"2026-06-13T01:12:25.310259Z","end":"2026-06-13T01:12:25.706575Z","steps":["trace[1561310958] 'process raft request' (duration: 395.926864ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:25.706642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.612354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:12:25.706706Z","caller":"traceutil/trace.go:171","msg":"trace[1172230578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1145; }","duration":"381.732596ms","start":"2026-06-13T01:12:25.32496Z","end":"2026-06-13T01:12:25.706693Z","steps":["trace[1172230578] 'agreement among raft nodes before linearized reading' (duration: 381.602054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:25.70675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:25.324931Z","time spent":"381.809998ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T01:12:25.706818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:25.310222Z","time spent":"396.480566ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:12:27.841678Z","caller":"traceutil/trace.go:171","msg":"trace[1979874690] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"124.251442ms","start":"2026-06-13T01:12:27.717385Z","end":"2026-06-13T01:12:27.841637Z","steps":["trace[1979874690] 'process raft request' (duration: 123.995126ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:28.377582Z","caller":"traceutil/trace.go:171","msg":"trace[1510398192] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"289.612741ms","start":"2026-06-13T01:12:28.087936Z","end":"2026-06-13T01:12:28.377549Z","steps":["trace[1510398192] 'process raft request' (duration: 289.373245ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:12:43.104343Z","caller":"traceutil/trace.go:171","msg":"trace[2068434725] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"474.618948ms","start":"2026-06-13T01:12:42.629695Z","end":"2026-06-13T01:12:43.104314Z","steps":["trace[2068434725] 'read index received' (duration: 474.345422ms)","trace[2068434725] 'applied index is now lower than readState.Index' (duration: 272.516µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:12:43.104542Z","caller":"traceutil/trace.go:171","msg":"trace[904632770] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"478.658118ms","start":"2026-06-13T01:12:42.625873Z","end":"2026-06-13T01:12:43.104531Z","steps":["trace[904632770] 'process raft request' (duration: 478.274289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:43.104703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:42.625843Z","time spent":"478.72581ms","remote":"127.0.0.1:52324","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-06-13T01:12:43.104832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.199215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T01:12:43.104935Z","caller":"traceutil/trace.go:171","msg":"trace[1082232641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"433.333478ms","start":"2026-06-13T01:12:42.671578Z","end":"2026-06-13T01:12:43.104911Z","steps":["trace[1082232641] 'agreement among raft nodes before linearized reading' (duration: 433.105192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:43.10498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:42.671565Z","time spent":"433.406589ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-13T01:12:43.105125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.628194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-13T01:12:43.105158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.596018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T01:12:43.10522Z","caller":"traceutil/trace.go:171","msg":"trace[1142066918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"346.775856ms","start":"2026-06-13T01:12:42.758415Z","end":"2026-06-13T01:12:43.105191Z","steps":["trace[1142066918] 'agreement among raft nodes before linearized reading' (duration: 346.47931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:43.105259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:42.758381Z","time spent":"346.873539ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-13T01:12:43.105277Z","caller":"traceutil/trace.go:171","msg":"trace[948798152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"431.67612ms","start":"2026-06-13T01:12:42.673517Z","end":"2026-06-13T01:12:43.105193Z","steps":["trace[948798152] 'agreement among raft nodes before linearized reading' (duration: 431.572898ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:43.105295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.606111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-13T01:12:43.105314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:42.673493Z","time spent":"431.808684ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-13T01:12:43.105333Z","caller":"traceutil/trace.go:171","msg":"trace[241872901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1251; }","duration":"475.685853ms","start":"2026-06-13T01:12:42.629634Z","end":"2026-06-13T01:12:43.10532Z","steps":["trace[241872901] 'agreement among raft nodes before linearized reading' (duration: 475.55339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:43.105365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:42.629598Z","time spent":"475.762874ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-13T01:12:45.744243Z","caller":"traceutil/trace.go:171","msg":"trace[683500643] linearizableReadLoop","detail":"{readStateIndex:1310; appliedIndex:1309; }","duration":"426.101666ms","start":"2026-06-13T01:12:45.318115Z","end":"2026-06-13T01:12:45.744216Z","steps":["trace[683500643] 'read index received' (duration: 425.84323ms)","trace[683500643] 'applied index is now lower than readState.Index' (duration: 257.446µs)"],"step_count":2} {"level":"info","ts":"2026-06-13T01:12:45.744325Z","caller":"traceutil/trace.go:171","msg":"trace[2024824946] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"634.662799ms","start":"2026-06-13T01:12:45.109611Z","end":"2026-06-13T01:12:45.744274Z","steps":["trace[2024824946] 'process raft request' (duration: 634.438234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:45.744522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.381492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-13T01:12:45.744552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:45.109562Z","time spent":"634.865933ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:12:45.744564Z","caller":"traceutil/trace.go:171","msg":"trace[672213645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"426.444713ms","start":"2026-06-13T01:12:45.318105Z","end":"2026-06-13T01:12:45.74455Z","steps":["trace[672213645] 'agreement among raft nodes before linearized reading' (duration: 426.267539ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:45.744608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:45.318033Z","time spent":"426.567326ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-13T01:12:45.744646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.491815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T01:12:45.744724Z","caller":"traceutil/trace.go:171","msg":"trace[227369552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1257; }","duration":"426.589567ms","start":"2026-06-13T01:12:45.318113Z","end":"2026-06-13T01:12:45.744703Z","steps":["trace[227369552] 'agreement among raft nodes before linearized reading' (duration: 426.376172ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:45.744779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:45.318104Z","time spent":"426.665658ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-13T01:12:45.744864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.681099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T01:12:45.744905Z","caller":"traceutil/trace.go:171","msg":"trace[1849351225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1257; }","duration":"426.752861ms","start":"2026-06-13T01:12:45.31814Z","end":"2026-06-13T01:12:45.744893Z","steps":["trace[1849351225] 'agreement among raft nodes before linearized reading' (duration: 426.639128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:45.744938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.803592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-13T01:12:45.74495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:45.318123Z","time spent":"426.819422ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-13T01:12:45.744965Z","caller":"traceutil/trace.go:171","msg":"trace[1910788908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1257; }","duration":"426.861423ms","start":"2026-06-13T01:12:45.318095Z","end":"2026-06-13T01:12:45.744956Z","steps":["trace[1910788908] 'agreement among raft nodes before linearized reading' (duration: 426.780031ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:45.744993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:45.318074Z","time spent":"426.913744ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-13T01:12:45.745261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.389889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:12:45.745285Z","caller":"traceutil/trace.go:171","msg":"trace[622433666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"420.45339ms","start":"2026-06-13T01:12:45.324823Z","end":"2026-06-13T01:12:45.745277Z","steps":["trace[622433666] 'agreement among raft nodes before linearized reading' (duration: 420.417029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:12:45.745318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:12:45.324799Z","time spent":"420.513912ms","remote":"127.0.0.1:52052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-13T01:13:02.227218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.223771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-13T01:13:02.227352Z","caller":"traceutil/trace.go:171","msg":"trace[1222668340] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"865.024969ms","start":"2026-06-13T01:13:01.362303Z","end":"2026-06-13T01:13:02.227328Z","steps":["trace[1222668340] 'process raft request' (duration: 446.569652ms)","trace[1222668340] 'compare' (duration: 417.791031ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:13:02.227405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:01.362276Z","time spent":"865.10624ms","remote":"127.0.0.1:52078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:13:02.228401Z","caller":"traceutil/trace.go:171","msg":"trace[1073140176] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"412.746248ms","start":"2026-06-13T01:13:01.81562Z","end":"2026-06-13T01:13:02.228366Z","steps":["trace[1073140176] 'process raft request' (duration: 412.469142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:02.228811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:01.815582Z","time spent":"412.924042ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:13:02.229176Z","caller":"traceutil/trace.go:171","msg":"trace[821171419] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1367; }","duration":"386.954503ms","start":"2026-06-13T01:13:01.842208Z","end":"2026-06-13T01:13:02.229163Z","steps":["trace[821171419] 'read index received' (duration: 386.948013ms)","trace[821171419] 'applied index is now lower than readState.Index' (duration: 5.01µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:13:02.229353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.152597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T01:13:02.22937Z","caller":"traceutil/trace.go:171","msg":"trace[714933036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1310; }","duration":"387.213648ms","start":"2026-06-13T01:13:01.84215Z","end":"2026-06-13T01:13:02.229363Z","steps":["trace[714933036] 'agreement among raft nodes before linearized reading' (duration: 387.074695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:02.229387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:01.842121Z","time spent":"387.26248ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-13T01:13:02.229491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.939586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-13T01:13:02.229504Z","caller":"traceutil/trace.go:171","msg":"trace[2075548672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"194.000097ms","start":"2026-06-13T01:13:02.0355Z","end":"2026-06-13T01:13:02.2295Z","steps":["trace[2075548672] 'agreement among raft nodes before linearized reading' (duration: 193.939666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:02.229579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.885478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-13T01:13:02.229571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.417951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T01:13:02.229591Z","caller":"traceutil/trace.go:171","msg":"trace[852440672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"376.929798ms","start":"2026-06-13T01:13:01.852657Z","end":"2026-06-13T01:13:02.229587Z","steps":["trace[852440672] 'agreement among raft nodes before linearized reading' (duration: 376.886657ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:02.229562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.701356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-13T01:13:02.229615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:01.85264Z","time spent":"376.97141ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-13T01:13:02.22962Z","caller":"traceutil/trace.go:171","msg":"trace[1013287089] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"373.528293ms","start":"2026-06-13T01:13:01.856081Z","end":"2026-06-13T01:13:02.229609Z","steps":["trace[1013287089] 'agreement among raft nodes before linearized reading' (duration: 373.321758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:02.229652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:01.856052Z","time spent":"373.592945ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-13T01:13:02.229656Z","caller":"traceutil/trace.go:171","msg":"trace[384541196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1310; }","duration":"377.841098ms","start":"2026-06-13T01:13:01.851793Z","end":"2026-06-13T01:13:02.229634Z","steps":["trace[384541196] 'agreement among raft nodes before linearized reading' (duration: 377.506541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:02.229733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:01.851779Z","time spent":"377.943662ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-13T01:13:02.477581Z","caller":"traceutil/trace.go:171","msg":"trace[824702158] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"238.581963ms","start":"2026-06-13T01:13:02.238964Z","end":"2026-06-13T01:13:02.477546Z","steps":["trace[824702158] 'process raft request' (duration: 238.509951ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:02.47822Z","caller":"traceutil/trace.go:171","msg":"trace[1777160719] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"241.398665ms","start":"2026-06-13T01:13:02.236803Z","end":"2026-06-13T01:13:02.478202Z","steps":["trace[1777160719] 'process raft request' (duration: 240.328281ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:02.478471Z","caller":"traceutil/trace.go:171","msg":"trace[612906588] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"240.836312ms","start":"2026-06-13T01:13:02.237619Z","end":"2026-06-13T01:13:02.478455Z","steps":["trace[612906588] 'process raft request' (duration: 239.732058ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:02.478616Z","caller":"traceutil/trace.go:171","msg":"trace[440209819] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"240.573847ms","start":"2026-06-13T01:13:02.238008Z","end":"2026-06-13T01:13:02.478582Z","steps":["trace[440209819] 'process raft request' (duration: 239.387391ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:02.649584Z","caller":"traceutil/trace.go:171","msg":"trace[1199798144] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"101.199288ms","start":"2026-06-13T01:13:02.54835Z","end":"2026-06-13T01:13:02.649549Z","steps":["trace[1199798144] 'process raft request' (duration: 89.169529ms)","trace[1199798144] 'compare' (duration: 11.62789ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:13:03.430215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.475396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-06-13T01:13:03.430297Z","caller":"traceutil/trace.go:171","msg":"trace[832399496] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1318; }","duration":"323.612878ms","start":"2026-06-13T01:13:03.106664Z","end":"2026-06-13T01:13:03.430277Z","steps":["trace[832399496] 'range keys from in-memory index tree' (duration: 323.289982ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:03.430353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:03.106639Z","time spent":"323.68721ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-13T01:13:04.033109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440202913639446646,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-13T01:13:04.25789Z","caller":"traceutil/trace.go:171","msg":"trace[862125195] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"754.853831ms","start":"2026-06-13T01:13:03.50301Z","end":"2026-06-13T01:13:04.257864Z","steps":["trace[862125195] 'process raft request' (duration: 754.668227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:04.258081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:03.502979Z","time spent":"754.997274ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:13:04.25814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.938866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-13T01:13:04.25826Z","caller":"traceutil/trace.go:171","msg":"trace[1978358265] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1319; }","duration":"726.148441ms","start":"2026-06-13T01:13:03.532087Z","end":"2026-06-13T01:13:04.258236Z","steps":["trace[1978358265] 'agreement among raft nodes before linearized reading' (duration: 725.855584ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:04.257872Z","caller":"traceutil/trace.go:171","msg":"trace[2022152521] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"725.68023ms","start":"2026-06-13T01:13:03.532164Z","end":"2026-06-13T01:13:04.257844Z","steps":["trace[2022152521] 'read index received' (duration: 725.521117ms)","trace[2022152521] 'applied index is now lower than readState.Index' (duration: 157.633µs)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:13:04.258326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:03.532054Z","time spent":"726.261473ms","remote":"127.0.0.1:52460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" "} {"level":"info","ts":"2026-06-13T01:13:04.605955Z","caller":"traceutil/trace.go:171","msg":"trace[2006579553] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"113.244697ms","start":"2026-06-13T01:13:04.492679Z","end":"2026-06-13T01:13:04.605924Z","steps":["trace[2006579553] 'process raft request' (duration: 113.204976ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:04.605982Z","caller":"traceutil/trace.go:171","msg":"trace[1199142122] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"114.117896ms","start":"2026-06-13T01:13:04.491832Z","end":"2026-06-13T01:13:04.60595Z","steps":["trace[1199142122] 'process raft request' (duration: 113.8562ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:04.606154Z","caller":"traceutil/trace.go:171","msg":"trace[956755537] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"113.701427ms","start":"2026-06-13T01:13:04.492441Z","end":"2026-06-13T01:13:04.606143Z","steps":["trace[956755537] 'process raft request' (duration: 113.40453ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:05.22641Z","caller":"traceutil/trace.go:171","msg":"trace[1551505598] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"385.613622ms","start":"2026-06-13T01:13:04.84077Z","end":"2026-06-13T01:13:05.226383Z","steps":["trace[1551505598] 'process raft request' (duration: 385.450638ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:05.226576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:04.840733Z","time spent":"385.775505ms","remote":"127.0.0.1:52134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-13T01:13:18.87756Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080726871s","expected-duration":"1s"} {"level":"info","ts":"2026-06-13T01:13:18.879097Z","caller":"traceutil/trace.go:171","msg":"trace[284035314] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"1.082358128s","start":"2026-06-13T01:13:17.79671Z","end":"2026-06-13T01:13:18.879068Z","steps":["trace[284035314] 'process raft request' (duration: 1.081547039s)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.879263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:17.796684Z","time spent":"1.082493691s","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:13:18.980366Z","caller":"traceutil/trace.go:171","msg":"trace[1434494050] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"642.950244ms","start":"2026-06-13T01:13:18.337363Z","end":"2026-06-13T01:13:18.980313Z","steps":["trace[1434494050] 'process raft request' (duration: 642.669988ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:18.980543Z","caller":"traceutil/trace.go:171","msg":"trace[1724056897] linearizableReadLoop","detail":"{readStateIndex:1488; appliedIndex:1486; }","duration":"516.36056ms","start":"2026-06-13T01:13:18.464146Z","end":"2026-06-13T01:13:18.980506Z","steps":["trace[1724056897] 'read index received' (duration: 414.092937ms)","trace[1724056897] 'applied index is now lower than readState.Index' (duration: 102.265563ms)"],"step_count":2} {"level":"warn","ts":"2026-06-13T01:13:18.980621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:18.337325Z","time spent":"643.155069ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-13T01:13:18.980734Z","caller":"traceutil/trace.go:171","msg":"trace[849955088] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"505.563569ms","start":"2026-06-13T01:13:18.475154Z","end":"2026-06-13T01:13:18.980718Z","steps":["trace[849955088] 'process raft request' (duration: 505.086179ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.98089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:18.475114Z","time spent":"505.665972ms","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6758,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-13T01:13:18.981463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.352863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-13T01:13:18.981555Z","caller":"traceutil/trace.go:171","msg":"trace[42332626] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1429; }","duration":"405.524847ms","start":"2026-06-13T01:13:18.576005Z","end":"2026-06-13T01:13:18.98153Z","steps":["trace[42332626] 'agreement among raft nodes before linearized reading' (duration: 405.040936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.98183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.976431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-13T01:13:18.981915Z","caller":"traceutil/trace.go:171","msg":"trace[1175451828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1429; }","duration":"265.089903ms","start":"2026-06-13T01:13:18.7168Z","end":"2026-06-13T01:13:18.98189Z","steps":["trace[1175451828] 'agreement among raft nodes before linearized reading' (duration: 264.859548ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.9823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.987947ms","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-06-13T01:13:18.98231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.79416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-13T01:13:18.983597Z","caller":"traceutil/trace.go:171","msg":"trace[1276678435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1429; }","duration":"271.256452ms","start":"2026-06-13T01:13:18.712286Z","end":"2026-06-13T01:13:18.983543Z","steps":["trace[1276678435] 'agreement among raft nodes before linearized reading' (duration: 269.569524ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:13:18.982358Z","caller":"traceutil/trace.go:171","msg":"trace[1072599631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1429; }","duration":"518.238121ms","start":"2026-06-13T01:13:18.464087Z","end":"2026-06-13T01:13:18.982325Z","steps":["trace[1072599631] 'agreement among raft nodes before linearized reading' (duration: 517.971626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.983839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:18.464061Z","time spent":"519.761375ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-13T01:13:18.982615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.078391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-13T01:13:18.984187Z","caller":"traceutil/trace.go:171","msg":"trace[1724102959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1429; }","duration":"286.679415ms","start":"2026-06-13T01:13:18.697492Z","end":"2026-06-13T01:13:18.984172Z","steps":["trace[1724102959] 'agreement among raft nodes before linearized reading' (duration: 285.059729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.982774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.28048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-13T01:13:18.984521Z","caller":"traceutil/trace.go:171","msg":"trace[1634517683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1429; }","duration":"272.08113ms","start":"2026-06-13T01:13:18.712403Z","end":"2026-06-13T01:13:18.984484Z","steps":["trace[1634517683] 'agreement among raft nodes before linearized reading' (duration: 270.114107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-13T01:13:18.981748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-13T01:13:18.575969Z","time spent":"405.63104ms","remote":"127.0.0.1:52324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-13T01:17:08.0189Z","caller":"traceutil/trace.go:171","msg":"trace[492789535] transaction","detail":"{read_only:false; response_revision:2501; number_of_response:1; }","duration":"136.902311ms","start":"2026-06-13T01:17:07.881962Z","end":"2026-06-13T01:17:08.018865Z","steps":["trace[492789535] 'process raft request' (duration: 136.715247ms)"],"step_count":1} {"level":"info","ts":"2026-06-13T01:17:16.08043Z","caller":"traceutil/trace.go:171","msg":"trace[525667932] transaction","detail":"{read_only:false; response_revision:2544; number_of_response:1; }","duration":"224.159558ms","start":"2026-06-13T01:17:15.856235Z","end":"2026-06-13T01:17:16.080394Z","steps":["trace[525667932] 'process raft request' (duration: 223.844832ms)"],"step_count":1}