{"level":"warn","ts":"2026-03-20T05:31:54.478473Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:31:54.478565Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-03-20T05:31:54.478621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-20T05:31:54.47863Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-03-20T05:31:54.478661Z","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-03-20T05:31:54.479287Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-03-20T05:31:54.479368Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-03-20T05:31:54.483703Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.128244ms"} {"level":"info","ts":"2026-03-20T05:31:54.494766Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-03-20T05:31:54.494925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-03-20T05:31:54.494962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-03-20T05:31:54.494984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-20T05:31:54.495005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-03-20T05:31:54.49506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-03-20T05:31:54.503159Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-20T05:31:54.511443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-20T05:31:54.514707Z","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-03-20T05:31:54.516708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-20T05:31:54.51688Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-20T05:31:54.516858Z","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-03-20T05:31:54.517021Z","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-03-20T05:31:54.517042Z","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-03-20T05:31:54.519953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-03-20T05:31:54.520119Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-03-20T05:31:54.521674Z","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-03-20T05:31:54.521898Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-20T05:31:54.521933Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-20T05:31:54.522012Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-03-20T05:31:54.522029Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-03-20T05:31:55.09614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-03-20T05:31:55.0962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-20T05:31:55.096223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-03-20T05:31:55.096242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-03-20T05:31:55.096269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-03-20T05:31:55.096285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-03-20T05:31:55.0963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-03-20T05:31:55.457539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:55.458415Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-03-20T05:31:55.458703Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:31:55.458762Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-20T05:31:55.459095Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:55.459274Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-20T05:31:55.459372Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:55.459382Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-20T05:31:55.459451Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-20T05:31:55.461267Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-03-20T05:31:55.464684Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-20T05:31:55.881713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.676214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.881792Z","caller":"traceutil/trace.go:171","msg":"trace[932721162] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"320.998324ms","start":"2026-03-20T05:31:55.560781Z","end":"2026-03-20T05:31:55.88178Z","steps":["trace[932721162] 'range keys from in-memory index tree' (duration: 320.573321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.881827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:55.560752Z","time spent":"321.067696ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 "} {"level":"warn","ts":"2026-03-20T05:31:55.881921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.984463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.881933Z","caller":"traceutil/trace.go:171","msg":"trace[963708826] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"321.135758ms","start":"2026-03-20T05:31:55.560793Z","end":"2026-03-20T05:31:55.881929Z","steps":["trace[963708826] 'count revisions from in-memory index tree' (duration: 319.903581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.881946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:55.560773Z","time spent":"321.169229ms","remote":"127.0.0.1:44964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:31:55.882013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.185341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.882026Z","caller":"traceutil/trace.go:171","msg":"trace[2055285993] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"308.200491ms","start":"2026-03-20T05:31:55.573821Z","end":"2026-03-20T05:31:55.882022Z","steps":["trace[2055285993] 'count revisions from in-memory index tree' (duration: 308.102678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.882044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:55.573804Z","time spent":"308.234792ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:31:55.882123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.301874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.882143Z","caller":"traceutil/trace.go:171","msg":"trace[1418040288] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"308.351706ms","start":"2026-03-20T05:31:55.573779Z","end":"2026-03-20T05:31:55.882131Z","steps":["trace[1418040288] 'range keys from in-memory index tree' (duration: 308.203661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.882165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:31:55.573762Z","time spent":"308.392427ms","remote":"127.0.0.1:44974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 "} {"level":"warn","ts":"2026-03-20T05:31:55.882246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.804591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.882259Z","caller":"traceutil/trace.go:171","msg":"trace[316553196] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"294.832491ms","start":"2026-03-20T05:31:55.587422Z","end":"2026-03-20T05:31:55.882254Z","steps":["trace[316553196] 'range keys from in-memory index tree' (duration: 294.745089ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.882341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.790971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.882354Z","caller":"traceutil/trace.go:171","msg":"trace[1532467951] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"294.807541ms","start":"2026-03-20T05:31:55.587541Z","end":"2026-03-20T05:31:55.882349Z","steps":["trace[1532467951] 'count revisions from in-memory index tree' (duration: 294.706758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.882425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.680218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.882437Z","caller":"traceutil/trace.go:171","msg":"trace[1862205015] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"282.71675ms","start":"2026-03-20T05:31:55.599716Z","end":"2026-03-20T05:31:55.882433Z","steps":["trace[1862205015] 'count revisions from in-memory index tree' (duration: 282.626837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.882726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.910055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.882748Z","caller":"traceutil/trace.go:171","msg":"trace[594852681] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"282.950987ms","start":"2026-03-20T05:31:55.599792Z","end":"2026-03-20T05:31:55.882743Z","steps":["trace[594852681] 'range keys from in-memory index tree' (duration: 282.860134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.882945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.31284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.88297Z","caller":"traceutil/trace.go:171","msg":"trace[891559008] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"264.350741ms","start":"2026-03-20T05:31:55.618614Z","end":"2026-03-20T05:31:55.882965Z","steps":["trace[891559008] 'range keys from in-memory index tree' (duration: 264.276979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.88307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.505395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.883088Z","caller":"traceutil/trace.go:171","msg":"trace[757015719] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"264.583777ms","start":"2026-03-20T05:31:55.6185Z","end":"2026-03-20T05:31:55.883084Z","steps":["trace[757015719] 'count revisions from in-memory index tree' (duration: 264.366121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.883151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.123135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.88317Z","caller":"traceutil/trace.go:171","msg":"trace[1604675568] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"254.247109ms","start":"2026-03-20T05:31:55.628917Z","end":"2026-03-20T05:31:55.883164Z","steps":["trace[1604675568] 'range keys from in-memory index tree' (duration: 254.035232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.883595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.399334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.883688Z","caller":"traceutil/trace.go:171","msg":"trace[1285971235] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"254.552727ms","start":"2026-03-20T05:31:55.629108Z","end":"2026-03-20T05:31:55.883661Z","steps":["trace[1285971235] 'count revisions from in-memory index tree' (duration: 254.29541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.883935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.466054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:31:55.883968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.884369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.884091Z","caller":"traceutil/trace.go:171","msg":"trace[1997951863] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"157.94687ms","start":"2026-03-20T05:31:55.726048Z","end":"2026-03-20T05:31:55.883995Z","steps":["trace[1997951863] 'range keys from in-memory index tree' (duration: 157.780176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.884342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.27541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.884381Z","caller":"traceutil/trace.go:171","msg":"trace[2115299140] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"158.339223ms","start":"2026-03-20T05:31:55.726033Z","end":"2026-03-20T05:31:55.884372Z","steps":["trace[2115299140] 'count revisions from in-memory index tree' (duration: 158.155887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.884589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.397356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.883971Z","caller":"traceutil/trace.go:171","msg":"trace[28781148] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"169.527606ms","start":"2026-03-20T05:31:55.71443Z","end":"2026-03-20T05:31:55.883957Z","steps":["trace[28781148] 'range keys from in-memory index tree' (duration: 169.355141ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:55.884628Z","caller":"traceutil/trace.go:171","msg":"trace[1211305590] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"147.441147ms","start":"2026-03-20T05:31:55.737178Z","end":"2026-03-20T05:31:55.884619Z","steps":["trace[1211305590] 'range keys from in-memory index tree' (duration: 147.311303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.884783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.975552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.884807Z","caller":"traceutil/trace.go:171","msg":"trace[1399473372] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"242.001573ms","start":"2026-03-20T05:31:55.642799Z","end":"2026-03-20T05:31:55.884801Z","steps":["trace[1399473372] 'range keys from in-memory index tree' (duration: 241.88538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.884856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.526799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:31:55.88497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.069854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.884995Z","caller":"traceutil/trace.go:171","msg":"trace[1987771893] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"242.103976ms","start":"2026-03-20T05:31:55.642883Z","end":"2026-03-20T05:31:55.884987Z","steps":["trace[1987771893] 'count revisions from in-memory index tree' (duration: 242.024583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.278992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885082Z","caller":"traceutil/trace.go:171","msg":"trace[2048026759] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"229.318694ms","start":"2026-03-20T05:31:55.655757Z","end":"2026-03-20T05:31:55.885075Z","steps":["trace[2048026759] 'count revisions from in-memory index tree' (duration: 229.226371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.354925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885235Z","caller":"traceutil/trace.go:171","msg":"trace[184456440] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"229.421836ms","start":"2026-03-20T05:31:55.655808Z","end":"2026-03-20T05:31:55.88523Z","steps":["trace[184456440] 'range keys from in-memory index tree' (duration: 229.269043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.454929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885369Z","caller":"traceutil/trace.go:171","msg":"trace[1661851180] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"212.523961ms","start":"2026-03-20T05:31:55.672838Z","end":"2026-03-20T05:31:55.885362Z","steps":["trace[1661851180] 'count revisions from in-memory index tree' (duration: 212.293115ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.625605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-20T05:31:55.885486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.660305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885519Z","caller":"traceutil/trace.go:171","msg":"trace[2116416230] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"212.681066ms","start":"2026-03-20T05:31:55.672833Z","end":"2026-03-20T05:31:55.885514Z","steps":["trace[2116416230] 'range keys from in-memory index tree' (duration: 212.45208ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:55.885553Z","caller":"traceutil/trace.go:171","msg":"trace[817617806] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"137.761807ms","start":"2026-03-20T05:31:55.747771Z","end":"2026-03-20T05:31:55.885533Z","steps":["trace[817617806] 'range keys from in-memory index tree' (duration: 137.604862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.482002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885656Z","caller":"traceutil/trace.go:171","msg":"trace[1912459013] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"200.504803ms","start":"2026-03-20T05:31:55.685146Z","end":"2026-03-20T05:31:55.885651Z","steps":["trace[1912459013] 'range keys from in-memory index tree' (duration: 200.39383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.014965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885754Z","caller":"traceutil/trace.go:171","msg":"trace[479368040] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"138.065117ms","start":"2026-03-20T05:31:55.747681Z","end":"2026-03-20T05:31:55.885747Z","steps":["trace[479368040] 'count revisions from in-memory index tree' (duration: 137.873191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.885831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.608546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.885854Z","caller":"traceutil/trace.go:171","msg":"trace[1111039479] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"200.663667ms","start":"2026-03-20T05:31:55.685185Z","end":"2026-03-20T05:31:55.885848Z","steps":["trace[1111039479] 'count revisions from in-memory index tree' (duration: 200.501952ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:31:55.884925Z","caller":"traceutil/trace.go:171","msg":"trace[1394559507] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"147.615962ms","start":"2026-03-20T05:31:55.737299Z","end":"2026-03-20T05:31:55.884915Z","steps":["trace[1394559507] 'count revisions from in-memory index tree' (duration: 147.454867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.888823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.985417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.889001Z","caller":"traceutil/trace.go:171","msg":"trace[681356537] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"189.123932ms","start":"2026-03-20T05:31:55.699784Z","end":"2026-03-20T05:31:55.888908Z","steps":["trace[681356537] 'range keys from in-memory index tree' (duration: 188.919836ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.890714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.352981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.890767Z","caller":"traceutil/trace.go:171","msg":"trace[1420436416] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"176.426752ms","start":"2026-03-20T05:31:55.714325Z","end":"2026-03-20T05:31:55.890752Z","steps":["trace[1420436416] 'count revisions from in-memory index tree' (duration: 176.295278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:31:55.891353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.629787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-20T05:31:55.891393Z","caller":"traceutil/trace.go:171","msg":"trace[973388853] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"191.700829ms","start":"2026-03-20T05:31:55.699683Z","end":"2026-03-20T05:31:55.891383Z","steps":["trace[973388853] 'count revisions from in-memory index tree' (duration: 191.467233ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:27.354151Z","caller":"traceutil/trace.go:171","msg":"trace[1000262597] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"209.665897ms","start":"2026-03-20T05:32:27.144461Z","end":"2026-03-20T05:32:27.354127Z","steps":["trace[1000262597] 'process raft request' (duration: 209.4579ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:27.431775Z","caller":"traceutil/trace.go:171","msg":"trace[1197339393] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:526; }","duration":"273.908977ms","start":"2026-03-20T05:32:27.157837Z","end":"2026-03-20T05:32:27.431746Z","steps":["trace[1197339393] 'read index received' (duration: 196.109341ms)","trace[1197339393] 'applied index is now lower than readState.Index' (duration: 77.798626ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:27.431911Z","caller":"traceutil/trace.go:171","msg":"trace[258527840] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"287.40553ms","start":"2026-03-20T05:32:27.144496Z","end":"2026-03-20T05:32:27.431901Z","steps":["trace[258527840] 'process raft request' (duration: 285.487333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:27.432026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.172921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-20T05:32:27.43211Z","caller":"traceutil/trace.go:171","msg":"trace[342475038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:513; }","duration":"214.296105ms","start":"2026-03-20T05:32:27.217795Z","end":"2026-03-20T05:32:27.432091Z","steps":["trace[342475038] 'agreement among raft nodes before linearized reading' (duration: 214.174331ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:27.432322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.471062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:32:27.432327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.472683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-20T05:32:27.432377Z","caller":"traceutil/trace.go:171","msg":"trace[1470383602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"113.567494ms","start":"2026-03-20T05:32:27.318796Z","end":"2026-03-20T05:32:27.432364Z","steps":["trace[1470383602] 'agreement among raft nodes before linearized reading' (duration: 113.499842ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:27.432397Z","caller":"traceutil/trace.go:171","msg":"trace[2102772184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"274.591277ms","start":"2026-03-20T05:32:27.157786Z","end":"2026-03-20T05:32:27.432377Z","steps":["trace[2102772184] 'agreement among raft nodes before linearized reading' (duration: 274.449012ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:28.799419Z","caller":"traceutil/trace.go:171","msg":"trace[1814370633] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"143.317483ms","start":"2026-03-20T05:32:28.656069Z","end":"2026-03-20T05:32:28.799386Z","steps":["trace[1814370633] 'read index received' (duration: 143.061675ms)","trace[1814370633] 'applied index is now lower than readState.Index' (duration: 254.728µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:28.799594Z","caller":"traceutil/trace.go:171","msg":"trace[214318605] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"529.510803ms","start":"2026-03-20T05:32:28.270056Z","end":"2026-03-20T05:32:28.799567Z","steps":["trace[214318605] 'process raft request' (duration: 529.161553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:28.79963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.54695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T05:32:28.799669Z","caller":"traceutil/trace.go:171","msg":"trace[662115835] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:517; }","duration":"143.604162ms","start":"2026-03-20T05:32:28.656052Z","end":"2026-03-20T05:32:28.799656Z","steps":["trace[662115835] 'agreement among raft nodes before linearized reading' (duration: 143.512889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:28.800581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:28.270029Z","time spent":"529.628957ms","remote":"127.0.0.1:45058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:35.582213Z","caller":"traceutil/trace.go:171","msg":"trace[1211664466] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"120.039568ms","start":"2026-03-20T05:32:35.462151Z","end":"2026-03-20T05:32:35.582191Z","steps":["trace[1211664466] 'read index received' (duration: 119.899533ms)","trace[1211664466] 'applied index is now lower than readState.Index' (duration: 139.025µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:35.58234Z","caller":"traceutil/trace.go:171","msg":"trace[299250337] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"169.790465ms","start":"2026-03-20T05:32:35.412499Z","end":"2026-03-20T05:32:35.58229Z","steps":["trace[299250337] 'process raft request' (duration: 169.557678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:35.582518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.359018ms","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-03-20T05:32:35.582567Z","caller":"traceutil/trace.go:171","msg":"trace[643579206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"120.468691ms","start":"2026-03-20T05:32:35.462086Z","end":"2026-03-20T05:32:35.582555Z","steps":["trace[643579206] 'agreement among raft nodes before linearized reading' (duration: 120.316406ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:35.582726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.027568ms","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-03-20T05:32:35.58284Z","caller":"traceutil/trace.go:171","msg":"trace[2097014610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"114.170522ms","start":"2026-03-20T05:32:35.468644Z","end":"2026-03-20T05:32:35.582815Z","steps":["trace[2097014610] 'agreement among raft nodes before linearized reading' (duration: 113.919505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:35.582958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.642686ms","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-03-20T05:32:35.583069Z","caller":"traceutil/trace.go:171","msg":"trace[1125063957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"113.793791ms","start":"2026-03-20T05:32:35.469252Z","end":"2026-03-20T05:32:35.583046Z","steps":["trace[1125063957] 'agreement among raft nodes before linearized reading' (duration: 113.471182ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:35.788464Z","caller":"traceutil/trace.go:171","msg":"trace[2060597451] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"200.936585ms","start":"2026-03-20T05:32:35.587502Z","end":"2026-03-20T05:32:35.788439Z","steps":["trace[2060597451] 'process raft request' (duration: 187.858994ms)","trace[2060597451] 'compare' (duration: 12.959207ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:35.805542Z","caller":"traceutil/trace.go:171","msg":"trace[32000725] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"216.904872ms","start":"2026-03-20T05:32:35.588613Z","end":"2026-03-20T05:32:35.805518Z","steps":["trace[32000725] 'process raft request' (duration: 216.677145ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:35.805906Z","caller":"traceutil/trace.go:171","msg":"trace[1056423987] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"216.354115ms","start":"2026-03-20T05:32:35.589533Z","end":"2026-03-20T05:32:35.805887Z","steps":["trace[1056423987] 'process raft request' (duration: 215.944253ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:35.805913Z","caller":"traceutil/trace.go:171","msg":"trace[521022557] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:552; }","duration":"167.822565ms","start":"2026-03-20T05:32:35.638047Z","end":"2026-03-20T05:32:35.80587Z","steps":["trace[521022557] 'read index received' (duration: 137.331513ms)","trace[521022557] 'applied index is now lower than readState.Index' (duration: 30.484561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:35.806279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.202876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-thb7s\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-20T05:32:35.806328Z","caller":"traceutil/trace.go:171","msg":"trace[541393560] range","detail":"{range_begin:/registry/pods/kube-system/cilium-thb7s; range_end:; response_count:1; response_revision:538; }","duration":"168.312059ms","start":"2026-03-20T05:32:35.638001Z","end":"2026-03-20T05:32:35.806313Z","steps":["trace[541393560] 'agreement among raft nodes before linearized reading' (duration: 168.0156ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:36.819534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478311220806556,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:32:37.046301Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.164566695s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:32:37.04701Z","caller":"traceutil/trace.go:171","msg":"trace[1365659074] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"727.903924ms","start":"2026-03-20T05:32:36.318936Z","end":"2026-03-20T05:32:37.04684Z","steps":["trace[1365659074] 'read index received' (duration: 727.639816ms)","trace[1365659074] 'applied index is now lower than readState.Index' (duration: 262.838µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:37.047043Z","caller":"traceutil/trace.go:171","msg":"trace[2031411047] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.22394147s","start":"2026-03-20T05:32:35.823051Z","end":"2026-03-20T05:32:37.046992Z","steps":["trace[2031411047] 'process raft request' (duration: 1.22362015s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:37.047895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:35.823025Z","time spent":"1.224755324s","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:37.047249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.283456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:37.048269Z","caller":"traceutil/trace.go:171","msg":"trace[869765799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"729.358798ms","start":"2026-03-20T05:32:36.31888Z","end":"2026-03-20T05:32:37.048239Z","steps":["trace[869765799] 'agreement among raft nodes before linearized reading' (duration: 728.251615ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:37.048333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:36.31886Z","time spent":"729.459401ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:32:38.452759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.968226ms","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-03-20T05:32:38.452833Z","caller":"traceutil/trace.go:171","msg":"trace[1002602036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"643.115271ms","start":"2026-03-20T05:32:37.8097Z","end":"2026-03-20T05:32:38.452816Z","steps":["trace[1002602036] 'range keys from in-memory index tree' (duration: 642.766831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.45287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:37.809679Z","time spent":"643.182543ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:32:38.452972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.523853ms","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-03-20T05:32:38.453169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:37.885041Z","time spent":"568.12165ms","remote":"127.0.0.1:56690","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-20T05:32:38.453507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.303802ms","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-03-20T05:32:38.453549Z","caller":"traceutil/trace.go:171","msg":"trace[901841718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"866.392804ms","start":"2026-03-20T05:32:37.587142Z","end":"2026-03-20T05:32:38.453535Z","steps":["trace[901841718] 'range keys from in-memory index tree' (duration: 866.153607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.453581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:37.587122Z","time spent":"866.452497ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T05:32:38.45304Z","caller":"traceutil/trace.go:171","msg":"trace[708396162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"642.603966ms","start":"2026-03-20T05:32:37.810417Z","end":"2026-03-20T05:32:38.453021Z","steps":["trace[708396162] 'range keys from in-memory index tree' (duration: 642.427571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.453731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.191112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-20T05:32:38.453747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:37.810409Z","time spent":"643.318037ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-20T05:32:38.453797Z","caller":"traceutil/trace.go:171","msg":"trace[1427859263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"660.401848ms","start":"2026-03-20T05:32:37.793376Z","end":"2026-03-20T05:32:38.453778Z","steps":["trace[1427859263] 'range keys from in-memory index tree' (duration: 658.891782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:38.453874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:37.793358Z","time spent":"660.498451ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:32:38.820427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478311220806563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:32:39.320715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478311220806563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:32:39.527534Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07173871s","expected-duration":"1s"} {"level":"info","ts":"2026-03-20T05:32:39.528004Z","caller":"traceutil/trace.go:171","msg":"trace[469459914] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.515830143s","start":"2026-03-20T05:32:38.01214Z","end":"2026-03-20T05:32:39.52797Z","steps":["trace[469459914] 'process raft request' (duration: 1.515613547s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:39.528241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.012119Z","time spent":"1.515984618s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:39.580245Z","caller":"traceutil/trace.go:171","msg":"trace[397604626] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.12157789s","start":"2026-03-20T05:32:38.458637Z","end":"2026-03-20T05:32:39.580215Z","steps":["trace[397604626] 'process raft request' (duration: 1.121400395s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:39.580263Z","caller":"traceutil/trace.go:171","msg":"trace[468094419] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.121674763s","start":"2026-03-20T05:32:38.458522Z","end":"2026-03-20T05:32:39.580197Z","steps":["trace[468094419] 'process raft request' (duration: 1.101716987s)","trace[468094419] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 19.587726ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:39.580376Z","caller":"traceutil/trace.go:171","msg":"trace[1633517824] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:556; }","duration":"1.260391039s","start":"2026-03-20T05:32:38.319968Z","end":"2026-03-20T05:32:39.580359Z","steps":["trace[1633517824] 'read index received' (duration: 1.207740485s)","trace[1633517824] 'applied index is now lower than readState.Index' (duration: 52.649704ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:39.580436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.458631Z","time spent":"1.121699073s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:39.580446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.458505Z","time spent":"1.121841158s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:39.580447Z","caller":"traceutil/trace.go:171","msg":"trace[2093178672] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.120671803s","start":"2026-03-20T05:32:38.459748Z","end":"2026-03-20T05:32:39.580419Z","steps":["trace[2093178672] 'process raft request' (duration: 1.120428226s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:39.580488Z","caller":"traceutil/trace.go:171","msg":"trace[932333318] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.120577111s","start":"2026-03-20T05:32:38.4599Z","end":"2026-03-20T05:32:39.580477Z","steps":["trace[932333318] 'process raft request' (duration: 1.120393475s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:39.580601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.459893Z","time spent":"1.120622482s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:39.580618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.459736Z","time spent":"1.120775966s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:39.58069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.919052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-20T05:32:39.580686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260717139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:39.58073Z","caller":"traceutil/trace.go:171","msg":"trace[1027263315] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:544; }","duration":"923.999065ms","start":"2026-03-20T05:32:38.656717Z","end":"2026-03-20T05:32:39.580717Z","steps":["trace[1027263315] 'agreement among raft nodes before linearized reading' (duration: 923.865701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:39.580771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.656695Z","time spent":"924.069806ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"info","ts":"2026-03-20T05:32:39.580768Z","caller":"traceutil/trace.go:171","msg":"trace[896885384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"1.260808761s","start":"2026-03-20T05:32:38.319919Z","end":"2026-03-20T05:32:39.580728Z","steps":["trace[896885384] 'agreement among raft nodes before linearized reading' (duration: 1.260718329s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:39.580815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:38.319901Z","time spent":"1.260900735s","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:32:41.807714Z","caller":"traceutil/trace.go:171","msg":"trace[876681567] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"123.605254ms","start":"2026-03-20T05:32:41.684084Z","end":"2026-03-20T05:32:41.807689Z","steps":["trace[876681567] 'process raft request' (duration: 123.550093ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:41.808034Z","caller":"traceutil/trace.go:171","msg":"trace[147792684] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"133.091948ms","start":"2026-03-20T05:32:41.67491Z","end":"2026-03-20T05:32:41.808002Z","steps":["trace[147792684] 'process raft request' (duration: 76.788275ms)","trace[147792684] 'compare' (duration: 55.752276ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:42.223006Z","caller":"traceutil/trace.go:171","msg":"trace[1168833454] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"190.523534ms","start":"2026-03-20T05:32:42.032459Z","end":"2026-03-20T05:32:42.222982Z","steps":["trace[1168833454] 'process raft request' (duration: 190.37472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:44.991847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.078594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-03-20T05:32:44.991923Z","caller":"traceutil/trace.go:171","msg":"trace[1264987622] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"161.196677ms","start":"2026-03-20T05:32:44.830707Z","end":"2026-03-20T05:32:44.991903Z","steps":["trace[1264987622] 'range keys from in-memory index tree' (duration: 160.93393ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.369184Z","caller":"traceutil/trace.go:171","msg":"trace[1034740661] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"139.84165ms","start":"2026-03-20T05:32:45.229324Z","end":"2026-03-20T05:32:45.369166Z","steps":["trace[1034740661] 'process raft request' (duration: 131.060747ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.398385Z","caller":"traceutil/trace.go:171","msg":"trace[171112599] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"138.322514ms","start":"2026-03-20T05:32:45.260043Z","end":"2026-03-20T05:32:45.398365Z","steps":["trace[171112599] 'process raft request' (duration: 138.015985ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.572298Z","caller":"traceutil/trace.go:171","msg":"trace[204985675] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"102.173254ms","start":"2026-03-20T05:32:45.470103Z","end":"2026-03-20T05:32:45.572276Z","steps":["trace[204985675] 'process raft request' (duration: 101.431542ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.572734Z","caller":"traceutil/trace.go:171","msg":"trace[1040201292] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"118.374548ms","start":"2026-03-20T05:32:45.454324Z","end":"2026-03-20T05:32:45.572698Z","steps":["trace[1040201292] 'process raft request' (duration: 58.936951ms)","trace[1040201292] 'compare' (duration: 57.929652ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:45.776377Z","caller":"traceutil/trace.go:171","msg":"trace[1954343342] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"138.844569ms","start":"2026-03-20T05:32:45.637506Z","end":"2026-03-20T05:32:45.77635Z","steps":["trace[1954343342] 'process raft request' (duration: 134.015055ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.776464Z","caller":"traceutil/trace.go:171","msg":"trace[1210984250] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"138.142599ms","start":"2026-03-20T05:32:45.638297Z","end":"2026-03-20T05:32:45.77644Z","steps":["trace[1210984250] 'process raft request' (duration: 138.004875ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.776499Z","caller":"traceutil/trace.go:171","msg":"trace[913062794] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"138.091157ms","start":"2026-03-20T05:32:45.6384Z","end":"2026-03-20T05:32:45.776492Z","steps":["trace[913062794] 'process raft request' (duration: 137.942513ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.776524Z","caller":"traceutil/trace.go:171","msg":"trace[952321018] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"137.953523ms","start":"2026-03-20T05:32:45.638565Z","end":"2026-03-20T05:32:45.776519Z","steps":["trace[952321018] 'process raft request' (duration: 137.815639ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.776478Z","caller":"traceutil/trace.go:171","msg":"trace[130925307] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"138.663254ms","start":"2026-03-20T05:32:45.637783Z","end":"2026-03-20T05:32:45.776447Z","steps":["trace[130925307] 'process raft request' (duration: 138.471978ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.776515Z","caller":"traceutil/trace.go:171","msg":"trace[1996980882] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"137.376295ms","start":"2026-03-20T05:32:45.639109Z","end":"2026-03-20T05:32:45.776486Z","steps":["trace[1996980882] 'process raft request' (duration: 137.337544ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:45.781524Z","caller":"traceutil/trace.go:171","msg":"trace[1989715811] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"117.951245ms","start":"2026-03-20T05:32:45.663553Z","end":"2026-03-20T05:32:45.781504Z","steps":["trace[1989715811] 'process raft request' (duration: 116.241774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:51.082612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.888884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:32:51.082686Z","caller":"traceutil/trace.go:171","msg":"trace[112025842] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"200.08441ms","start":"2026-03-20T05:32:50.88259Z","end":"2026-03-20T05:32:51.082675Z","steps":["trace[112025842] 'read index received' (duration: 1.900837ms)","trace[112025842] 'applied index is now lower than readState.Index' (duration: 198.182443ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:51.082824Z","caller":"traceutil/trace.go:171","msg":"trace[2013016879] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"236.168048ms","start":"2026-03-20T05:32:50.846644Z","end":"2026-03-20T05:32:51.082812Z","steps":["trace[2013016879] 'process raft request' (duration: 37.852381ms)","trace[2013016879] 'compare' (duration: 197.689608ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:51.083148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.561685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-thb7s\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-03-20T05:32:51.083178Z","caller":"traceutil/trace.go:171","msg":"trace[101975609] range","detail":"{range_begin:/registry/pods/kube-system/cilium-thb7s; range_end:; response_count:1; response_revision:667; }","duration":"200.632727ms","start":"2026-03-20T05:32:50.882538Z","end":"2026-03-20T05:32:51.083171Z","steps":["trace[101975609] 'agreement among raft nodes before linearized reading' (duration: 200.168333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:51.388801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.970163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:32:51.388966Z","caller":"traceutil/trace.go:171","msg":"trace[717804419] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"286.638517ms","start":"2026-03-20T05:32:51.102308Z","end":"2026-03-20T05:32:51.388946Z","steps":["trace[717804419] 'process raft request' (duration: 144.446287ms)","trace[717804419] 'compare' (duration: 141.852979ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:51.388993Z","caller":"traceutil/trace.go:171","msg":"trace[2021648551] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"212.073078ms","start":"2026-03-20T05:32:51.176899Z","end":"2026-03-20T05:32:51.388972Z","steps":["trace[2021648551] 'process raft request' (duration: 211.992696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.209163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478311220806908,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-20T05:32:58.39101Z","caller":"traceutil/trace.go:171","msg":"trace[923903214] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"682.214753ms","start":"2026-03-20T05:32:57.708772Z","end":"2026-03-20T05:32:58.390987Z","steps":["trace[923903214] 'read index received' (duration: 682.064659ms)","trace[923903214] 'applied index is now lower than readState.Index' (duration: 149.064µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:58.391146Z","caller":"traceutil/trace.go:171","msg":"trace[611092150] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"761.517904ms","start":"2026-03-20T05:32:57.629618Z","end":"2026-03-20T05:32:58.391136Z","steps":["trace[611092150] 'process raft request' (duration: 761.222965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.391258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:57.629592Z","time spent":"761.581186ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:58.391317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.297492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:58.391449Z","caller":"traceutil/trace.go:171","msg":"trace[666509809] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:728; }","duration":"573.487757ms","start":"2026-03-20T05:32:57.817942Z","end":"2026-03-20T05:32:58.391429Z","steps":["trace[666509809] 'agreement among raft nodes before linearized reading' (duration: 573.311682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.391495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:57.817919Z","time spent":"573.56623ms","remote":"127.0.0.1:45148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:32:58.391762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.734431ms","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-03-20T05:32:58.391811Z","caller":"traceutil/trace.go:171","msg":"trace[506986833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"544.797433ms","start":"2026-03-20T05:32:57.847002Z","end":"2026-03-20T05:32:58.391799Z","steps":["trace[506986833] 'agreement among raft nodes before linearized reading' (duration: 544.646308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.391821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.628105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-20T05:32:58.391842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:57.846993Z","time spent":"544.842384ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T05:32:58.391859Z","caller":"traceutil/trace.go:171","msg":"trace[1767904587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"546.733238ms","start":"2026-03-20T05:32:57.845115Z","end":"2026-03-20T05:32:58.391848Z","steps":["trace[1767904587] 'agreement among raft nodes before linearized reading' (duration: 546.576083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.39189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:57.845088Z","time spent":"546.795269ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:32:58.391889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.096978ms","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-03-20T05:32:58.391969Z","caller":"traceutil/trace.go:171","msg":"trace[618280864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"683.227202ms","start":"2026-03-20T05:32:57.708722Z","end":"2026-03-20T05:32:58.391949Z","steps":["trace[618280864] 'agreement among raft nodes before linearized reading' (duration: 682.963154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.39195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.173242ms","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-03-20T05:32:58.39201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:57.708705Z","time spent":"683.297714ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:32:58.392038Z","caller":"traceutil/trace.go:171","msg":"trace[93669211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"565.296865ms","start":"2026-03-20T05:32:57.826721Z","end":"2026-03-20T05:32:58.392018Z","steps":["trace[93669211] 'agreement among raft nodes before linearized reading' (duration: 564.999616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:58.392088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:57.82671Z","time spent":"565.369006ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:32:59.262786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.42147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:32:59.262918Z","caller":"traceutil/trace.go:171","msg":"trace[1722038846] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"857.20571ms","start":"2026-03-20T05:32:58.405683Z","end":"2026-03-20T05:32:59.262888Z","steps":["trace[1722038846] 'process raft request' (duration: 682.597914ms)","trace[1722038846] 'compare' (duration: 174.281406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:59.262986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.405665Z","time spent":"857.291693ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:59.480899Z","caller":"traceutil/trace.go:171","msg":"trace[590702420] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.074961121s","start":"2026-03-20T05:32:58.405911Z","end":"2026-03-20T05:32:59.480872Z","steps":["trace[590702420] 'process raft request' (duration: 1.074888349s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:32:59.480992Z","caller":"traceutil/trace.go:171","msg":"trace[1316666281] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.075043913s","start":"2026-03-20T05:32:58.405868Z","end":"2026-03-20T05:32:59.480911Z","steps":["trace[1316666281] 'process raft request' (duration: 1.063452584s)","trace[1316666281] 'compare' (duration: 11.352512ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:32:59.481045Z","caller":"traceutil/trace.go:171","msg":"trace[754926226] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:750; }","duration":"823.463582ms","start":"2026-03-20T05:32:58.657555Z","end":"2026-03-20T05:32:59.481019Z","steps":["trace[754926226] 'read index received' (duration: 430.735615ms)","trace[754926226] 'applied index is now lower than readState.Index' (duration: 392.725687ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:32:59.481071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.405906Z","time spent":"1.075070914s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:32:59.481162Z","caller":"traceutil/trace.go:171","msg":"trace[507904108] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.075168697s","start":"2026-03-20T05:32:58.405978Z","end":"2026-03-20T05:32:59.481147Z","steps":["trace[507904108] 'process raft request' (duration: 1.074858778s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:59.481239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.405861Z","time spent":"1.075239098s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:59.481418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.872414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T05:32:59.481469Z","caller":"traceutil/trace.go:171","msg":"trace[1259750189] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:732; }","duration":"823.963296ms","start":"2026-03-20T05:32:58.657489Z","end":"2026-03-20T05:32:59.481453Z","steps":["trace[1259750189] 'agreement among raft nodes before linearized reading' (duration: 823.785961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:59.481244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.40595Z","time spent":"1.075242339s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:32:59.481525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:58.657466Z","time spent":"824.045029ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-03-20T05:32:59.482938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.938177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:32:59.482999Z","caller":"traceutil/trace.go:171","msg":"trace[1962897061] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:732; }","duration":"452.049781ms","start":"2026-03-20T05:32:59.030935Z","end":"2026-03-20T05:32:59.482984Z","steps":["trace[1962897061] 'agreement among raft nodes before linearized reading' (duration: 450.906938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:32:59.483037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:59.03092Z","time spent":"452.107762ms","remote":"127.0.0.1:45032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:33:00.804107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.643885ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:00.804181Z","caller":"traceutil/trace.go:171","msg":"trace[854796970] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"487.222648ms","start":"2026-03-20T05:33:00.316947Z","end":"2026-03-20T05:33:00.80417Z","steps":["trace[854796970] 'read index received' (duration: 169.379138ms)","trace[854796970] 'applied index is now lower than readState.Index' (duration: 317.84274ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:00.804323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.363382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:00.80439Z","caller":"traceutil/trace.go:171","msg":"trace[930937614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"487.468055ms","start":"2026-03-20T05:33:00.316905Z","end":"2026-03-20T05:33:00.804373Z","steps":["trace[930937614] 'agreement among raft nodes before linearized reading' (duration: 487.348101ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:00.804407Z","caller":"traceutil/trace.go:171","msg":"trace[481385550] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"966.775028ms","start":"2026-03-20T05:32:59.837623Z","end":"2026-03-20T05:33:00.804398Z","steps":["trace[481385550] 'process raft request' (duration: 648.763833ms)","trace[481385550] 'compare' (duration: 317.406458ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:00.804426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.316888Z","time spent":"487.531676ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:00.804454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:32:59.837603Z","time spent":"966.82631ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:00.8045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.719569ms","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-03-20T05:33:00.804613Z","caller":"traceutil/trace.go:171","msg":"trace[194640203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"408.873664ms","start":"2026-03-20T05:33:00.395722Z","end":"2026-03-20T05:33:00.804595Z","steps":["trace[194640203] 'agreement among raft nodes before linearized reading' (duration: 408.627527ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:00.804667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:00.395706Z","time spent":"408.951366ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:33:07.481019Z","caller":"traceutil/trace.go:171","msg":"trace[2090565422] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"146.89485ms","start":"2026-03-20T05:33:07.334104Z","end":"2026-03-20T05:33:07.480999Z","steps":["trace[2090565422] 'process raft request' (duration: 146.734575ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:21.082068Z","caller":"traceutil/trace.go:171","msg":"trace[1803314244] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"124.784132ms","start":"2026-03-20T05:33:20.957259Z","end":"2026-03-20T05:33:21.082043Z","steps":["trace[1803314244] 'process raft request' (duration: 124.634188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.889803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.596041ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:22.890055Z","caller":"traceutil/trace.go:171","msg":"trace[198274354] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"746.27879ms","start":"2026-03-20T05:33:22.143756Z","end":"2026-03-20T05:33:22.890035Z","steps":["trace[198274354] 'process raft request' (duration: 746.196468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.890193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:22.143734Z","time spent":"746.410295ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:22.89041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260902497s","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-03-20T05:33:22.890451Z","caller":"traceutil/trace.go:171","msg":"trace[1283296536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.26099263s","start":"2026-03-20T05:33:21.629449Z","end":"2026-03-20T05:33:22.890442Z","steps":["trace[1283296536] 'agreement among raft nodes before linearized reading' (duration: 1.260858616s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.890475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.629426Z","time spent":"1.261044171s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T05:33:22.890559Z","caller":"traceutil/trace.go:171","msg":"trace[98601659] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"1.820573653s","start":"2026-03-20T05:33:21.06944Z","end":"2026-03-20T05:33:22.890013Z","steps":["trace[98601659] 'process raft request' (duration: 956.657223ms)","trace[98601659] 'compare' (duration: 861.928403ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:22.890053Z","caller":"traceutil/trace.go:171","msg":"trace[1360198543] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"1.260515836s","start":"2026-03-20T05:33:21.629509Z","end":"2026-03-20T05:33:22.890025Z","steps":["trace[1360198543] 'read index received' (duration: 396.571246ms)","trace[1360198543] 'applied index is now lower than readState.Index' (duration: 863.94273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:22.890805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.06942Z","time spent":"1.821280852s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:22.890957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.207071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:33:22.890982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.080134ms","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-03-20T05:33:22.891013Z","caller":"traceutil/trace.go:171","msg":"trace[1196028633] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:798; }","duration":"333.153226ms","start":"2026-03-20T05:33:22.55785Z","end":"2026-03-20T05:33:22.891004Z","steps":["trace[1196028633] 'agreement among raft nodes before linearized reading' (duration: 333.098344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.891033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:22.557831Z","time spent":"333.197597ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:33:22.891193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176545833s","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-03-20T05:33:22.891236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236174995s","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-03-20T05:33:22.891239Z","caller":"traceutil/trace.go:171","msg":"trace[302289554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"1.176629635s","start":"2026-03-20T05:33:21.714598Z","end":"2026-03-20T05:33:22.891228Z","steps":["trace[302289554] 'agreement among raft nodes before linearized reading' (duration: 1.176488951s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.891254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.369481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-20T05:33:22.89126Z","caller":"traceutil/trace.go:171","msg":"trace[44181018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.236221626s","start":"2026-03-20T05:33:21.655031Z","end":"2026-03-20T05:33:22.891252Z","steps":["trace[44181018] 'agreement among raft nodes before linearized reading' (duration: 1.235856336s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.891277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.714578Z","time spent":"1.176691017s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-20T05:33:22.891301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.65502Z","time spent":"1.236276258s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-20T05:33:22.891306Z","caller":"traceutil/trace.go:171","msg":"trace[784798990] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:798; }","duration":"914.468374ms","start":"2026-03-20T05:33:21.976824Z","end":"2026-03-20T05:33:22.891292Z","steps":["trace[784798990] 'agreement among raft nodes before linearized reading' (duration: 914.190196ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.891347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.97679Z","time spent":"914.547426ms","remote":"127.0.0.1:45426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":98,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:33:22.891017Z","caller":"traceutil/trace.go:171","msg":"trace[131394843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"572.300384ms","start":"2026-03-20T05:33:22.318698Z","end":"2026-03-20T05:33:22.890998Z","steps":["trace[131394843] 'agreement among raft nodes before linearized reading' (duration: 572.210741ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.891419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:22.31868Z","time spent":"572.730565ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:22.891631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176912353s","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-03-20T05:33:22.891711Z","caller":"traceutil/trace.go:171","msg":"trace[2078966298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"1.177008776s","start":"2026-03-20T05:33:21.714672Z","end":"2026-03-20T05:33:22.891681Z","steps":["trace[2078966298] 'agreement among raft nodes before linearized reading' (duration: 1.176819351s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:22.891746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:21.714638Z","time spent":"1.177101939s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-20T05:33:23.045852Z","caller":"traceutil/trace.go:171","msg":"trace[2059365094] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"151.395797ms","start":"2026-03-20T05:33:22.894433Z","end":"2026-03-20T05:33:23.045829Z","steps":["trace[2059365094] 'process raft request' (duration: 151.273024ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.065793Z","caller":"traceutil/trace.go:171","msg":"trace[1848460596] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"170.140839ms","start":"2026-03-20T05:33:22.895628Z","end":"2026-03-20T05:33:23.065769Z","steps":["trace[1848460596] 'process raft request' (duration: 169.985625ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.066157Z","caller":"traceutil/trace.go:171","msg":"trace[917095414] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"168.74723ms","start":"2026-03-20T05:33:22.897395Z","end":"2026-03-20T05:33:23.066142Z","steps":["trace[917095414] 'process raft request' (duration: 168.652797ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:23.066171Z","caller":"traceutil/trace.go:171","msg":"trace[776120355] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"169.287815ms","start":"2026-03-20T05:33:22.89686Z","end":"2026-03-20T05:33:23.066148Z","steps":["trace[776120355] 'process raft request' (duration: 168.872723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:24.380312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.615122ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-20T05:33:25.301499Z","caller":"traceutil/trace.go:171","msg":"trace[1616946772] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"190.386663ms","start":"2026-03-20T05:33:25.111088Z","end":"2026-03-20T05:33:25.301475Z","steps":["trace[1616946772] 'process raft request' (duration: 190.188118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:25.67962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.536677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:25.679858Z","caller":"traceutil/trace.go:171","msg":"trace[1372578564] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:834; }","duration":"361.350607ms","start":"2026-03-20T05:33:25.318489Z","end":"2026-03-20T05:33:25.67984Z","steps":["trace[1372578564] 'read index received' (duration: 250.49027ms)","trace[1372578564] 'applied index is now lower than readState.Index' (duration: 110.859366ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:25.679946Z","caller":"traceutil/trace.go:171","msg":"trace[1421881363] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"568.092284ms","start":"2026-03-20T05:33:25.111843Z","end":"2026-03-20T05:33:25.679936Z","steps":["trace[1421881363] 'process raft request' (duration: 567.95621ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:25.679941Z","caller":"traceutil/trace.go:171","msg":"trace[424444909] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"568.63027ms","start":"2026-03-20T05:33:25.111295Z","end":"2026-03-20T05:33:25.679925Z","steps":["trace[424444909] 'process raft request' (duration: 568.424804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:25.680009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:25.111818Z","time spent":"568.152486ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:25.680026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:25.111287Z","time spent":"568.690351ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:33:25.680207Z","caller":"traceutil/trace.go:171","msg":"trace[1124530594] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"569.048142ms","start":"2026-03-20T05:33:25.111147Z","end":"2026-03-20T05:33:25.680195Z","steps":["trace[1124530594] 'process raft request' (duration: 457.807085ms)","trace[1124530594] 'compare' (duration: 110.30246ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:25.680257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:25.111129Z","time spent":"569.100833ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:25.680503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.997665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:25.680618Z","caller":"traceutil/trace.go:171","msg":"trace[426422428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"362.142089ms","start":"2026-03-20T05:33:25.318459Z","end":"2026-03-20T05:33:25.680601Z","steps":["trace[426422428] 'agreement among raft nodes before linearized reading' (duration: 361.997785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:25.680657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:25.318445Z","time spent":"362.20131ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:33:26.194484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.769899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:26.19454Z","caller":"traceutil/trace.go:171","msg":"trace[313443400] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:808; }","duration":"354.887403ms","start":"2026-03-20T05:33:25.839642Z","end":"2026-03-20T05:33:26.194529Z","steps":["trace[313443400] 'count revisions from in-memory index tree' (duration: 354.644206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:26.194564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:25.839618Z","time spent":"354.940614ms","remote":"127.0.0.1:45160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-03-20T05:33:27.455967Z","caller":"traceutil/trace.go:171","msg":"trace[1070815055] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"150.110911ms","start":"2026-03-20T05:33:27.30583Z","end":"2026-03-20T05:33:27.455941Z","steps":["trace[1070815055] 'read index received' (duration: 149.944586ms)","trace[1070815055] 'applied index is now lower than readState.Index' (duration: 165.105µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:27.456084Z","caller":"traceutil/trace.go:171","msg":"trace[2088767333] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"340.524544ms","start":"2026-03-20T05:33:27.115548Z","end":"2026-03-20T05:33:27.456073Z","steps":["trace[2088767333] 'process raft request' (duration: 340.244466ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.456183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:27.115526Z","time spent":"340.586146ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:27.456271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.127899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:27.456365Z","caller":"traceutil/trace.go:171","msg":"trace[1168975673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"139.294393ms","start":"2026-03-20T05:33:27.317051Z","end":"2026-03-20T05:33:27.456346Z","steps":["trace[1168975673] 'agreement among raft nodes before linearized reading' (duration: 139.101568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.456572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.721638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-20T05:33:27.456625Z","caller":"traceutil/trace.go:171","msg":"trace[1336588926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"150.804081ms","start":"2026-03-20T05:33:27.305809Z","end":"2026-03-20T05:33:27.456613Z","steps":["trace[1336588926] 'agreement among raft nodes before linearized reading' (duration: 150.381919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.819771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.311247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:27.819869Z","caller":"traceutil/trace.go:171","msg":"trace[1868494266] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"134.831776ms","start":"2026-03-20T05:33:27.685021Z","end":"2026-03-20T05:33:27.819853Z","steps":["trace[1868494266] 'read index received' (duration: 44.831µs)","trace[1868494266] 'applied index is now lower than readState.Index' (duration: 134.785795ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:27.819953Z","caller":"traceutil/trace.go:171","msg":"trace[4387616] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"358.106354ms","start":"2026-03-20T05:33:27.461836Z","end":"2026-03-20T05:33:27.819942Z","steps":["trace[4387616] 'process raft request' (duration: 164.54483ms)","trace[4387616] 'compare' (duration: 193.204854ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:27.820115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:27.46181Z","time spent":"358.176296ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:27.820273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.164276ms","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-03-20T05:33:27.820368Z","caller":"traceutil/trace.go:171","msg":"trace[1126654029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"135.338162ms","start":"2026-03-20T05:33:27.685012Z","end":"2026-03-20T05:33:27.82035Z","steps":["trace[1126654029] 'agreement among raft nodes before linearized reading' (duration: 135.027373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.820683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.65841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-20T05:33:27.820739Z","caller":"traceutil/trace.go:171","msg":"trace[1748100616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"135.748823ms","start":"2026-03-20T05:33:27.684979Z","end":"2026-03-20T05:33:27.820728Z","steps":["trace[1748100616] 'agreement among raft nodes before linearized reading' (duration: 135.599949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:27.820868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.735882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-20T05:33:27.820999Z","caller":"traceutil/trace.go:171","msg":"trace[1969229306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"135.890717ms","start":"2026-03-20T05:33:27.685085Z","end":"2026-03-20T05:33:27.820975Z","steps":["trace[1969229306] 'agreement among raft nodes before linearized reading' (duration: 135.520177ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:28.047575Z","caller":"traceutil/trace.go:171","msg":"trace[866173062] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"222.622239ms","start":"2026-03-20T05:33:27.824927Z","end":"2026-03-20T05:33:28.047549Z","steps":["trace[866173062] 'process raft request' (duration: 127.100178ms)","trace[866173062] 'compare' (duration: 95.201862ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:28.047575Z","caller":"traceutil/trace.go:171","msg":"trace[1234543173] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"222.33069ms","start":"2026-03-20T05:33:27.82522Z","end":"2026-03-20T05:33:28.047551Z","steps":["trace[1234543173] 'process raft request' (duration: 222.235278ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:28.047648Z","caller":"traceutil/trace.go:171","msg":"trace[1958441864] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"222.585678ms","start":"2026-03-20T05:33:27.825046Z","end":"2026-03-20T05:33:28.047632Z","steps":["trace[1958441864] 'process raft request' (duration: 222.458214ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:32.202201Z","caller":"traceutil/trace.go:171","msg":"trace[682925788] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"124.180344ms","start":"2026-03-20T05:33:32.077996Z","end":"2026-03-20T05:33:32.202176Z","steps":["trace[682925788] 'process raft request' (duration: 124.01925ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:36.211897Z","caller":"traceutil/trace.go:171","msg":"trace[1388535183] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"100.77191ms","start":"2026-03-20T05:33:36.111098Z","end":"2026-03-20T05:33:36.21187Z","steps":["trace[1388535183] 'process raft request' (duration: 100.616826ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:40.426763Z","caller":"traceutil/trace.go:171","msg":"trace[1102967890] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:929; }","duration":"133.16896ms","start":"2026-03-20T05:33:40.293561Z","end":"2026-03-20T05:33:40.42673Z","steps":["trace[1102967890] 'read index received' (duration: 67.254789ms)","trace[1102967890] 'applied index is now lower than readState.Index' (duration: 65.913001ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:40.426931Z","caller":"traceutil/trace.go:171","msg":"trace[175029540] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"136.872615ms","start":"2026-03-20T05:33:40.290015Z","end":"2026-03-20T05:33:40.426888Z","steps":["trace[175029540] 'process raft request' (duration: 107.341907ms)","trace[175029540] 'compare' (duration: 29.204439ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:40.427096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.695395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:40.427239Z","caller":"traceutil/trace.go:171","msg":"trace[759199033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"108.88233ms","start":"2026-03-20T05:33:40.318335Z","end":"2026-03-20T05:33:40.427217Z","steps":["trace[759199033] 'agreement among raft nodes before linearized reading' (duration: 108.683275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:40.42753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.948641ms","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-03-20T05:33:40.427592Z","caller":"traceutil/trace.go:171","msg":"trace[76923907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"134.062305ms","start":"2026-03-20T05:33:40.293514Z","end":"2026-03-20T05:33:40.427576Z","steps":["trace[76923907] 'agreement among raft nodes before linearized reading' (duration: 133.376335ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:40.876101Z","caller":"traceutil/trace.go:171","msg":"trace[1424260471] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"173.3158ms","start":"2026-03-20T05:33:40.702756Z","end":"2026-03-20T05:33:40.876071Z","steps":["trace[1424260471] 'process raft request' (duration: 173.153555ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:42.334462Z","caller":"traceutil/trace.go:171","msg":"trace[2097063944] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"163.943023ms","start":"2026-03-20T05:33:42.170495Z","end":"2026-03-20T05:33:42.334438Z","steps":["trace[2097063944] 'read index received' (duration: 163.729497ms)","trace[2097063944] 'applied index is now lower than readState.Index' (duration: 212.586µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:42.334711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.19944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-20T05:33:42.33476Z","caller":"traceutil/trace.go:171","msg":"trace[244460115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"164.302924ms","start":"2026-03-20T05:33:42.170442Z","end":"2026-03-20T05:33:42.334745Z","steps":["trace[244460115] 'agreement among raft nodes before linearized reading' (duration: 164.136869ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:42.334575Z","caller":"traceutil/trace.go:171","msg":"trace[1238348171] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"344.336563ms","start":"2026-03-20T05:33:41.990218Z","end":"2026-03-20T05:33:42.334554Z","steps":["trace[1238348171] 'process raft request' (duration: 343.990214ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:42.335295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:41.990202Z","time spent":"345.030593ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:43.45559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.962605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:43.455681Z","caller":"traceutil/trace.go:171","msg":"trace[669922074] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:939; }","duration":"136.895836ms","start":"2026-03-20T05:33:43.31877Z","end":"2026-03-20T05:33:43.455666Z","steps":["trace[669922074] 'read index received' (duration: 16.814878ms)","trace[669922074] 'applied index is now lower than readState.Index' (duration: 120.079568ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:43.455733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.968798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:43.45576Z","caller":"traceutil/trace.go:171","msg":"trace[1521028219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:906; }","duration":"137.03055ms","start":"2026-03-20T05:33:43.318722Z","end":"2026-03-20T05:33:43.455753Z","steps":["trace[1521028219] 'agreement among raft nodes before linearized reading' (duration: 136.988799ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:43.455927Z","caller":"traceutil/trace.go:171","msg":"trace[624474322] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"203.806115ms","start":"2026-03-20T05:33:43.25209Z","end":"2026-03-20T05:33:43.455896Z","steps":["trace[624474322] 'process raft request' (duration: 83.470469ms)","trace[624474322] 'compare' (duration: 119.594235ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:44.014016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.856423ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:44.014159Z","caller":"traceutil/trace.go:171","msg":"trace[1812601201] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:906; }","duration":"422.042748ms","start":"2026-03-20T05:33:43.592097Z","end":"2026-03-20T05:33:44.01414Z","steps":["trace[1812601201] 'range keys from in-memory index tree' (duration: 421.834782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:44.014404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.594176ms","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-03-20T05:33:44.014485Z","caller":"traceutil/trace.go:171","msg":"trace[2129037660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"396.72529ms","start":"2026-03-20T05:33:43.617739Z","end":"2026-03-20T05:33:44.014465Z","steps":["trace[2129037660] 'range keys from in-memory index tree' (duration: 396.429071ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:44.014518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:43.617724Z","time spent":"396.787011ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-20T05:33:46.459441Z","caller":"traceutil/trace.go:171","msg":"trace[1737955203] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"138.950644ms","start":"2026-03-20T05:33:46.320475Z","end":"2026-03-20T05:33:46.459426Z","steps":["trace[1737955203] 'read index received' (duration: 138.8247ms)","trace[1737955203] 'applied index is now lower than readState.Index' (duration: 125.344µs)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:33:46.459678Z","caller":"traceutil/trace.go:171","msg":"trace[1833695701] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"391.671337ms","start":"2026-03-20T05:33:46.067999Z","end":"2026-03-20T05:33:46.45967Z","steps":["trace[1833695701] 'process raft request' (duration: 391.343687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:46.459758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.067987Z","time spent":"391.716868ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:46.459912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.435567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:33:46.45994Z","caller":"traceutil/trace.go:171","msg":"trace[245067549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"139.499989ms","start":"2026-03-20T05:33:46.320434Z","end":"2026-03-20T05:33:46.459934Z","steps":["trace[245067549] 'agreement among raft nodes before linearized reading' (duration: 139.446938ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:33:46.568993Z","caller":"traceutil/trace.go:171","msg":"trace[334365968] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"100.663987ms","start":"2026-03-20T05:33:46.468293Z","end":"2026-03-20T05:33:46.568957Z","steps":["trace[334365968] 'process raft request' (duration: 100.378689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:33:46.936569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.569723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:46.93667Z","caller":"traceutil/trace.go:171","msg":"trace[802102938] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"364.468875ms","start":"2026-03-20T05:33:46.572187Z","end":"2026-03-20T05:33:46.936656Z","steps":["trace[802102938] 'process raft request' (duration: 238.710196ms)","trace[802102938] 'compare' (duration: 125.389369ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:46.936714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.572172Z","time spent":"364.522966ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:33:47.579327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.473739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:33:47.579413Z","caller":"traceutil/trace.go:171","msg":"trace[798353551] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"1.004892961s","start":"2026-03-20T05:33:46.574505Z","end":"2026-03-20T05:33:47.579398Z","steps":["trace[798353551] 'process raft request' (duration: 637.285778ms)","trace[798353551] 'compare' (duration: 367.175821ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:33:47.579453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:46.574474Z","time spent":"1.004960013s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:00.183898Z","caller":"traceutil/trace.go:171","msg":"trace[392592314] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"541.629888ms","start":"2026-03-20T05:33:59.642243Z","end":"2026-03-20T05:34:00.183873Z","steps":["trace[392592314] 'process raft request' (duration: 541.493564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:00.18405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:33:59.642215Z","time spent":"541.761932ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:03.757385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.912432ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:03.757472Z","caller":"traceutil/trace.go:171","msg":"trace[1249429081] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:978; }","duration":"165.031505ms","start":"2026-03-20T05:34:03.592429Z","end":"2026-03-20T05:34:03.757461Z","steps":["trace[1249429081] 'range keys from in-memory index tree' (duration: 164.901841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:05.566371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478311220807555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-20T05:34:05.775927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.029418496s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-20T05:34:06.017337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.09516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:06.017406Z","caller":"traceutil/trace.go:171","msg":"trace[2058244354] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"951.312953ms","start":"2026-03-20T05:34:05.066082Z","end":"2026-03-20T05:34:06.017395Z","steps":["trace[2058244354] 'read index received' (duration: 710.040557ms)","trace[2058244354] 'applied index is now lower than readState.Index' (duration: 241.271746ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:06.017477Z","caller":"traceutil/trace.go:171","msg":"trace[457802355] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"1.310756031s","start":"2026-03-20T05:34:04.706697Z","end":"2026-03-20T05:34:06.017453Z","steps":["trace[457802355] 'process raft request' (duration: 1.069497986s)","trace[457802355] 'compare' (duration: 240.941056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:06.017538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.451766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-20T05:34:06.017561Z","caller":"traceutil/trace.go:171","msg":"trace[362228384] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"951.502928ms","start":"2026-03-20T05:34:05.066052Z","end":"2026-03-20T05:34:06.017555Z","steps":["trace[362228384] 'agreement among raft nodes before linearized reading' (duration: 951.399725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:06.017577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:05.066038Z","time spent":"951.535379ms","remote":"127.0.0.1:45126","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-03-20T05:34:06.017591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:04.706659Z","time spent":"1.310886305s","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:34:06.017688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.322256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:06.017706Z","caller":"traceutil/trace.go:171","msg":"trace[699243450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"699.367907ms","start":"2026-03-20T05:34:05.318333Z","end":"2026-03-20T05:34:06.017701Z","steps":["trace[699243450] 'agreement among raft nodes before linearized reading' (duration: 699.341286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:06.017717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:05.318315Z","time spent":"699.400028ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:07.323801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.175981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:07.323939Z","caller":"traceutil/trace.go:171","msg":"trace[1503519145] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"1.025548477s","start":"2026-03-20T05:34:06.298376Z","end":"2026-03-20T05:34:07.323924Z","steps":["trace[1503519145] 'read index received' (duration: 420.087149ms)","trace[1503519145] 'applied index is now lower than readState.Index' (duration: 605.460228ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:07.32419Z","caller":"traceutil/trace.go:171","msg":"trace[1075484945] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"1.303877709s","start":"2026-03-20T05:34:06.0203Z","end":"2026-03-20T05:34:07.324178Z","steps":["trace[1075484945] 'process raft request' (duration: 698.244336ms)","trace[1075484945] 'compare' (duration: 605.091187ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:07.324246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:06.020285Z","time spent":"1.30392978s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:07.32438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.962425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:34:07.324372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.808671ms","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-03-20T05:34:07.324418Z","caller":"traceutil/trace.go:171","msg":"trace[1091712222] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:987; }","duration":"521.032107ms","start":"2026-03-20T05:34:06.803371Z","end":"2026-03-20T05:34:07.324404Z","steps":["trace[1091712222] 'agreement among raft nodes before linearized reading' (duration: 520.976545ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:07.32443Z","caller":"traceutil/trace.go:171","msg":"trace[888677850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"687.876873ms","start":"2026-03-20T05:34:06.636539Z","end":"2026-03-20T05:34:07.324416Z","steps":["trace[888677850] 'agreement among raft nodes before linearized reading' (duration: 687.686858ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:07.324446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:06.803352Z","time spent":"521.086228ms","remote":"127.0.0.1:45308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-20T05:34:07.324463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:06.63648Z","time spent":"687.977345ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:07.324646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.621208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-20T05:34:07.324649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.055862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-20T05:34:07.324702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026321439s","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-03-20T05:34:07.324713Z","caller":"traceutil/trace.go:171","msg":"trace[1054419561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"624.713761ms","start":"2026-03-20T05:34:06.69998Z","end":"2026-03-20T05:34:07.324694Z","steps":["trace[1054419561] 'agreement among raft nodes before linearized reading' (duration: 624.553217ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:07.324715Z","caller":"traceutil/trace.go:171","msg":"trace[1645529110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"574.160915ms","start":"2026-03-20T05:34:06.750539Z","end":"2026-03-20T05:34:07.3247Z","steps":["trace[1645529110] 'agreement among raft nodes before linearized reading' (duration: 573.940749ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:07.324735Z","caller":"traceutil/trace.go:171","msg":"trace[1383865812] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"1.026382021s","start":"2026-03-20T05:34:06.298344Z","end":"2026-03-20T05:34:07.324726Z","steps":["trace[1383865812] 'agreement among raft nodes before linearized reading' (duration: 1.026272878s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:07.324751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:06.699964Z","time spent":"624.779503ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:34:07.324752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:06.75052Z","time spent":"574.224167ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:07.324763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:06.298329Z","time spent":"1.026427522s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:07.831705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.859185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:07.831823Z","caller":"traceutil/trace.go:171","msg":"trace[814293345] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"503.197914ms","start":"2026-03-20T05:34:07.3286Z","end":"2026-03-20T05:34:07.831798Z","steps":["trace[814293345] 'process raft request' (duration: 324.170855ms)","trace[814293345] 'compare' (duration: 178.738301ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:07.831893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:07.328583Z","time spent":"503.273566ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:07.905046Z","caller":"traceutil/trace.go:171","msg":"trace[1424670648] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"576.42315ms","start":"2026-03-20T05:34:07.3286Z","end":"2026-03-20T05:34:07.905023Z","steps":["trace[1424670648] 'process raft request' (duration: 576.217134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:07.9052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:07.328583Z","time spent":"576.531462ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:07.905376Z","caller":"traceutil/trace.go:171","msg":"trace[621181597] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"576.044288ms","start":"2026-03-20T05:34:07.329179Z","end":"2026-03-20T05:34:07.905223Z","steps":["trace[621181597] 'process raft request' (duration: 575.76237ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:07.905361Z","caller":"traceutil/trace.go:171","msg":"trace[37322992] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"575.043741ms","start":"2026-03-20T05:34:07.330222Z","end":"2026-03-20T05:34:07.905266Z","steps":["trace[37322992] 'process raft request' (duration: 574.884646ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:07.905664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:07.329167Z","time spent":"576.326326ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:07.905664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:07.33021Z","time spent":"575.352799ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:08.640532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.923744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:08.640683Z","caller":"traceutil/trace.go:171","msg":"trace[549422541] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"160.436325ms","start":"2026-03-20T05:34:08.480225Z","end":"2026-03-20T05:34:08.640662Z","steps":["trace[549422541] 'process raft request' (duration: 46.290615ms)","trace[549422541] 'compare' (duration: 113.677867ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:09.072344Z","caller":"traceutil/trace.go:171","msg":"trace[494230353] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"424.355008ms","start":"2026-03-20T05:34:08.647889Z","end":"2026-03-20T05:34:09.072244Z","steps":["trace[494230353] 'process raft request' (duration: 424.133922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:09.07259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:08.647869Z","time spent":"424.596725ms","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-20T05:34:09.281416Z","caller":"traceutil/trace.go:171","msg":"trace[1859051118] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"631.948474ms","start":"2026-03-20T05:34:08.649441Z","end":"2026-03-20T05:34:09.28139Z","steps":["trace[1859051118] 'process raft request' (duration: 631.201113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:09.281598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:08.649422Z","time spent":"632.087877ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:09.281608Z","caller":"traceutil/trace.go:171","msg":"trace[831218536] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1032; }","duration":"620.615473ms","start":"2026-03-20T05:34:08.660963Z","end":"2026-03-20T05:34:09.281579Z","steps":["trace[831218536] 'read index received' (duration: 411.100504ms)","trace[831218536] 'applied index is now lower than readState.Index' (duration: 209.511589ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:09.281803Z","caller":"traceutil/trace.go:171","msg":"trace[37071483] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"595.64301ms","start":"2026-03-20T05:34:08.686128Z","end":"2026-03-20T05:34:09.281771Z","steps":["trace[37071483] 'process raft request' (duration: 595.180267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:09.281815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.838321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-20T05:34:09.281871Z","caller":"traceutil/trace.go:171","msg":"trace[1512812864] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:996; }","duration":"620.911082ms","start":"2026-03-20T05:34:08.660942Z","end":"2026-03-20T05:34:09.281853Z","steps":["trace[1512812864] 'agreement among raft nodes before linearized reading' (duration: 620.704816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:09.281887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:08.686099Z","time spent":"595.746743ms","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:34:09.281924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:08.660933Z","time spent":"620.978104ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"warn","ts":"2026-03-20T05:34:10.855528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305545105s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-20T05:34:10.855705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.28316Z","time spent":"1.572542766s","remote":"127.0.0.1:44914","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-20T05:34:10.856187Z","caller":"traceutil/trace.go:171","msg":"trace[943071453] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"1.569083869s","start":"2026-03-20T05:34:09.287067Z","end":"2026-03-20T05:34:10.856151Z","steps":["trace[943071453] 'process raft request' (duration: 1.568551254s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:10.856183Z","caller":"traceutil/trace.go:171","msg":"trace[1893125206] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1035; }","duration":"1.536312005s","start":"2026-03-20T05:34:09.319836Z","end":"2026-03-20T05:34:10.856148Z","steps":["trace[1893125206] 'read index received' (duration: 230.240965ms)","trace[1893125206] 'applied index is now lower than readState.Index' (duration: 1.30606915s)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:10.856307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.287055Z","time spent":"1.569186792s","remote":"127.0.0.1:44960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-20T05:34:10.856378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536532881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:34:10.856571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.287794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-20T05:34:10.856524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528941626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-20T05:34:10.856607Z","caller":"traceutil/trace.go:171","msg":"trace[1483982942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:997; }","duration":"945.368125ms","start":"2026-03-20T05:34:09.911227Z","end":"2026-03-20T05:34:10.856595Z","steps":["trace[1483982942] 'agreement among raft nodes before linearized reading' (duration: 945.221011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:10.856601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.475947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-20T05:34:10.856634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016362998s","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-03-20T05:34:10.856632Z","caller":"traceutil/trace.go:171","msg":"trace[844418440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:997; }","duration":"1.529095821s","start":"2026-03-20T05:34:09.327515Z","end":"2026-03-20T05:34:10.856611Z","steps":["trace[844418440] 'agreement among raft nodes before linearized reading' (duration: 1.528845874s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:10.856629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.736405ms","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-03-20T05:34:10.856654Z","caller":"traceutil/trace.go:171","msg":"trace[1041419705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"946.55319ms","start":"2026-03-20T05:34:09.910085Z","end":"2026-03-20T05:34:10.856639Z","steps":["trace[1041419705] 'agreement among raft nodes before linearized reading' (duration: 946.359684ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:10.856675Z","caller":"traceutil/trace.go:171","msg":"trace[1200674028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:997; }","duration":"1.01644263s","start":"2026-03-20T05:34:09.84022Z","end":"2026-03-20T05:34:10.856662Z","steps":["trace[1200674028] 'agreement among raft nodes before linearized reading' (duration: 1.016294526s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:10.856694Z","caller":"traceutil/trace.go:171","msg":"trace[415273955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"946.844018ms","start":"2026-03-20T05:34:09.909837Z","end":"2026-03-20T05:34:10.856681Z","steps":["trace[415273955] 'agreement among raft nodes before linearized reading' (duration: 946.632632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:10.856691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.32749Z","time spent":"1.529186694s","remote":"127.0.0.1:45126","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-03-20T05:34:10.856709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.840191Z","time spent":"1.016510202s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:10.856728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.909824Z","time spent":"946.898329ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-20T05:34:10.8567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.910079Z","time spent":"946.614081ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-20T05:34:10.856643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.911216Z","time spent":"945.418308ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-20T05:34:10.856414Z","caller":"traceutil/trace.go:171","msg":"trace[1041983716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"1.536610813s","start":"2026-03-20T05:34:09.319791Z","end":"2026-03-20T05:34:10.856402Z","steps":["trace[1041983716] 'agreement among raft nodes before linearized reading' (duration: 1.53649326s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:10.856914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:09.319773Z","time spent":"1.537127887s","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:11.441633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.185129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:11.441707Z","caller":"traceutil/trace.go:171","msg":"trace[1368487813] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"583.385535ms","start":"2026-03-20T05:34:10.858309Z","end":"2026-03-20T05:34:11.441695Z","steps":["trace[1368487813] 'read index received' (duration: 223.086133ms)","trace[1368487813] 'applied index is now lower than readState.Index' (duration: 360.298642ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:11.441764Z","caller":"traceutil/trace.go:171","msg":"trace[2088810349] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"584.018853ms","start":"2026-03-20T05:34:10.857738Z","end":"2026-03-20T05:34:11.441757Z","steps":["trace[2088810349] 'process raft request' (duration: 223.643478ms)","trace[2088810349] 'compare' (duration: 360.091747ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:11.4418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.857717Z","time spent":"584.064105ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:11.44201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.699134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:11.442036Z","caller":"traceutil/trace.go:171","msg":"trace[812304629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"583.751366ms","start":"2026-03-20T05:34:10.858279Z","end":"2026-03-20T05:34:11.44203Z","steps":["trace[812304629] 'agreement among raft nodes before linearized reading' (duration: 583.655423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:11.442053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.858262Z","time spent":"583.787177ms","remote":"127.0.0.1:44882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:34:11.923433Z","caller":"traceutil/trace.go:171","msg":"trace[1298085533] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.061877662s","start":"2026-03-20T05:34:10.861516Z","end":"2026-03-20T05:34:11.923393Z","steps":["trace[1298085533] 'process raft request' (duration: 996.745945ms)","trace[1298085533] 'compare' (duration: 64.975583ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:11.923578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.861494Z","time spent":"1.062010917s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:11.923733Z","caller":"traceutil/trace.go:171","msg":"trace[1466044690] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"1.062170291s","start":"2026-03-20T05:34:10.861532Z","end":"2026-03-20T05:34:11.923702Z","steps":["trace[1466044690] 'process raft request' (duration: 1.061823381s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:11.923826Z","caller":"traceutil/trace.go:171","msg":"trace[805291285] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1038; }","duration":"482.078808ms","start":"2026-03-20T05:34:11.441732Z","end":"2026-03-20T05:34:11.92381Z","steps":["trace[805291285] 'read index received' (duration: 416.589631ms)","trace[805291285] 'applied index is now lower than readState.Index' (duration: 65.488257ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:11.923873Z","caller":"traceutil/trace.go:171","msg":"trace[1139175502] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.062191812s","start":"2026-03-20T05:34:10.861669Z","end":"2026-03-20T05:34:11.92386Z","steps":["trace[1139175502] 'process raft request' (duration: 1.061918064s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:11.923874Z","caller":"traceutil/trace.go:171","msg":"trace[223954578] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.06213764s","start":"2026-03-20T05:34:10.861698Z","end":"2026-03-20T05:34:11.923836Z","steps":["trace[223954578] 'process raft request' (duration: 1.061964925s)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:11.92393Z","caller":"traceutil/trace.go:171","msg":"trace[1646501998] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.061764779s","start":"2026-03-20T05:34:10.862147Z","end":"2026-03-20T05:34:11.923912Z","steps":["trace[1646501998] 'process raft request' (duration: 1.061610485s)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:11.92394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.861659Z","time spent":"1.062248293s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:11.923929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.861511Z","time spent":"1.062322065s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-20T05:34:11.924009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.140723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-03-20T05:34:11.924005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.197624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-20T05:34:11.924025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.862135Z","time spent":"1.061842911s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:11.924051Z","caller":"traceutil/trace.go:171","msg":"trace[484455186] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1003; }","duration":"480.216176ms","start":"2026-03-20T05:34:11.443824Z","end":"2026-03-20T05:34:11.92404Z","steps":["trace[484455186] 'agreement among raft nodes before linearized reading' (duration: 480.115083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:11.924047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:10.86169Z","time spent":"1.062272603s","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:11.92407Z","caller":"traceutil/trace.go:171","msg":"trace[1931073551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"514.302147ms","start":"2026-03-20T05:34:11.40975Z","end":"2026-03-20T05:34:11.924052Z","steps":["trace[1931073551] 'agreement among raft nodes before linearized reading' (duration: 514.169573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:11.92409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:11.443811Z","time spent":"480.271917ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-03-20T05:34:11.924113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:11.40973Z","time spent":"514.373178ms","remote":"127.0.0.1:44884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-20T05:34:13.206077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.837889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:13.206175Z","caller":"traceutil/trace.go:171","msg":"trace[247332812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"884.973792ms","start":"2026-03-20T05:34:12.321184Z","end":"2026-03-20T05:34:13.206158Z","steps":["trace[247332812] 'range keys from in-memory index tree' (duration: 884.781377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:13.206214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:12.321167Z","time spent":"885.037795ms","remote":"127.0.0.1:44882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-20T05:34:14.608149Z","caller":"traceutil/trace.go:171","msg":"trace[1544450482] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"314.81246ms","start":"2026-03-20T05:34:14.293318Z","end":"2026-03-20T05:34:14.608131Z","steps":["trace[1544450482] 'process raft request' (duration: 314.705057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:14.608255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:14.293303Z","time spent":"314.895663ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:16.20152Z","caller":"traceutil/trace.go:171","msg":"trace[460611013] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"259.54359ms","start":"2026-03-20T05:34:15.941951Z","end":"2026-03-20T05:34:16.201494Z","steps":["trace[460611013] 'process raft request' (duration: 259.404136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:16.461165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.975261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-20T05:34:16.462181Z","caller":"traceutil/trace.go:171","msg":"trace[1959384029] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"519.101712ms","start":"2026-03-20T05:34:15.943037Z","end":"2026-03-20T05:34:16.462138Z","steps":["trace[1959384029] 'process raft request' (duration: 413.02253ms)","trace[1959384029] 'compare' (duration: 104.690783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:16.46251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:15.943017Z","time spent":"519.238316ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:16.462803Z","caller":"traceutil/trace.go:171","msg":"trace[915689339] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"519.641028ms","start":"2026-03-20T05:34:15.943138Z","end":"2026-03-20T05:34:16.462779Z","steps":["trace[915689339] 'process raft request' (duration: 518.723832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:16.462898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:15.943113Z","time spent":"519.739911ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:16.463221Z","caller":"traceutil/trace.go:171","msg":"trace[379917069] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1053; }","duration":"144.469975ms","start":"2026-03-20T05:34:16.318738Z","end":"2026-03-20T05:34:16.463208Z","steps":["trace[379917069] 'read index received' (duration: 37.269461ms)","trace[379917069] 'applied index is now lower than readState.Index' (duration: 107.199534ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:34:16.463337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.603339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:34:16.463368Z","caller":"traceutil/trace.go:171","msg":"trace[1337332554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"144.675981ms","start":"2026-03-20T05:34:16.318684Z","end":"2026-03-20T05:34:16.46336Z","steps":["trace[1337332554] 'agreement among raft nodes before linearized reading' (duration: 144.617099ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:16.463457Z","caller":"traceutil/trace.go:171","msg":"trace[1967571401] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"519.877704ms","start":"2026-03-20T05:34:15.943557Z","end":"2026-03-20T05:34:16.463435Z","steps":["trace[1967571401] 'process raft request' (duration: 519.161134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:16.463615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:15.943544Z","time spent":"519.997327ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:16.463666Z","caller":"traceutil/trace.go:171","msg":"trace[643493297] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"519.951886ms","start":"2026-03-20T05:34:15.943652Z","end":"2026-03-20T05:34:16.463604Z","steps":["trace[643493297] 'process raft request' (duration: 519.372759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:34:16.46389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:34:15.943645Z","time spent":"520.206384ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:34:40.687579Z","caller":"traceutil/trace.go:171","msg":"trace[725504151] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"105.931708ms","start":"2026-03-20T05:34:40.581627Z","end":"2026-03-20T05:34:40.687558Z","steps":["trace[725504151] 'process raft request' (duration: 105.898087ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:34:40.687629Z","caller":"traceutil/trace.go:171","msg":"trace[445326689] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"106.809603ms","start":"2026-03-20T05:34:40.580773Z","end":"2026-03-20T05:34:40.687583Z","steps":["trace[445326689] 'process raft request' (duration: 36.248073ms)","trace[445326689] 'compare' (duration: 70.365635ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:34:40.687734Z","caller":"traceutil/trace.go:171","msg":"trace[1702931953] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"106.842044ms","start":"2026-03-20T05:34:40.58084Z","end":"2026-03-20T05:34:40.687682Z","steps":["trace[1702931953] 'process raft request' (duration: 106.654979ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:27.416242Z","caller":"traceutil/trace.go:171","msg":"trace[849391381] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"318.686378ms","start":"2026-03-20T05:37:27.097527Z","end":"2026-03-20T05:37:27.416213Z","steps":["trace[849391381] 'process raft request' (duration: 318.05428ms)"],"step_count":1} {"level":"warn","ts":"2026-03-20T05:37:27.416386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-20T05:37:27.09749Z","time spent":"318.830152ms","remote":"127.0.0.1:45126","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-20T05:37:27.416331Z","caller":"traceutil/trace.go:171","msg":"trace[1341950396] linearizableReadLoop","detail":"{readStateIndex:1929; appliedIndex:1928; }","duration":"218.158843ms","start":"2026-03-20T05:37:27.197616Z","end":"2026-03-20T05:37:27.415775Z","steps":["trace[1341950396] 'read index received' (duration: 217.884865ms)","trace[1341950396] 'applied index is now lower than readState.Index' (duration: 272.438µs)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:37:27.416848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.212762ms","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:531"} {"level":"info","ts":"2026-03-20T05:37:27.416955Z","caller":"traceutil/trace.go:171","msg":"trace[2141927783] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1849; }","duration":"219.381438ms","start":"2026-03-20T05:37:27.197549Z","end":"2026-03-20T05:37:27.41693Z","steps":["trace[2141927783] 'agreement among raft nodes before linearized reading' (duration: 218.927195ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:27.553114Z","caller":"traceutil/trace.go:171","msg":"trace[775941455] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"120.702005ms","start":"2026-03-20T05:37:27.432361Z","end":"2026-03-20T05:37:27.553063Z","steps":["trace[775941455] 'read index received' (duration: 82.689859ms)","trace[775941455] 'applied index is now lower than readState.Index' (duration: 38.010746ms)"],"step_count":2} {"level":"info","ts":"2026-03-20T05:37:27.553362Z","caller":"traceutil/trace.go:171","msg":"trace[1899449506] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"131.685006ms","start":"2026-03-20T05:37:27.421631Z","end":"2026-03-20T05:37:27.553316Z","steps":["trace[1899449506] 'process raft request' (duration: 93.450824ms)","trace[1899449506] 'compare' (duration: 37.80616ms)"],"step_count":2} {"level":"warn","ts":"2026-03-20T05:37:27.553434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.951272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-20T05:37:27.553604Z","caller":"traceutil/trace.go:171","msg":"trace[965733431] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1850; }","duration":"121.243311ms","start":"2026-03-20T05:37:27.43231Z","end":"2026-03-20T05:37:27.553554Z","steps":["trace[965733431] 'agreement among raft nodes before linearized reading' (duration: 120.902031ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:28.065188Z","caller":"traceutil/trace.go:171","msg":"trace[629241020] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"110.233229ms","start":"2026-03-20T05:37:27.954924Z","end":"2026-03-20T05:37:28.065157Z","steps":["trace[629241020] 'process raft request' (duration: 110.138587ms)"],"step_count":1} {"level":"info","ts":"2026-03-20T05:37:28.065352Z","caller":"traceutil/trace.go:171","msg":"trace[1086076162] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"111.010991ms","start":"2026-03-20T05:37:27.954268Z","end":"2026-03-20T05:37:28.065279Z","steps":["trace[1086076162] 'process raft request' (duration: 107.144752ms)"],"step_count":1}