{"level":"warn","ts":"2026-07-03T11:57:50.485794Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T11:57:50.485889Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-07-03T11:57:50.485965Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T11:57:50.485976Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-03T11:57:50.485995Z","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-07-03T11:57:50.486794Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-03T11:57:50.486918Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-07-03T11:57:50.489476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.289364ms"} {"level":"info","ts":"2026-07-03T11:57:50.497966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-03T11:57:50.498075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T11:57:50.498106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-03T11:57:50.49812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T11:57:50.498135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-03T11:57:50.498172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-03T11:57:50.50342Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T11:57:50.50796Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T11:57:50.510101Z","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-07-03T11:57:50.513503Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T11:57:50.513743Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T11:57:50.51387Z","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-07-03T11:57:50.51489Z","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-07-03T11:57:50.514959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-03T11:57:50.515059Z","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-07-03T11:57:50.515127Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-03T11:57:50.519495Z","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-07-03T11:57:50.519716Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-03T11:57:50.519875Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-03T11:57:50.519932Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T11:57:50.519819Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T11:57:51.199252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T11:57:51.199304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T11:57:51.199328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-03T11:57:51.199346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-03T11:57:51.199353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-03T11:57:51.199363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-03T11:57:51.199372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-03T11:57:51.200391Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T11:57:51.200561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T11:57:51.200596Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T11:57:51.200755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:57:51.20086Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T11:57:51.200901Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T11:57:51.20165Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:57:51.201712Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:57:51.201734Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:57:51.202839Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-03T11:57:51.203027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-03T11:58:16.534951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220928837690769,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T11:58:16.651478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.550457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T11:58:16.651808Z","caller":"traceutil/trace.go:171","msg":"trace[1449515682] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"879.759027ms","start":"2026-07-03T11:58:15.772023Z","end":"2026-07-03T11:58:16.651782Z","steps":["trace[1449515682] 'process raft request' (duration: 879.596401ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:16.652095Z","caller":"traceutil/trace.go:171","msg":"trace[1410070994] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"878.965201ms","start":"2026-07-03T11:58:15.773099Z","end":"2026-07-03T11:58:16.652065Z","steps":["trace[1410070994] 'process raft request' (duration: 878.593379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.652272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:15.773081Z","time spent":"879.089735ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:16.652318Z","caller":"traceutil/trace.go:171","msg":"trace[32171739] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"521.373964ms","start":"2026-07-03T11:58:16.130926Z","end":"2026-07-03T11:58:16.6523Z","steps":["trace[32171739] 'process raft request' (duration: 520.9214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.65236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.69355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T11:58:16.651904Z","caller":"traceutil/trace.go:171","msg":"trace[1989615962] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"885.70847ms","start":"2026-07-03T11:58:15.766167Z","end":"2026-07-03T11:58:16.651876Z","steps":["trace[1989615962] 'process raft request' (duration: 35.977184ms)","trace[1989615962] 'compare' (duration: 848.394823ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:16.652475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:16.130902Z","time spent":"521.493848ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:16.652469Z","caller":"traceutil/trace.go:171","msg":"trace[1849278781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:402; }","duration":"617.822144ms","start":"2026-07-03T11:58:16.034627Z","end":"2026-07-03T11:58:16.65245Z","steps":["trace[1849278781] 'agreement among raft nodes before linearized reading' (duration: 617.338539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.6526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:15.766156Z","time spent":"886.34626ms","remote":"127.0.0.1:43478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:58:16.652612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:16.034611Z","time spent":"617.944598ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T11:58:16.651933Z","caller":"traceutil/trace.go:171","msg":"trace[1853688741] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:410; }","duration":"617.245385ms","start":"2026-07-03T11:58:16.034651Z","end":"2026-07-03T11:58:16.651896Z","steps":["trace[1853688741] 'read index received' (duration: 611.375586ms)","trace[1853688741] 'applied index is now lower than readState.Index' (duration: 5.868709ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:16.652799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.127052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-03T11:58:16.652857Z","caller":"traceutil/trace.go:171","msg":"trace[41054927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:402; }","duration":"613.197475ms","start":"2026-07-03T11:58:16.039642Z","end":"2026-07-03T11:58:16.65284Z","steps":["trace[41054927] 'agreement among raft nodes before linearized reading' (duration: 613.101632ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:16.651972Z","caller":"traceutil/trace.go:171","msg":"trace[269831061] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"869.608099ms","start":"2026-07-03T11:58:15.782355Z","end":"2026-07-03T11:58:16.651963Z","steps":["trace[269831061] 'process raft request' (duration: 869.397062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.65289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:16.039636Z","time spent":"613.247066ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T11:58:16.652959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:15.78234Z","time spent":"870.57023ms","remote":"127.0.0.1:43508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:58:16.652984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.89145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:58:16.653034Z","caller":"traceutil/trace.go:171","msg":"trace[1956540166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"368.006744ms","start":"2026-07-03T11:58:16.285011Z","end":"2026-07-03T11:58:16.653017Z","steps":["trace[1956540166] 'agreement among raft nodes before linearized reading' (duration: 367.930411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.653087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:16.28499Z","time spent":"368.086006ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-03T11:58:16.653041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.670309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-03T11:58:16.653186Z","caller":"traceutil/trace.go:171","msg":"trace[1257906371] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:402; }","duration":"438.850186ms","start":"2026-07-03T11:58:16.214323Z","end":"2026-07-03T11:58:16.653173Z","steps":["trace[1257906371] 'agreement among raft nodes before linearized reading' (duration: 438.68665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.65323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:16.214302Z","time spent":"438.918807ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-03T11:58:16.652329Z","caller":"traceutil/trace.go:171","msg":"trace[2061001899] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"868.458321ms","start":"2026-07-03T11:58:15.783778Z","end":"2026-07-03T11:58:16.652237Z","steps":["trace[2061001899] 'process raft request' (duration: 868.025537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:16.653451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:15.783768Z","time spent":"869.636959ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:58:16.653743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:15.772Z","time spent":"880.109228ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:58:29.083221Z","caller":"traceutil/trace.go:171","msg":"trace[670078074] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"362.729152ms","start":"2026-07-03T11:58:28.720467Z","end":"2026-07-03T11:58:29.083196Z","steps":["trace[670078074] 'process raft request' (duration: 362.582937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.083432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.720449Z","time spent":"362.853256ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:29.091783Z","caller":"traceutil/trace.go:171","msg":"trace[32266306] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"370.69765ms","start":"2026-07-03T11:58:28.721049Z","end":"2026-07-03T11:58:29.091747Z","steps":["trace[32266306] 'process raft request' (duration: 370.043309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.091862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.721036Z","time spent":"370.786482ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:29.091939Z","caller":"traceutil/trace.go:171","msg":"trace[1544973572] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"370.806643ms","start":"2026-07-03T11:58:28.721109Z","end":"2026-07-03T11:58:29.091915Z","steps":["trace[1544973572] 'process raft request' (duration: 370.08805ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.091944Z","caller":"traceutil/trace.go:171","msg":"trace[518952735] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"293.045318ms","start":"2026-07-03T11:58:28.798867Z","end":"2026-07-03T11:58:29.091912Z","steps":["trace[518952735] 'process raft request' (duration: 292.509681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.092006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.721103Z","time spent":"370.871665ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:29.092037Z","caller":"traceutil/trace.go:171","msg":"trace[218732026] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"316.753165ms","start":"2026-07-03T11:58:28.775279Z","end":"2026-07-03T11:58:29.092032Z","steps":["trace[218732026] 'process raft request' (duration: 315.96441ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.091981Z","caller":"traceutil/trace.go:171","msg":"trace[441307722] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"307.134253ms","start":"2026-07-03T11:58:28.784816Z","end":"2026-07-03T11:58:29.09195Z","steps":["trace[441307722] 'process raft request' (duration: 306.465642ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.092116Z","caller":"traceutil/trace.go:171","msg":"trace[413280126] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"307.106493ms","start":"2026-07-03T11:58:28.784997Z","end":"2026-07-03T11:58:29.092104Z","steps":["trace[413280126] 'process raft request' (duration: 306.313678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.092133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.775256Z","time spent":"316.814177ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:58:29.092212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.78499Z","time spent":"307.162215ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:58:29.092269Z","caller":"traceutil/trace.go:171","msg":"trace[2060641483] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"303.675722ms","start":"2026-07-03T11:58:28.788582Z","end":"2026-07-03T11:58:29.092258Z","steps":["trace[2060641483] 'process raft request' (duration: 302.759823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.092124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.784797Z","time spent":"307.309589ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:58:29.092295Z","caller":"traceutil/trace.go:171","msg":"trace[1246445012] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"293.283506ms","start":"2026-07-03T11:58:28.799001Z","end":"2026-07-03T11:58:29.092284Z","steps":["trace[1246445012] 'process raft request' (duration: 292.559783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.092327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.788563Z","time spent":"303.732384ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:58:29.092438Z","caller":"traceutil/trace.go:171","msg":"trace[1430634054] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"293.523804ms","start":"2026-07-03T11:58:28.798905Z","end":"2026-07-03T11:58:29.092429Z","steps":["trace[1430634054] 'process raft request' (duration: 292.617945ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.092542Z","caller":"traceutil/trace.go:171","msg":"trace[1181058293] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"291.018342ms","start":"2026-07-03T11:58:28.801479Z","end":"2026-07-03T11:58:29.092497Z","steps":["trace[1181058293] 'process raft request' (duration: 290.156804ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.092691Z","caller":"traceutil/trace.go:171","msg":"trace[1507367372] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"273.383132ms","start":"2026-07-03T11:58:28.819257Z","end":"2026-07-03T11:58:29.09264Z","steps":["trace[1507367372] 'process raft request' (duration: 272.496603ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.092953Z","caller":"traceutil/trace.go:171","msg":"trace[573817911] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"255.07975ms","start":"2026-07-03T11:58:28.83786Z","end":"2026-07-03T11:58:29.09294Z","steps":["trace[573817911] 'process raft request' (duration: 253.940284ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.093409Z","caller":"traceutil/trace.go:171","msg":"trace[1546715731] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"151.360826ms","start":"2026-07-03T11:58:28.941777Z","end":"2026-07-03T11:58:29.093138Z","steps":["trace[1546715731] 'process raft request' (duration: 151.166039ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:29.099587Z","caller":"traceutil/trace.go:171","msg":"trace[1705828416] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"300.52376ms","start":"2026-07-03T11:58:28.799046Z","end":"2026-07-03T11:58:29.09957Z","steps":["trace[1705828416] 'process raft request' (duration: 292.549822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:29.09966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:28.799037Z","time spent":"300.589373ms","remote":"127.0.0.1:43088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:58:35.485654Z","caller":"traceutil/trace.go:171","msg":"trace[1962247297] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"610.362853ms","start":"2026-07-03T11:58:34.875274Z","end":"2026-07-03T11:58:35.485637Z","steps":["trace[1962247297] 'process raft request' (duration: 608.661597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:35.485767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.896859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6snsg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-03T11:58:35.485821Z","caller":"traceutil/trace.go:171","msg":"trace[893764738] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6snsg; range_end:; response_count:1; response_revision:590; }","duration":"473.989002ms","start":"2026-07-03T11:58:35.011815Z","end":"2026-07-03T11:58:35.485804Z","steps":["trace[893764738] 'agreement among raft nodes before linearized reading' (duration: 473.872788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:35.485863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:35.011796Z","time spent":"474.056684ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-6snsg\" "} {"level":"warn","ts":"2026-07-03T11:58:35.485774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:34.875244Z","time spent":"610.463586ms","remote":"127.0.0.1:43134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:35.485627Z","caller":"traceutil/trace.go:171","msg":"trace[1460508372] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"473.737263ms","start":"2026-07-03T11:58:35.011863Z","end":"2026-07-03T11:58:35.485601Z","steps":["trace[1460508372] 'read index received' (duration: 471.708778ms)","trace[1460508372] 'applied index is now lower than readState.Index' (duration: 2.027355ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:35.486108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.705025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-03T11:58:35.486714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.672021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T11:58:35.48619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.963958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T11:58:35.486811Z","caller":"traceutil/trace.go:171","msg":"trace[1351053850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"200.792705ms","start":"2026-07-03T11:58:35.285988Z","end":"2026-07-03T11:58:35.486781Z","steps":["trace[1351053850] 'agreement among raft nodes before linearized reading' (duration: 200.113753ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:35.486841Z","caller":"traceutil/trace.go:171","msg":"trace[1004216432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"375.625341ms","start":"2026-07-03T11:58:35.111181Z","end":"2026-07-03T11:58:35.486806Z","steps":["trace[1004216432] 'agreement among raft nodes before linearized reading' (duration: 374.956039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:35.486955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:35.111156Z","time spent":"375.743204ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T11:58:35.487438Z","caller":"traceutil/trace.go:171","msg":"trace[1672045669] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"380.766326ms","start":"2026-07-03T11:58:35.105359Z","end":"2026-07-03T11:58:35.486126Z","steps":["trace[1672045669] 'agreement among raft nodes before linearized reading' (duration: 380.673963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:35.487712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:35.10534Z","time spent":"382.348738ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T11:58:35.488377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.137939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-03T11:58:35.48855Z","caller":"traceutil/trace.go:171","msg":"trace[345316540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"377.287844ms","start":"2026-07-03T11:58:35.111131Z","end":"2026-07-03T11:58:35.488419Z","steps":["trace[345316540] 'agreement among raft nodes before linearized reading' (duration: 375.01817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:35.48863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:35.111076Z","time spent":"377.503461ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T11:58:35.699837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.516954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T11:58:35.700017Z","caller":"traceutil/trace.go:171","msg":"trace[593008663] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"206.402876ms","start":"2026-07-03T11:58:35.493588Z","end":"2026-07-03T11:58:35.699991Z","steps":["trace[593008663] 'process raft request' (duration: 74.621084ms)","trace[593008663] 'compare' (duration: 131.355958ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:58:35.748378Z","caller":"traceutil/trace.go:171","msg":"trace[2015482933] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"253.731798ms","start":"2026-07-03T11:58:35.494619Z","end":"2026-07-03T11:58:35.74835Z","steps":["trace[2015482933] 'process raft request' (duration: 253.450899ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:35.748859Z","caller":"traceutil/trace.go:171","msg":"trace[418049676] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"253.80772ms","start":"2026-07-03T11:58:35.495029Z","end":"2026-07-03T11:58:35.748837Z","steps":["trace[418049676] 'process raft request' (duration: 253.282473ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:35.748968Z","caller":"traceutil/trace.go:171","msg":"trace[148176266] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"245.76812ms","start":"2026-07-03T11:58:35.503175Z","end":"2026-07-03T11:58:35.748943Z","steps":["trace[148176266] 'process raft request' (duration: 245.44992ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:35.749347Z","caller":"traceutil/trace.go:171","msg":"trace[892239672] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"209.511927ms","start":"2026-07-03T11:58:35.539821Z","end":"2026-07-03T11:58:35.749333Z","steps":["trace[892239672] 'process raft request' (duration: 209.083404ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:36.441465Z","caller":"traceutil/trace.go:171","msg":"trace[1907771700] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"151.426418ms","start":"2026-07-03T11:58:36.29001Z","end":"2026-07-03T11:58:36.441437Z","steps":["trace[1907771700] 'read index received' (duration: 106.900348ms)","trace[1907771700] 'applied index is now lower than readState.Index' (duration: 44.52503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:36.441682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.654585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:58:36.441725Z","caller":"traceutil/trace.go:171","msg":"trace[912951416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"151.753288ms","start":"2026-07-03T11:58:36.28996Z","end":"2026-07-03T11:58:36.441714Z","steps":["trace[912951416] 'agreement among raft nodes before linearized reading' (duration: 151.642495ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:36.442042Z","caller":"traceutil/trace.go:171","msg":"trace[333433436] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"239.787646ms","start":"2026-07-03T11:58:36.202209Z","end":"2026-07-03T11:58:36.441996Z","steps":["trace[333433436] 'process raft request' (duration: 195.029259ms)","trace[333433436] 'compare' (duration: 43.775006ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:37.525418Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012126589s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T11:58:37.546808Z","caller":"traceutil/trace.go:171","msg":"trace[642850316] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:617; }","duration":"260.853627ms","start":"2026-07-03T11:58:37.285928Z","end":"2026-07-03T11:58:37.546781Z","steps":["trace[642850316] 'read index received' (duration: 239.788276ms)","trace[642850316] 'applied index is now lower than readState.Index' (duration: 21.064421ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:37.546932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.995692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:58:37.546988Z","caller":"traceutil/trace.go:171","msg":"trace[1936881705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"261.094665ms","start":"2026-07-03T11:58:37.285875Z","end":"2026-07-03T11:58:37.54697Z","steps":["trace[1936881705] 'agreement among raft nodes before linearized reading' (duration: 260.999562ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:37.547006Z","caller":"traceutil/trace.go:171","msg":"trace[1949660258] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.037398906s","start":"2026-07-03T11:58:36.509582Z","end":"2026-07-03T11:58:37.546981Z","steps":["trace[1949660258] 'process raft request' (duration: 1.016136668s)","trace[1949660258] 'compare' (duration: 20.873785ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:58:37.547132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:36.509556Z","time spent":"1.037496109s","remote":"127.0.0.1:43292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:58:37.547192Z","caller":"traceutil/trace.go:171","msg":"trace[1329932414] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"531.377408ms","start":"2026-07-03T11:58:37.0158Z","end":"2026-07-03T11:58:37.547177Z","steps":["trace[1329932414] 'process raft request' (duration: 530.928183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:37.547281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:58:37.015765Z","time spent":"531.462611ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:58:39.347078Z","caller":"traceutil/trace.go:171","msg":"trace[221558897] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"184.970823ms","start":"2026-07-03T11:58:39.162065Z","end":"2026-07-03T11:58:39.347036Z","steps":["trace[221558897] 'process raft request' (duration: 184.789467ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:49.279155Z","caller":"traceutil/trace.go:171","msg":"trace[1883895089] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"164.393577ms","start":"2026-07-03T11:58:49.114729Z","end":"2026-07-03T11:58:49.279123Z","steps":["trace[1883895089] 'process raft request' (duration: 164.189331ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:52.044873Z","caller":"traceutil/trace.go:171","msg":"trace[2146704416] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"223.309573ms","start":"2026-07-03T11:58:51.821537Z","end":"2026-07-03T11:58:52.044847Z","steps":["trace[2146704416] 'read index received' (duration: 222.961482ms)","trace[2146704416] 'applied index is now lower than readState.Index' (duration: 346.851µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:58:52.044967Z","caller":"traceutil/trace.go:171","msg":"trace[1880095284] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"278.221419ms","start":"2026-07-03T11:58:51.766698Z","end":"2026-07-03T11:58:52.044919Z","steps":["trace[1880095284] 'process raft request' (duration: 277.965881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:52.045238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.672746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-03T11:58:52.045238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.664453ms","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-07-03T11:58:52.045279Z","caller":"traceutil/trace.go:171","msg":"trace[881639934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"223.759528ms","start":"2026-07-03T11:58:51.821508Z","end":"2026-07-03T11:58:52.045268Z","steps":["trace[881639934] 'agreement among raft nodes before linearized reading' (duration: 223.488599ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:52.04529Z","caller":"traceutil/trace.go:171","msg":"trace[1135244337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"222.749625ms","start":"2026-07-03T11:58:51.822526Z","end":"2026-07-03T11:58:52.045276Z","steps":["trace[1135244337] 'agreement among raft nodes before linearized reading' (duration: 222.566659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:58:52.045313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.897897ms","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-07-03T11:58:52.045383Z","caller":"traceutil/trace.go:171","msg":"trace[1125394124] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"200.037231ms","start":"2026-07-03T11:58:51.845322Z","end":"2026-07-03T11:58:52.045359Z","steps":["trace[1125394124] 'agreement among raft nodes before linearized reading' (duration: 199.800513ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:52.173709Z","caller":"traceutil/trace.go:171","msg":"trace[1409678688] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"123.930379ms","start":"2026-07-03T11:58:52.049749Z","end":"2026-07-03T11:58:52.173679Z","steps":["trace[1409678688] 'process raft request' (duration: 122.600066ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:52.175815Z","caller":"traceutil/trace.go:171","msg":"trace[2106216359] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"125.340794ms","start":"2026-07-03T11:58:52.050405Z","end":"2026-07-03T11:58:52.175746Z","steps":["trace[2106216359] 'process raft request' (duration: 124.927311ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:58:52.17607Z","caller":"traceutil/trace.go:171","msg":"trace[1590339750] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"125.993415ms","start":"2026-07-03T11:58:52.049855Z","end":"2026-07-03T11:58:52.175849Z","steps":["trace[1590339750] 'process raft request' (duration: 125.368455ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:59:27.391899Z","caller":"traceutil/trace.go:171","msg":"trace[683807410] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"376.818189ms","start":"2026-07-03T11:59:27.015058Z","end":"2026-07-03T11:59:27.391876Z","steps":["trace[683807410] 'process raft request' (duration: 376.697255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:59:27.392071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:59:27.015046Z","time spent":"376.934163ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:59:28.22297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.481527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T11:59:28.223042Z","caller":"traceutil/trace.go:171","msg":"trace[1828068035] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"938.950718ms","start":"2026-07-03T11:59:27.284081Z","end":"2026-07-03T11:59:28.223032Z","steps":["trace[1828068035] 'read index received' (duration: 107.786603ms)","trace[1828068035] 'applied index is now lower than readState.Index' (duration: 831.163295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:59:28.223194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.080582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:59:28.223187Z","caller":"traceutil/trace.go:171","msg":"trace[630572995] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"957.835707ms","start":"2026-07-03T11:59:27.265306Z","end":"2026-07-03T11:59:28.223142Z","steps":["trace[630572995] 'process raft request' (duration: 210.721679ms)","trace[630572995] 'compare' (duration: 746.073294ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:59:28.2233Z","caller":"traceutil/trace.go:171","msg":"trace[821865954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"939.237967ms","start":"2026-07-03T11:59:27.284037Z","end":"2026-07-03T11:59:28.223275Z","steps":["trace[821865954] 'agreement among raft nodes before linearized reading' (duration: 939.065381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:59:28.223446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:59:27.26528Z","time spent":"958.071104ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:59:28.223445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:59:27.284012Z","time spent":"939.384642ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T11:59:28.415874Z","caller":"traceutil/trace.go:171","msg":"trace[947438029] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"129.592855ms","start":"2026-07-03T11:59:28.286251Z","end":"2026-07-03T11:59:28.415844Z","steps":["trace[947438029] 'read index received' (duration: 90.736954ms)","trace[947438029] 'applied index is now lower than readState.Index' (duration: 38.854691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:59:28.416056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.794251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:59:28.416138Z","caller":"traceutil/trace.go:171","msg":"trace[1662993178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"129.905345ms","start":"2026-07-03T11:59:28.286215Z","end":"2026-07-03T11:59:28.416121Z","steps":["trace[1662993178] 'agreement among raft nodes before linearized reading' (duration: 129.778721ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:59:28.416733Z","caller":"traceutil/trace.go:171","msg":"trace[569633098] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"190.881508ms","start":"2026-07-03T11:59:28.225811Z","end":"2026-07-03T11:59:28.416692Z","steps":["trace[569633098] 'process raft request' (duration: 151.274603ms)","trace[569633098] 'compare' (duration: 38.626724ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:59:56.740534Z","caller":"traceutil/trace.go:171","msg":"trace[1233116710] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"334.211347ms","start":"2026-07-03T11:59:56.406296Z","end":"2026-07-03T11:59:56.740507Z","steps":["trace[1233116710] 'read index received' (duration: 334.104354ms)","trace[1233116710] 'applied index is now lower than readState.Index' (duration: 106.013µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:59:56.740665Z","caller":"traceutil/trace.go:171","msg":"trace[2029962788] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"376.071195ms","start":"2026-07-03T11:59:56.364562Z","end":"2026-07-03T11:59:56.740633Z","steps":["trace[2029962788] 'process raft request' (duration: 375.816887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:59:56.740718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.399473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T11:59:56.740777Z","caller":"traceutil/trace.go:171","msg":"trace[892722087] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1070; }","duration":"334.502646ms","start":"2026-07-03T11:59:56.406264Z","end":"2026-07-03T11:59:56.740767Z","steps":["trace[892722087] 'agreement among raft nodes before linearized reading' (duration: 334.373792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:59:56.740809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:59:56.406249Z","time spent":"334.550798ms","remote":"127.0.0.1:43344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-03T11:59:56.740845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:59:56.364518Z","time spent":"376.24865ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:59:56.740868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.358038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:59:56.740961Z","caller":"traceutil/trace.go:171","msg":"trace[296639165] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1070; }","duration":"251.530683ms","start":"2026-07-03T11:59:56.489412Z","end":"2026-07-03T11:59:56.740943Z","steps":["trace[296639165] 'agreement among raft nodes before linearized reading' (duration: 251.348027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:59:56.740965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.025584ms","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-07-03T11:59:56.741059Z","caller":"traceutil/trace.go:171","msg":"trace[1756049303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"114.160287ms","start":"2026-07-03T11:59:56.626879Z","end":"2026-07-03T11:59:56.741039Z","steps":["trace[1756049303] 'agreement among raft nodes before linearized reading' (duration: 113.895789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:59:56.741061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.879323ms","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-07-03T11:59:56.741097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.646129ms","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-07-03T11:59:56.741095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.503895ms","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-07-03T11:59:56.741135Z","caller":"traceutil/trace.go:171","msg":"trace[1269844359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"112.692121ms","start":"2026-07-03T11:59:56.62843Z","end":"2026-07-03T11:59:56.741122Z","steps":["trace[1269844359] 'agreement among raft nodes before linearized reading' (duration: 112.559017ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:59:56.741131Z","caller":"traceutil/trace.go:171","msg":"trace[1727309694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1070; }","duration":"183.985407ms","start":"2026-07-03T11:59:56.557129Z","end":"2026-07-03T11:59:56.741115Z","steps":["trace[1727309694] 'agreement among raft nodes before linearized reading' (duration: 183.78736ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:59:56.74118Z","caller":"traceutil/trace.go:171","msg":"trace[767029393] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1070; }","duration":"121.639879ms","start":"2026-07-03T11:59:56.619522Z","end":"2026-07-03T11:59:56.741162Z","steps":["trace[767029393] 'agreement among raft nodes before linearized reading' (duration: 121.380331ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:59:59.472704Z","caller":"traceutil/trace.go:171","msg":"trace[325723974] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"107.452741ms","start":"2026-07-03T11:59:59.365222Z","end":"2026-07-03T11:59:59.472675Z","steps":["trace[325723974] 'process raft request' (duration: 107.297526ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:01.159602Z","caller":"traceutil/trace.go:171","msg":"trace[1983026281] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"242.316057ms","start":"2026-07-03T12:00:00.917249Z","end":"2026-07-03T12:00:01.159565Z","steps":["trace[1983026281] 'process raft request' (duration: 211.701561ms)","trace[1983026281] 'compare' (duration: 30.352398ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:01.163548Z","caller":"traceutil/trace.go:171","msg":"trace[1811632401] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"245.728886ms","start":"2026-07-03T12:00:00.917793Z","end":"2026-07-03T12:00:01.163522Z","steps":["trace[1811632401] 'process raft request' (duration: 245.582612ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:15.048948Z","caller":"traceutil/trace.go:171","msg":"trace[1084043436] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"104.718413ms","start":"2026-07-03T12:00:14.944203Z","end":"2026-07-03T12:00:15.048921Z","steps":["trace[1084043436] 'read index received' (duration: 56.531081ms)","trace[1084043436] 'applied index is now lower than readState.Index' (duration: 48.186212ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:15.049116Z","caller":"traceutil/trace.go:171","msg":"trace[812869121] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"141.373844ms","start":"2026-07-03T12:00:14.907715Z","end":"2026-07-03T12:00:15.049089Z","steps":["trace[812869121] 'process raft request' (duration: 93.080578ms)","trace[812869121] 'compare' (duration: 47.977595ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T12:00:15.049158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.93112ms","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-07-03T12:00:15.0492Z","caller":"traceutil/trace.go:171","msg":"trace[7754705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1182; }","duration":"105.021193ms","start":"2026-07-03T12:00:14.944167Z","end":"2026-07-03T12:00:15.049188Z","steps":["trace[7754705] 'agreement among raft nodes before linearized reading' (duration: 104.859368ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:17.151689Z","caller":"traceutil/trace.go:171","msg":"trace[1523529426] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"276.533058ms","start":"2026-07-03T12:00:16.875119Z","end":"2026-07-03T12:00:17.151652Z","steps":["trace[1523529426] 'process raft request' (duration: 276.30235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:29.880704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.663416ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-03T12:00:29.880793Z","caller":"traceutil/trace.go:171","msg":"trace[1265739182] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"124.518308ms","start":"2026-07-03T12:00:29.756263Z","end":"2026-07-03T12:00:29.880781Z","steps":["trace[1265739182] 'read index received' (duration: 12.76289ms)","trace[1265739182] 'applied index is now lower than readState.Index' (duration: 111.754498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T12:00:29.880902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.638642ms","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-07-03T12:00:29.880925Z","caller":"traceutil/trace.go:171","msg":"trace[2029229044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1231; }","duration":"124.709944ms","start":"2026-07-03T12:00:29.756208Z","end":"2026-07-03T12:00:29.880918Z","steps":["trace[2029229044] 'agreement among raft nodes before linearized reading' (duration: 124.600551ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:29.987818Z","caller":"traceutil/trace.go:171","msg":"trace[1458610847] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"102.096438ms","start":"2026-07-03T12:00:29.885699Z","end":"2026-07-03T12:00:29.987796Z","steps":["trace[1458610847] 'process raft request' (duration: 101.943804ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:32.250554Z","caller":"traceutil/trace.go:171","msg":"trace[1249930460] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"253.183038ms","start":"2026-07-03T12:00:31.997355Z","end":"2026-07-03T12:00:32.250538Z","steps":["trace[1249930460] 'process raft request' (duration: 253.059134ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:37.484335Z","caller":"traceutil/trace.go:171","msg":"trace[2034298160] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"223.762144ms","start":"2026-07-03T12:00:37.260549Z","end":"2026-07-03T12:00:37.484311Z","steps":["trace[2034298160] 'read index received' (duration: 223.548217ms)","trace[2034298160] 'applied index is now lower than readState.Index' (duration: 212.977µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:37.484498Z","caller":"traceutil/trace.go:171","msg":"trace[718296473] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"269.172352ms","start":"2026-07-03T12:00:37.215315Z","end":"2026-07-03T12:00:37.484487Z","steps":["trace[718296473] 'process raft request' (duration: 268.835572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:37.484702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.035368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T12:00:37.484789Z","caller":"traceutil/trace.go:171","msg":"trace[1024420975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"198.159492ms","start":"2026-07-03T12:00:37.286606Z","end":"2026-07-03T12:00:37.484765Z","steps":["trace[1024420975] 'agreement among raft nodes before linearized reading' (duration: 198.017618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:37.485147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.13078ms","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-07-03T12:00:37.485148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.298863ms","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-07-03T12:00:37.485182Z","caller":"traceutil/trace.go:171","msg":"trace[1801039096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"193.166741ms","start":"2026-07-03T12:00:37.292005Z","end":"2026-07-03T12:00:37.485171Z","steps":["trace[1801039096] 'agreement among raft nodes before linearized reading' (duration: 193.036057ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:37.485192Z","caller":"traceutil/trace.go:171","msg":"trace[1998248404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"182.376455ms","start":"2026-07-03T12:00:37.302804Z","end":"2026-07-03T12:00:37.48518Z","steps":["trace[1998248404] 'agreement among raft nodes before linearized reading' (duration: 182.22441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:37.48533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.788557ms","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-07-03T12:00:37.485358Z","caller":"traceutil/trace.go:171","msg":"trace[513997566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"224.852289ms","start":"2026-07-03T12:00:37.260498Z","end":"2026-07-03T12:00:37.48535Z","steps":["trace[513997566] 'agreement among raft nodes before linearized reading' (duration: 224.769816ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:37.485471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.519872ms","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-07-03T12:00:37.485498Z","caller":"traceutil/trace.go:171","msg":"trace[2107194569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"178.565783ms","start":"2026-07-03T12:00:37.306925Z","end":"2026-07-03T12:00:37.48549Z","steps":["trace[2107194569] 'agreement among raft nodes before linearized reading' (duration: 178.49678ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:37.60878Z","caller":"traceutil/trace.go:171","msg":"trace[352825658] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"115.184868ms","start":"2026-07-03T12:00:37.493557Z","end":"2026-07-03T12:00:37.608742Z","steps":["trace[352825658] 'process raft request' (duration: 115.124816ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:37.609349Z","caller":"traceutil/trace.go:171","msg":"trace[292844483] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"117.639957ms","start":"2026-07-03T12:00:37.491693Z","end":"2026-07-03T12:00:37.609333Z","steps":["trace[292844483] 'process raft request' (duration: 116.931104ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:37.608937Z","caller":"traceutil/trace.go:171","msg":"trace[93358214] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"117.248184ms","start":"2026-07-03T12:00:37.491668Z","end":"2026-07-03T12:00:37.608916Z","steps":["trace[93358214] 'process raft request' (duration: 116.720008ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:38.830557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.871971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T12:00:38.830862Z","caller":"traceutil/trace.go:171","msg":"trace[1311040883] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1294; }","duration":"126.021096ms","start":"2026-07-03T12:00:38.704605Z","end":"2026-07-03T12:00:38.830626Z","steps":["trace[1311040883] 'count revisions from in-memory index tree' (duration: 125.794399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:38.830859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.270971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-03T12:00:38.83094Z","caller":"traceutil/trace.go:171","msg":"trace[658357994] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1294; }","duration":"538.398295ms","start":"2026-07-03T12:00:38.292522Z","end":"2026-07-03T12:00:38.83092Z","steps":["trace[658357994] 'range keys from in-memory index tree' (duration: 538.113726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:38.830991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:38.292502Z","time spent":"538.479488ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-03T12:00:38.832545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.214147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T12:00:38.832634Z","caller":"traceutil/trace.go:171","msg":"trace[1854584444] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1294; }","duration":"613.330281ms","start":"2026-07-03T12:00:38.219289Z","end":"2026-07-03T12:00:38.832619Z","steps":["trace[1854584444] 'range keys from in-memory index tree' (duration: 613.143396ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:38.832762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:38.219274Z","time spent":"613.439115ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-03T12:00:39.391601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220928837692719,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T12:00:39.523598Z","caller":"traceutil/trace.go:171","msg":"trace[629122015] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"674.895127ms","start":"2026-07-03T12:00:38.848684Z","end":"2026-07-03T12:00:39.523579Z","steps":["trace[629122015] 'process raft request' (duration: 674.771324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.52373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:38.848663Z","time spent":"675.000131ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:00:39.673645Z","caller":"traceutil/trace.go:171","msg":"trace[1805162679] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"781.481449ms","start":"2026-07-03T12:00:38.892132Z","end":"2026-07-03T12:00:39.673613Z","steps":["trace[1805162679] 'process raft request' (duration: 717.537946ms)","trace[1805162679] 'compare' (duration: 63.769338ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:39.67373Z","caller":"traceutil/trace.go:171","msg":"trace[1829229941] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"767.304865ms","start":"2026-07-03T12:00:38.906381Z","end":"2026-07-03T12:00:39.673686Z","steps":["trace[1829229941] 'process raft request' (duration: 767.17352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.673841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:38.906344Z","time spent":"767.438859ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T12:00:39.673783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:38.892118Z","time spent":"781.599584ms","remote":"127.0.0.1:43118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T12:00:39.708863Z","caller":"traceutil/trace.go:171","msg":"trace[1895742552] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1343; }","duration":"818.26551ms","start":"2026-07-03T12:00:38.890575Z","end":"2026-07-03T12:00:39.708841Z","steps":["trace[1895742552] 'read index received' (duration: 632.864298ms)","trace[1895742552] 'applied index is now lower than readState.Index' (duration: 185.400332ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:39.708897Z","caller":"traceutil/trace.go:171","msg":"trace[438124380] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"216.087618ms","start":"2026-07-03T12:00:39.492792Z","end":"2026-07-03T12:00:39.708879Z","steps":["trace[438124380] 'process raft request' (duration: 215.882121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.709172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.577241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-03T12:00:39.709232Z","caller":"traceutil/trace.go:171","msg":"trace[987219155] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1299; }","duration":"818.674473ms","start":"2026-07-03T12:00:38.890541Z","end":"2026-07-03T12:00:39.709215Z","steps":["trace[987219155] 'agreement among raft nodes before linearized reading' (duration: 818.412745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.709266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:38.890523Z","time spent":"818.734246ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-03T12:00:39.71152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.383452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-07-03T12:00:39.711571Z","caller":"traceutil/trace.go:171","msg":"trace[2034983681] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1300; }","duration":"446.477164ms","start":"2026-07-03T12:00:39.26508Z","end":"2026-07-03T12:00:39.711558Z","steps":["trace[2034983681] 'agreement among raft nodes before linearized reading' (duration: 446.296779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.711596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:39.265061Z","time spent":"446.530226ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-03T12:00:39.711901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.622367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T12:00:39.71193Z","caller":"traceutil/trace.go:171","msg":"trace[1686803702] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1300; }","duration":"179.673368ms","start":"2026-07-03T12:00:39.532249Z","end":"2026-07-03T12:00:39.711923Z","steps":["trace[1686803702] 'agreement among raft nodes before linearized reading' (duration: 179.628917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.712139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.719912ms","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-07-03T12:00:39.712179Z","caller":"traceutil/trace.go:171","msg":"trace[1553397390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"194.796914ms","start":"2026-07-03T12:00:39.517372Z","end":"2026-07-03T12:00:39.712169Z","steps":["trace[1553397390] 'agreement among raft nodes before linearized reading' (duration: 194.715091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.712216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.412032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T12:00:39.712272Z","caller":"traceutil/trace.go:171","msg":"trace[1913455425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"427.493455ms","start":"2026-07-03T12:00:39.284765Z","end":"2026-07-03T12:00:39.712258Z","steps":["trace[1913455425] 'agreement among raft nodes before linearized reading' (duration: 427.415612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:39.712304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:39.284755Z","time spent":"427.542056ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-03T12:00:39.845639Z","caller":"traceutil/trace.go:171","msg":"trace[88037347] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"129.375943ms","start":"2026-07-03T12:00:39.716242Z","end":"2026-07-03T12:00:39.845618Z","steps":["trace[88037347] 'process raft request' (duration: 129.308661ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:39.845707Z","caller":"traceutil/trace.go:171","msg":"trace[2146007008] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"129.57863ms","start":"2026-07-03T12:00:39.716084Z","end":"2026-07-03T12:00:39.845662Z","steps":["trace[2146007008] 'process raft request' (duration: 77.717465ms)","trace[2146007008] 'compare' (duration: 51.582326ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:39.845753Z","caller":"traceutil/trace.go:171","msg":"trace[684130577] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"129.529898ms","start":"2026-07-03T12:00:39.716164Z","end":"2026-07-03T12:00:39.845694Z","steps":["trace[684130577] 'process raft request' (duration: 129.347072ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:39.845897Z","caller":"traceutil/trace.go:171","msg":"trace[1995136411] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"126.898064ms","start":"2026-07-03T12:00:39.71898Z","end":"2026-07-03T12:00:39.845878Z","steps":["trace[1995136411] 'process raft request' (duration: 126.78477ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:39.84592Z","caller":"traceutil/trace.go:171","msg":"trace[1235736631] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"128.679512ms","start":"2026-07-03T12:00:39.71722Z","end":"2026-07-03T12:00:39.8459Z","steps":["trace[1235736631] 'process raft request' (duration: 128.361581ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:40.950519Z","caller":"traceutil/trace.go:171","msg":"trace[384629427] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"286.050514ms","start":"2026-07-03T12:00:40.664444Z","end":"2026-07-03T12:00:40.950495Z","steps":["trace[384629427] 'read index received' (duration: 201.947854ms)","trace[384629427] 'applied index is now lower than readState.Index' (duration: 84.10178ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T12:00:40.950655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.188548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T12:00:40.950686Z","caller":"traceutil/trace.go:171","msg":"trace[1768889679] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"309.194497ms","start":"2026-07-03T12:00:40.641438Z","end":"2026-07-03T12:00:40.950632Z","steps":["trace[1768889679] 'process raft request' (duration: 224.916521ms)","trace[1768889679] 'compare' (duration: 84.027307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T12:00:40.950902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:40.641422Z","time spent":"309.365603ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:00:40.95121Z","caller":"traceutil/trace.go:171","msg":"trace[569234741] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1308; }","duration":"286.792708ms","start":"2026-07-03T12:00:40.664401Z","end":"2026-07-03T12:00:40.951193Z","steps":["trace[569234741] 'agreement among raft nodes before linearized reading' (duration: 286.186338ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:45.930349Z","caller":"traceutil/trace.go:171","msg":"trace[767666650] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"350.370898ms","start":"2026-07-03T12:00:45.579955Z","end":"2026-07-03T12:00:45.930326Z","steps":["trace[767666650] 'process raft request' (duration: 350.212493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:45.93061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.579935Z","time spent":"350.494543ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T12:00:46.996685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.88695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T12:00:46.997015Z","caller":"traceutil/trace.go:171","msg":"trace[550979788] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1393; }","duration":"1.229908317s","start":"2026-07-03T12:00:45.767083Z","end":"2026-07-03T12:00:46.996992Z","steps":["trace[550979788] 'read index received' (duration: 163.211809ms)","trace[550979788] 'applied index is now lower than readState.Index' (duration: 1.066695248s)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:46.997094Z","caller":"traceutil/trace.go:171","msg":"trace[1615903997] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.266606975s","start":"2026-07-03T12:00:45.730435Z","end":"2026-07-03T12:00:46.997042Z","steps":["trace[1615903997] 'process raft request' (duration: 775.25523ms)","trace[1615903997] 'compare' (duration: 490.399395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T12:00:46.997279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230201186s","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-07-03T12:00:46.997277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.730411Z","time spent":"1.26675118s","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:00:46.997313Z","caller":"traceutil/trace.go:171","msg":"trace[1328123987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"1.23028883s","start":"2026-07-03T12:00:45.767015Z","end":"2026-07-03T12:00:46.997304Z","steps":["trace[1328123987] 'agreement among raft nodes before linearized reading' (duration: 1.230137365s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:46.997346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.766992Z","time spent":"1.230345931s","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T12:00:46.997392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129557335s","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-07-03T12:00:46.99713Z","caller":"traceutil/trace.go:171","msg":"trace[777120385] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"1.108531871s","start":"2026-07-03T12:00:45.888584Z","end":"2026-07-03T12:00:46.997116Z","steps":["trace[777120385] 'process raft request' (duration: 1.108308343s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:46.997512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.06952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-03T12:00:46.997539Z","caller":"traceutil/trace.go:171","msg":"trace[542301261] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1346; }","duration":"697.130292ms","start":"2026-07-03T12:00:46.300401Z","end":"2026-07-03T12:00:46.997531Z","steps":["trace[542301261] 'agreement among raft nodes before linearized reading' (duration: 697.05882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:46.997569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:46.300381Z","time spent":"697.181524ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-03T12:00:46.99758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127899672s","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-07-03T12:00:46.997616Z","caller":"traceutil/trace.go:171","msg":"trace[839666849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"1.127955493s","start":"2026-07-03T12:00:45.869649Z","end":"2026-07-03T12:00:46.997605Z","steps":["trace[839666849] 'agreement among raft nodes before linearized reading' (duration: 1.12785728s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:46.997594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.888565Z","time spent":"1.108926543s","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T12:00:46.997645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.869639Z","time spent":"1.127999415s","remote":"127.0.0.1:43330","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-07-03T12:00:46.997687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129152823s","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-07-03T12:00:46.997712Z","caller":"traceutil/trace.go:171","msg":"trace[131011006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"1.129212055s","start":"2026-07-03T12:00:45.868493Z","end":"2026-07-03T12:00:46.997705Z","steps":["trace[131011006] 'agreement among raft nodes before linearized reading' (duration: 1.129141512s)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:46.997439Z","caller":"traceutil/trace.go:171","msg":"trace[305980379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"1.129605477s","start":"2026-07-03T12:00:45.86782Z","end":"2026-07-03T12:00:46.997425Z","steps":["trace[305980379] 'agreement among raft nodes before linearized reading' (duration: 1.129440671s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:46.997744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.868474Z","time spent":"1.129264446s","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-03T12:00:46.997761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.867762Z","time spent":"1.129987329s","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-03T12:00:46.997789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042551542s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-03T12:00:46.997863Z","caller":"traceutil/trace.go:171","msg":"trace[947587119] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1346; }","duration":"1.042658446s","start":"2026-07-03T12:00:45.955183Z","end":"2026-07-03T12:00:46.997841Z","steps":["trace[947587119] 'agreement among raft nodes before linearized reading' (duration: 1.042434019s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:46.997902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:45.955168Z","time spent":"1.042725978s","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-03T12:00:47.427973Z","caller":"traceutil/trace.go:171","msg":"trace[590398814] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"425.360416ms","start":"2026-07-03T12:00:47.002578Z","end":"2026-07-03T12:00:47.427939Z","steps":["trace[590398814] 'process raft request' (duration: 424.295352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:47.428159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.002562Z","time spent":"425.495271ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:00:47.43371Z","caller":"traceutil/trace.go:171","msg":"trace[708891037] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"431.0605ms","start":"2026-07-03T12:00:47.002623Z","end":"2026-07-03T12:00:47.433683Z","steps":["trace[708891037] 'process raft request' (duration: 430.77148ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:47.433834Z","caller":"traceutil/trace.go:171","msg":"trace[1826109593] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"431.110801ms","start":"2026-07-03T12:00:47.0027Z","end":"2026-07-03T12:00:47.43381Z","steps":["trace[1826109593] 'process raft request' (duration: 430.840442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:47.433928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.002609Z","time spent":"431.234855ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T12:00:47.43395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.002677Z","time spent":"431.208464ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:00:47.434209Z","caller":"traceutil/trace.go:171","msg":"trace[2113634481] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1396; }","duration":"398.456302ms","start":"2026-07-03T12:00:47.035742Z","end":"2026-07-03T12:00:47.434199Z","steps":["trace[2113634481] 'read index received' (duration: 391.150058ms)","trace[2113634481] 'applied index is now lower than readState.Index' (duration: 7.305534ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:00:47.434338Z","caller":"traceutil/trace.go:171","msg":"trace[1157128710] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"430.843803ms","start":"2026-07-03T12:00:47.003487Z","end":"2026-07-03T12:00:47.434331Z","steps":["trace[1157128710] 'process raft request' (duration: 430.616615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:47.434518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.763993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T12:00:47.434599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.221479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-07-03T12:00:47.434596Z","caller":"traceutil/trace.go:171","msg":"trace[436584107] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1352; }","duration":"398.865956ms","start":"2026-07-03T12:00:47.035704Z","end":"2026-07-03T12:00:47.43457Z","steps":["trace[436584107] 'agreement among raft nodes before linearized reading' (duration: 398.772333ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:47.434637Z","caller":"traceutil/trace.go:171","msg":"trace[197706185] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1352; }","duration":"229.304562ms","start":"2026-07-03T12:00:47.205322Z","end":"2026-07-03T12:00:47.434626Z","steps":["trace[197706185] 'agreement among raft nodes before linearized reading' (duration: 229.198109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:47.434665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.03569Z","time spent":"398.957019ms","remote":"127.0.0.1:49390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-03T12:00:47.434745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.995644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T12:00:47.434768Z","caller":"traceutil/trace.go:171","msg":"trace[789859079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1352; }","duration":"149.054345ms","start":"2026-07-03T12:00:47.285708Z","end":"2026-07-03T12:00:47.434763Z","steps":["trace[789859079] 'agreement among raft nodes before linearized reading' (duration: 149.021394ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:00:47.434915Z","caller":"traceutil/trace.go:171","msg":"trace[859171173] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"431.107651ms","start":"2026-07-03T12:00:47.003789Z","end":"2026-07-03T12:00:47.434897Z","steps":["trace[859171173] 'process raft request' (duration: 430.360687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:47.435001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.003763Z","time spent":"431.196634ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:00:47.435067Z","caller":"traceutil/trace.go:171","msg":"trace[1749585847] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"431.125732ms","start":"2026-07-03T12:00:47.003934Z","end":"2026-07-03T12:00:47.435059Z","steps":["trace[1749585847] 'process raft request' (duration: 430.239673ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:00:47.435162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.003924Z","time spent":"431.170733ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T12:00:47.435353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T12:00:47.003479Z","time spent":"430.887064ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T12:04:26.728876Z","caller":"traceutil/trace.go:171","msg":"trace[753248425] linearizableReadLoop","detail":"{readStateIndex:2470; appliedIndex:2469; }","duration":"158.275591ms","start":"2026-07-03T12:04:26.570577Z","end":"2026-07-03T12:04:26.728852Z","steps":["trace[753248425] 'read index received' (duration: 158.050793ms)","trace[753248425] 'applied index is now lower than readState.Index' (duration: 222.787µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:04:26.728926Z","caller":"traceutil/trace.go:171","msg":"trace[1128191268] transaction","detail":"{read_only:false; response_revision:2375; number_of_response:1; }","duration":"159.850351ms","start":"2026-07-03T12:04:26.569047Z","end":"2026-07-03T12:04:26.728897Z","steps":["trace[1128191268] 'process raft request' (duration: 159.667185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:04:26.729176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.239559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-03T12:04:26.729251Z","caller":"traceutil/trace.go:171","msg":"trace[1511459267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2375; }","duration":"158.348753ms","start":"2026-07-03T12:04:26.570885Z","end":"2026-07-03T12:04:26.729234Z","steps":["trace[1511459267] 'agreement among raft nodes before linearized reading' (duration: 158.164467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T12:04:26.729241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.612881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-03T12:04:26.729421Z","caller":"traceutil/trace.go:171","msg":"trace[75156655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2375; }","duration":"158.861759ms","start":"2026-07-03T12:04:26.570544Z","end":"2026-07-03T12:04:26.729406Z","steps":["trace[75156655] 'agreement among raft nodes before linearized reading' (duration: 158.461206ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:04:26.928683Z","caller":"traceutil/trace.go:171","msg":"trace[1117030413] transaction","detail":"{read_only:false; response_revision:2376; number_of_response:1; }","duration":"195.130613ms","start":"2026-07-03T12:04:26.733518Z","end":"2026-07-03T12:04:26.928648Z","steps":["trace[1117030413] 'process raft request' (duration: 109.187334ms)","trace[1117030413] 'compare' (duration: 85.778244ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T12:04:26.928695Z","caller":"traceutil/trace.go:171","msg":"trace[1995166080] transaction","detail":"{read_only:false; response_revision:2377; number_of_response:1; }","duration":"194.924537ms","start":"2026-07-03T12:04:26.733747Z","end":"2026-07-03T12:04:26.928672Z","steps":["trace[1995166080] 'process raft request' (duration: 194.862755ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:04:26.929007Z","caller":"traceutil/trace.go:171","msg":"trace[73536830] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"194.894965ms","start":"2026-07-03T12:04:26.734095Z","end":"2026-07-03T12:04:26.92899Z","steps":["trace[73536830] 'process raft request' (duration: 194.560894ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:04:26.928997Z","caller":"traceutil/trace.go:171","msg":"trace[1172897007] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"193.989887ms","start":"2026-07-03T12:04:26.734994Z","end":"2026-07-03T12:04:26.928984Z","steps":["trace[1172897007] 'process raft request' (duration: 193.826311ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T12:04:32.962529Z","caller":"traceutil/trace.go:171","msg":"trace[1326026973] transaction","detail":"{read_only:false; response_revision:2407; number_of_response:1; }","duration":"209.062481ms","start":"2026-07-03T12:04:32.753434Z","end":"2026-07-03T12:04:32.962496Z","steps":["trace[1326026973] 'process raft request' (duration: 208.809473ms)"],"step_count":1}