{"level":"warn","ts":"2026-07-17T11:05:55.964493Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T11:05:55.96459Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115: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.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T11:05:55.964666Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T11:05:55.964679Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-17T11:05:55.964697Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T11:05:55.965572Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-17T11:05:55.965831Z","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.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T11:05:55.968372Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.30556ms"} {"level":"info","ts":"2026-07-17T11:05:55.974091Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-17T11:05:55.974146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T11:05:55.974164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-17T11:05:55.974173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T11:05:55.974182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-17T11:05:55.974216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-17T11:05:55.982129Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T11:05:55.984403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T11:05:55.985869Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T11:05:55.988037Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T11:05:55.989005Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T11:05:55.989103Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T11:05:55.98912Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T11:05:55.989134Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T11:05:55.992437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-17T11:05:55.992583Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-17T11:05:55.995155Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T11:05:55.995607Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-17T11:05:55.995865Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T11:05:55.99591Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T11:05:55.995804Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-17T11:05:56.775308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T11:05:56.775412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T11:05:56.775481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-17T11:05:56.775523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-17T11:05:56.77562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-17T11:05:56.77565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-17T11:05:56.775714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-17T11:05:56.838732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:05:56.838793Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:05:56.838909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T11:05:56.839148Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T11:05:56.839298Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T11:05:56.839343Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T11:05:56.843428Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-17T11:05:56.844095Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T11:05:56.875357Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:05:56.87557Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T11:05:56.875627Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-17T11:05:57.025385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.046757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:57.025488Z","caller":"traceutil/trace.go:171","msg":"trace[314304779] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"121.216441ms","start":"2026-07-17T11:05:56.904249Z","end":"2026-07-17T11:05:57.025466Z","steps":["trace[314304779] 'range keys from in-memory index tree' (duration: 120.919443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:57.027989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.995915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:57.028108Z","caller":"traceutil/trace.go:171","msg":"trace[671715128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"123.570443ms","start":"2026-07-17T11:05:56.90451Z","end":"2026-07-17T11:05:57.028081Z","steps":["trace[671715128] 'count revisions from in-memory index tree' (duration: 120.764159ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.633385Z","caller":"traceutil/trace.go:171","msg":"trace[29863924] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"125.392489ms","start":"2026-07-17T11:05:58.507942Z","end":"2026-07-17T11:05:58.633335Z","steps":["trace[29863924] 'read index received' (duration: 125.086451ms)","trace[29863924] 'applied index is now lower than readState.Index' (duration: 304.588µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:05:58.633449Z","caller":"traceutil/trace.go:171","msg":"trace[530710407] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"125.829731ms","start":"2026-07-17T11:05:58.507537Z","end":"2026-07-17T11:05:58.633367Z","steps":["trace[530710407] 'process raft request' (duration: 125.435401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.633765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.77542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:58.633817Z","caller":"traceutil/trace.go:171","msg":"trace[140595632] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:2; }","duration":"125.896983ms","start":"2026-07-17T11:05:58.507911Z","end":"2026-07-17T11:05:58.633808Z","steps":["trace[140595632] 'agreement among raft nodes before linearized reading' (duration: 125.634975ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836292Z","caller":"traceutil/trace.go:171","msg":"trace[83509672] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:5; }","duration":"202.654989ms","start":"2026-07-17T11:05:58.633599Z","end":"2026-07-17T11:05:58.836254Z","steps":["trace[83509672] 'read index received' (duration: 197.810453ms)","trace[83509672] 'applied index is now lower than readState.Index' (duration: 4.843726ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:05:58.836499Z","caller":"traceutil/trace.go:171","msg":"trace[198047154] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"254.954658ms","start":"2026-07-17T11:05:58.581498Z","end":"2026-07-17T11:05:58.836452Z","steps":["trace[198047154] 'process raft request' (duration: 254.487076ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836597Z","caller":"traceutil/trace.go:171","msg":"trace[1602186261] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"254.766103ms","start":"2026-07-17T11:05:58.581825Z","end":"2026-07-17T11:05:58.836591Z","steps":["trace[1602186261] 'process raft request' (duration: 254.208689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.836652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.305179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:58.83669Z","caller":"traceutil/trace.go:171","msg":"trace[2142999676] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:0; response_revision:10; }","duration":"262.387622ms","start":"2026-07-17T11:05:58.574293Z","end":"2026-07-17T11:05:58.836681Z","steps":["trace[2142999676] 'agreement among raft nodes before linearized reading' (duration: 262.31899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.836702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.212329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:58.836826Z","caller":"traceutil/trace.go:171","msg":"trace[2106155255] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"254.892766ms","start":"2026-07-17T11:05:58.581923Z","end":"2026-07-17T11:05:58.836816Z","steps":["trace[2106155255] 'process raft request' (duration: 254.133807ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836829Z","caller":"traceutil/trace.go:171","msg":"trace[837200375] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"259.454675ms","start":"2026-07-17T11:05:58.577346Z","end":"2026-07-17T11:05:58.836801Z","steps":["trace[837200375] 'agreement among raft nodes before linearized reading' (duration: 259.064405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.83693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.33835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:58.836975Z","caller":"traceutil/trace.go:171","msg":"trace[431984889] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"277.416232ms","start":"2026-07-17T11:05:58.559548Z","end":"2026-07-17T11:05:58.836964Z","steps":["trace[431984889] 'agreement among raft nodes before linearized reading' (duration: 277.34001ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836809Z","caller":"traceutil/trace.go:171","msg":"trace[655768352] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"240.439701ms","start":"2026-07-17T11:05:58.596314Z","end":"2026-07-17T11:05:58.836753Z","steps":["trace[655768352] 'process raft request' (duration: 239.803984ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836981Z","caller":"traceutil/trace.go:171","msg":"trace[208816360] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"260.885262ms","start":"2026-07-17T11:05:58.576081Z","end":"2026-07-17T11:05:58.836966Z","steps":["trace[208816360] 'process raft request' (duration: 255.528793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.837142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.438458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:58.837186Z","caller":"traceutil/trace.go:171","msg":"trace[1991642740] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:10; }","duration":"328.491099ms","start":"2026-07-17T11:05:58.508688Z","end":"2026-07-17T11:05:58.837179Z","steps":["trace[1991642740] 'agreement among raft nodes before linearized reading' (duration: 328.434638ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.837209Z","caller":"traceutil/trace.go:171","msg":"trace[202648835] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"232.843293ms","start":"2026-07-17T11:05:58.60435Z","end":"2026-07-17T11:05:58.837193Z","steps":["trace[202648835] 'process raft request' (duration: 231.803776ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.836561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.027776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-17T11:05:58.837339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.210698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-17T11:05:58.837351Z","caller":"traceutil/trace.go:171","msg":"trace[825408327] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"261.860747ms","start":"2026-07-17T11:05:58.57548Z","end":"2026-07-17T11:05:58.837341Z","steps":["trace[825408327] 'agreement among raft nodes before linearized reading' (duration: 260.979494ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.837373Z","caller":"traceutil/trace.go:171","msg":"trace[108538217] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"329.266229ms","start":"2026-07-17T11:05:58.508098Z","end":"2026-07-17T11:05:58.837364Z","steps":["trace[108538217] 'agreement among raft nodes before linearized reading' (duration: 329.156536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.837401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.508063Z","time spent":"329.330471ms","remote":"127.0.0.1:50470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-17T11:05:58.837397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.247555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-17T11:05:58.837481Z","caller":"traceutil/trace.go:171","msg":"trace[19745643] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"200.391699ms","start":"2026-07-17T11:05:58.637074Z","end":"2026-07-17T11:05:58.837465Z","steps":["trace[19745643] 'agreement among raft nodes before linearized reading' (duration: 200.230445ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836561Z","caller":"traceutil/trace.go:171","msg":"trace[472709047] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"254.888796ms","start":"2026-07-17T11:05:58.581648Z","end":"2026-07-17T11:05:58.836537Z","steps":["trace[472709047] 'process raft request' (duration: 254.364643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:58.837568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.894726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-17T11:05:58.837224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.508683Z","time spent":"328.53369ms","remote":"127.0.0.1:50364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-17T11:05:58.837649Z","caller":"traceutil/trace.go:171","msg":"trace[1188092900] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"210.03664ms","start":"2026-07-17T11:05:58.6276Z","end":"2026-07-17T11:05:58.837637Z","steps":["trace[1188092900] 'agreement among raft nodes before linearized reading' (duration: 209.922507ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:58.836948Z","caller":"traceutil/trace.go:171","msg":"trace[1379538955] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"254.839665ms","start":"2026-07-17T11:05:58.582096Z","end":"2026-07-17T11:05:58.836936Z","steps":["trace[1379538955] 'process raft request' (duration: 253.977813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:59.4691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.938562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-17T11:05:59.469434Z","caller":"traceutil/trace.go:171","msg":"trace[142684523] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"549.059013ms","start":"2026-07-17T11:05:58.920357Z","end":"2026-07-17T11:05:59.469416Z","steps":["trace[142684523] 'process raft request' (duration: 549.014042ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:59.469433Z","caller":"traceutil/trace.go:171","msg":"trace[1687630164] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"558.092508ms","start":"2026-07-17T11:05:58.911288Z","end":"2026-07-17T11:05:59.46938Z","steps":["trace[1687630164] 'process raft request' (duration: 557.963294ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:59.469527Z","caller":"traceutil/trace.go:171","msg":"trace[2118658904] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"558.091868ms","start":"2026-07-17T11:05:58.911428Z","end":"2026-07-17T11:05:59.46952Z","steps":["trace[2118658904] 'process raft request' (duration: 557.845412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:59.469553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.911391Z","time spent":"558.15173ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:05:59.469511Z","caller":"traceutil/trace.go:171","msg":"trace[999461887] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"558.986012ms","start":"2026-07-17T11:05:58.910484Z","end":"2026-07-17T11:05:59.46947Z","steps":["trace[999461887] 'process raft request' (duration: 558.738646ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:59.469654Z","caller":"traceutil/trace.go:171","msg":"trace[539506739] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"559.281809ms","start":"2026-07-17T11:05:58.910323Z","end":"2026-07-17T11:05:59.469605Z","steps":["trace[539506739] 'process raft request' (duration: 32.338531ms)","trace[539506739] 'compare' (duration: 525.792809ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:05:59.469818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.910473Z","time spent":"559.213547ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:05:59.469893Z","caller":"traceutil/trace.go:171","msg":"trace[181206266] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"550.890592ms","start":"2026-07-17T11:05:58.918961Z","end":"2026-07-17T11:05:59.469852Z","steps":["trace[181206266] 'process raft request' (duration: 550.358658ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:05:59.469983Z","caller":"traceutil/trace.go:171","msg":"trace[1469281473] linearizableReadLoop","detail":"{readStateIndex:30; appliedIndex:25; }","duration":"556.869607ms","start":"2026-07-17T11:05:58.913103Z","end":"2026-07-17T11:05:59.469973Z","steps":["trace[1469281473] 'read index received' (duration: 29.490467ms)","trace[1469281473] 'applied index is now lower than readState.Index' (duration: 527.37795ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:05:59.469984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.910305Z","time spent":"559.567256ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:05:59.470107Z","caller":"traceutil/trace.go:171","msg":"trace[600365592] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"558.081447ms","start":"2026-07-17T11:05:58.912019Z","end":"2026-07-17T11:05:59.4701Z","steps":["trace[600365592] 'process raft request' (duration: 557.272106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:59.470126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.918929Z","time spent":"551.052755ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:05:59.47016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.912008Z","time spent":"558.119509ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:05:59.469716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.911268Z","time spent":"558.289553ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:05:59.47037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.012786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:59.470404Z","caller":"traceutil/trace.go:171","msg":"trace[813354181] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:28; }","duration":"558.061917ms","start":"2026-07-17T11:05:58.912332Z","end":"2026-07-17T11:05:59.470394Z","steps":["trace[813354181] 'agreement among raft nodes before linearized reading' (duration: 558.021226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:59.470436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.912324Z","time spent":"558.097948ms","remote":"127.0.0.1:50392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-07-17T11:05:59.469496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.920332Z","time spent":"549.140295ms","remote":"127.0.0.1:50764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:05:59.47033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.608915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-17T11:05:59.470522Z","caller":"traceutil/trace.go:171","msg":"trace[2007152767] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:28; }","duration":"425.85846ms","start":"2026-07-17T11:05:59.04465Z","end":"2026-07-17T11:05:59.470509Z","steps":["trace[2007152767] 'agreement among raft nodes before linearized reading' (duration: 425.572063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:59.470565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:59.044612Z","time spent":"425.935322ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-07-17T11:05:59.470835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.736806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-07-17T11:05:59.470916Z","caller":"traceutil/trace.go:171","msg":"trace[853555783] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:28; }","duration":"560.797028ms","start":"2026-07-17T11:05:58.910076Z","end":"2026-07-17T11:05:59.470873Z","steps":["trace[853555783] 'agreement among raft nodes before linearized reading' (duration: 560.11178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:05:59.470952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:05:58.91Z","time spent":"560.944212ms","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-17T11:06:09.018395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147841325,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:06:09.227147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091101594s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:06:09.227763Z","caller":"traceutil/trace.go:171","msg":"trace[1497502464] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"1.091812163s","start":"2026-07-17T11:06:08.135915Z","end":"2026-07-17T11:06:09.227727Z","steps":["trace[1497502464] 'process raft request' (duration: 1.091546776s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:09.228355Z","caller":"traceutil/trace.go:171","msg":"trace[299054950] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:367; }","duration":"716.013433ms","start":"2026-07-17T11:06:08.512323Z","end":"2026-07-17T11:06:09.228337Z","steps":["trace[299054950] 'read index received' (duration: 715.094549ms)","trace[299054950] 'applied index is now lower than readState.Index' (duration: 917.824µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:09.228453Z","caller":"traceutil/trace.go:171","msg":"trace[46261597] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"1.092372206s","start":"2026-07-17T11:06:08.136067Z","end":"2026-07-17T11:06:09.22844Z","steps":["trace[46261597] 'process raft request' (duration: 1.0921321s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:09.228694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.358933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:06:09.228694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.152246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:06:09.228798Z","caller":"traceutil/trace.go:171","msg":"trace[1142549472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:354; }","duration":"716.485776ms","start":"2026-07-17T11:06:08.512266Z","end":"2026-07-17T11:06:09.228751Z","steps":["trace[1142549472] 'agreement among raft nodes before linearized reading' (duration: 716.356743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:09.228843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:08.135886Z","time spent":"1.091951477s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:06:09.228877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:08.51224Z","time spent":"716.623689ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:06:09.228783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.072733ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:09.229039Z","caller":"traceutil/trace.go:171","msg":"trace[2047349338] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:354; }","duration":"160.34709ms","start":"2026-07-17T11:06:09.068649Z","end":"2026-07-17T11:06:09.228996Z","steps":["trace[2047349338] 'agreement among raft nodes before linearized reading' (duration: 159.994271ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:09.228808Z","caller":"traceutil/trace.go:171","msg":"trace[1106559556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:354; }","duration":"257.376501ms","start":"2026-07-17T11:06:08.971404Z","end":"2026-07-17T11:06:09.228781Z","steps":["trace[1106559556] 'agreement among raft nodes before linearized reading' (duration: 257.105114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:09.229729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:08.136047Z","time spent":"1.09244451s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:06:15.150884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147841374,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:06:15.647476Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.047785989s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:06:15.648176Z","caller":"traceutil/trace.go:171","msg":"trace[731824169] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"998.001224ms","start":"2026-07-17T11:06:14.650142Z","end":"2026-07-17T11:06:15.648144Z","steps":["trace[731824169] 'read index received' (duration: 997.639315ms)","trace[731824169] 'applied index is now lower than readState.Index' (duration: 360.679µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:15.648366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.199659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:15.648416Z","caller":"traceutil/trace.go:171","msg":"trace[1752936231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"998.327593ms","start":"2026-07-17T11:06:14.650074Z","end":"2026-07-17T11:06:15.648402Z","steps":["trace[1752936231] 'agreement among raft nodes before linearized reading' (duration: 998.22113ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:15.648389Z","caller":"traceutil/trace.go:171","msg":"trace[1799901561] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"1.048742013s","start":"2026-07-17T11:06:14.599522Z","end":"2026-07-17T11:06:15.648264Z","steps":["trace[1799901561] 'process raft request' (duration: 1.048378924s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:15.648467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:14.650035Z","time spent":"998.422345ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:06:15.648784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:14.599494Z","time spent":"1.049121692s","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:06:16.464471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.952121ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T11:06:16.464602Z","caller":"traceutil/trace.go:171","msg":"trace[1477085958] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"816.322002ms","start":"2026-07-17T11:06:15.648261Z","end":"2026-07-17T11:06:16.464583Z","steps":["trace[1477085958] 'read index received' (duration: 162.233478ms)","trace[1477085958] 'applied index is now lower than readState.Index' (duration: 654.086884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:16.464765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206589458s","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-07-17T11:06:16.464802Z","caller":"traceutil/trace.go:171","msg":"trace[1877885202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"1.20668245s","start":"2026-07-17T11:06:15.258109Z","end":"2026-07-17T11:06:16.464792Z","steps":["trace[1877885202] 'agreement among raft nodes before linearized reading' (duration: 1.206577067s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:16.464774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198826744s","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-07-17T11:06:16.464837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:15.258083Z","time spent":"1.206745701s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T11:06:16.464865Z","caller":"traceutil/trace.go:171","msg":"trace[1922638336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"1.198995469s","start":"2026-07-17T11:06:15.265848Z","end":"2026-07-17T11:06:16.464844Z","steps":["trace[1922638336] 'agreement among raft nodes before linearized reading' (duration: 1.198771534s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:16.464921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:15.265821Z","time spent":"1.199090201s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T11:06:16.464983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.51916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-17T11:06:16.465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206776593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T11:06:16.465097Z","caller":"traceutil/trace.go:171","msg":"trace[390272232] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:373; }","duration":"810.695205ms","start":"2026-07-17T11:06:15.654371Z","end":"2026-07-17T11:06:16.465066Z","steps":["trace[390272232] 'agreement among raft nodes before linearized reading' (duration: 810.440169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:16.464951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.14786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:16.465125Z","caller":"traceutil/trace.go:171","msg":"trace[443717472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"1.206986908s","start":"2026-07-17T11:06:15.258109Z","end":"2026-07-17T11:06:16.465096Z","steps":["trace[443717472] 'agreement among raft nodes before linearized reading' (duration: 1.20668835s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:16.465141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159521814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-17T11:06:16.465164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:15.654352Z","time spent":"810.798918ms","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-17T11:06:16.465192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:15.258098Z","time spent":"1.20708274s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T11:06:16.465189Z","caller":"traceutil/trace.go:171","msg":"trace[1087843081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"815.428637ms","start":"2026-07-17T11:06:15.649728Z","end":"2026-07-17T11:06:16.465157Z","steps":["trace[1087843081] 'agreement among raft nodes before linearized reading' (duration: 815.045278ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:16.465221Z","caller":"traceutil/trace.go:171","msg":"trace[1764704317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:373; }","duration":"1.159586495s","start":"2026-07-17T11:06:15.305581Z","end":"2026-07-17T11:06:16.465167Z","steps":["trace[1764704317] 'agreement among raft nodes before linearized reading' (duration: 1.159519893s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:16.465258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:15.649659Z","time spent":"815.584232ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:06:16.465277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:15.305557Z","time spent":"1.159711908s","remote":"127.0.0.1:50572","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":"info","ts":"2026-07-17T11:06:22.627377Z","caller":"traceutil/trace.go:171","msg":"trace[653010935] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"129.897647ms","start":"2026-07-17T11:06:22.497456Z","end":"2026-07-17T11:06:22.627353Z","steps":["trace[653010935] 'read index received' (duration: 129.63216ms)","trace[653010935] 'applied index is now lower than readState.Index' (duration: 264.437µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:22.627475Z","caller":"traceutil/trace.go:171","msg":"trace[1465034852] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"317.499223ms","start":"2026-07-17T11:06:22.30993Z","end":"2026-07-17T11:06:22.627428Z","steps":["trace[1465034852] 'process raft request' (duration: 317.273658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:22.627619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.515165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:06:22.627655Z","caller":"traceutil/trace.go:171","msg":"trace[880339095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"122.585047ms","start":"2026-07-17T11:06:22.505062Z","end":"2026-07-17T11:06:22.627647Z","steps":["trace[880339095] 'agreement among raft nodes before linearized reading' (duration: 122.515275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:22.627681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.204105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-17T11:06:22.62771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:22.309903Z","time spent":"317.695189ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:06:22.627712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.842168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:06:22.62784Z","caller":"traceutil/trace.go:171","msg":"trace[1677634267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"122.008282ms","start":"2026-07-17T11:06:22.505807Z","end":"2026-07-17T11:06:22.627815Z","steps":["trace[1677634267] 'agreement among raft nodes before linearized reading' (duration: 121.750896ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:22.627756Z","caller":"traceutil/trace.go:171","msg":"trace[1556538625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"130.292397ms","start":"2026-07-17T11:06:22.497445Z","end":"2026-07-17T11:06:22.627737Z","steps":["trace[1556538625] 'agreement among raft nodes before linearized reading' (duration: 130.032021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:22.627984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.503107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T11:06:22.628008Z","caller":"traceutil/trace.go:171","msg":"trace[164853805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:389; }","duration":"125.00163ms","start":"2026-07-17T11:06:22.502999Z","end":"2026-07-17T11:06:22.628Z","steps":["trace[164853805] 'agreement among raft nodes before linearized reading' (duration: 124.479167ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:42.052371Z","caller":"traceutil/trace.go:171","msg":"trace[1267618981] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"525.525986ms","start":"2026-07-17T11:06:41.526818Z","end":"2026-07-17T11:06:42.052344Z","steps":["trace[1267618981] 'process raft request' (duration: 525.31519ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.052782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.526787Z","time spent":"525.908716ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.560057Z","caller":"traceutil/trace.go:171","msg":"trace[1883871212] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.02126589s","start":"2026-07-17T11:06:41.538761Z","end":"2026-07-17T11:06:42.560026Z","steps":["trace[1883871212] 'process raft request' (duration: 1.020574082s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:42.560209Z","caller":"traceutil/trace.go:171","msg":"trace[1444906816] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"959.351022ms","start":"2026-07-17T11:06:41.600827Z","end":"2026-07-17T11:06:42.560178Z","steps":["trace[1444906816] 'process raft request' (duration: 958.818278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.560222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.538729Z","time spent":"1.021413543s","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.560227Z","caller":"traceutil/trace.go:171","msg":"trace[1893795807] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.01900616s","start":"2026-07-17T11:06:41.541153Z","end":"2026-07-17T11:06:42.560159Z","steps":["trace[1893795807] 'process raft request' (duration: 1.018314982s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.560312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.600797Z","time spent":"959.466785ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.560312Z","caller":"traceutil/trace.go:171","msg":"trace[1788825114] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"953.130867ms","start":"2026-07-17T11:06:41.607107Z","end":"2026-07-17T11:06:42.560238Z","steps":["trace[1788825114] 'process raft request' (duration: 952.632754ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:42.560425Z","caller":"traceutil/trace.go:171","msg":"trace[1141329350] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"997.781292ms","start":"2026-07-17T11:06:41.562632Z","end":"2026-07-17T11:06:42.560414Z","steps":["trace[1141329350] 'process raft request' (duration: 996.92576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.560448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.541141Z","time spent":"1.019198085s","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:06:42.560476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.562616Z","time spent":"997.833943ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.560509Z","caller":"traceutil/trace.go:171","msg":"trace[935148974] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"953.968921ms","start":"2026-07-17T11:06:41.606504Z","end":"2026-07-17T11:06:42.560473Z","steps":["trace[935148974] 'process raft request' (duration: 953.18303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.560589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.607089Z","time spent":"953.361034ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:06:42.560665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.606487Z","time spent":"954.098544ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.560689Z","caller":"traceutil/trace.go:171","msg":"trace[622103462] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"945.037855ms","start":"2026-07-17T11:06:41.615638Z","end":"2026-07-17T11:06:42.560675Z","steps":["trace[622103462] 'process raft request' (duration: 944.290005ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:42.560712Z","caller":"traceutil/trace.go:171","msg":"trace[1667585005] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"968.994515ms","start":"2026-07-17T11:06:41.591704Z","end":"2026-07-17T11:06:42.560698Z","steps":["trace[1667585005] 'process raft request' (duration: 967.896847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.56076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.615561Z","time spent":"945.164868ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:06:42.560774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.591676Z","time spent":"969.064636ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.560951Z","caller":"traceutil/trace.go:171","msg":"trace[1368642347] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"912.985113ms","start":"2026-07-17T11:06:41.647954Z","end":"2026-07-17T11:06:42.560939Z","steps":["trace[1368642347] 'process raft request' (duration: 912.055909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.561013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.64792Z","time spent":"913.058105ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.561113Z","caller":"traceutil/trace.go:171","msg":"trace[1131191368] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.010525307s","start":"2026-07-17T11:06:41.549474Z","end":"2026-07-17T11:06:42.559999Z","steps":["trace[1131191368] 'process raft request' (duration: 1.010039825s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.561178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.549462Z","time spent":"1.011673907s","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.561225Z","caller":"traceutil/trace.go:171","msg":"trace[1519230328] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:523; }","duration":"910.428166ms","start":"2026-07-17T11:06:41.650777Z","end":"2026-07-17T11:06:42.561205Z","steps":["trace[1519230328] 'read index received' (duration: 401.578008ms)","trace[1519230328] 'applied index is now lower than readState.Index' (duration: 508.848898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:42.561339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.548149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:42.561375Z","caller":"traceutil/trace.go:171","msg":"trace[817254530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"910.623711ms","start":"2026-07-17T11:06:41.650737Z","end":"2026-07-17T11:06:42.561361Z","steps":["trace[817254530] 'agreement among raft nodes before linearized reading' (duration: 910.531608ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:42.561376Z","caller":"traceutil/trace.go:171","msg":"trace[1105874082] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"796.183642ms","start":"2026-07-17T11:06:41.76518Z","end":"2026-07-17T11:06:42.561363Z","steps":["trace[1105874082] 'process raft request' (duration: 794.940399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.561406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.650725Z","time spent":"910.675172ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:06:42.561445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:41.765134Z","time spent":"796.281534ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.566986Z","caller":"traceutil/trace.go:171","msg":"trace[408949664] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"490.001212ms","start":"2026-07-17T11:06:42.076953Z","end":"2026-07-17T11:06:42.566954Z","steps":["trace[408949664] 'process raft request' (duration: 489.456538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.567099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:42.076924Z","time spent":"490.120535ms","remote":"127.0.0.1:50848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:42.567304Z","caller":"traceutil/trace.go:171","msg":"trace[455117099] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"487.747293ms","start":"2026-07-17T11:06:42.079538Z","end":"2026-07-17T11:06:42.567286Z","steps":["trace[455117099] 'process raft request' (duration: 486.975983ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:42.568194Z","caller":"traceutil/trace.go:171","msg":"trace[1075790636] transaction","detail":"{read_only:false; number_of_response:1; response_revision:512; }","duration":"488.417901ms","start":"2026-07-17T11:06:42.07976Z","end":"2026-07-17T11:06:42.568178Z","steps":["trace[1075790636] 'process raft request' (duration: 486.827739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:42.568262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:42.079749Z","time spent":"488.476243ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:06:42.570632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:42.079528Z","time spent":"487.814575ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:06:43.305268Z","caller":"traceutil/trace.go:171","msg":"trace[878755534] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"103.131402ms","start":"2026-07-17T11:06:43.202115Z","end":"2026-07-17T11:06:43.305246Z","steps":["trace[878755534] 'process raft request' (duration: 92.329988ms)","trace[878755534] 'compare' (duration: 10.384793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:43.71385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.087751ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:06:43.713953Z","caller":"traceutil/trace.go:171","msg":"trace[1841182017] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"176.87629ms","start":"2026-07-17T11:06:43.537061Z","end":"2026-07-17T11:06:43.713938Z","steps":["trace[1841182017] 'process raft request' (duration: 11.632715ms)","trace[1841182017] 'compare' (duration: 164.985628ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:44.14905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147841688,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:06:44.478867Z","caller":"traceutil/trace.go:171","msg":"trace[1506173450] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"888.036567ms","start":"2026-07-17T11:06:43.590801Z","end":"2026-07-17T11:06:44.478837Z","steps":["trace[1506173450] 'process raft request' (duration: 837.205151ms)","trace[1506173450] 'compare' (duration: 50.369104ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:44.479417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:43.590762Z","time spent":"888.181421ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T11:06:44.487192Z","caller":"traceutil/trace.go:171","msg":"trace[720798950] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"838.501516ms","start":"2026-07-17T11:06:43.64863Z","end":"2026-07-17T11:06:44.487132Z","steps":["trace[720798950] 'read index received' (duration: 779.392692ms)","trace[720798950] 'applied index is now lower than readState.Index' (duration: 59.107664ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:44.487345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.691092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:44.487376Z","caller":"traceutil/trace.go:171","msg":"trace[1870826413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"838.745123ms","start":"2026-07-17T11:06:43.648622Z","end":"2026-07-17T11:06:44.487367Z","steps":["trace[1870826413] 'agreement among raft nodes before linearized reading' (duration: 838.63829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:44.487413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:43.648548Z","time spent":"838.858365ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:06:44.48762Z","caller":"traceutil/trace.go:171","msg":"trace[253412965] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"750.362087ms","start":"2026-07-17T11:06:43.737245Z","end":"2026-07-17T11:06:44.487607Z","steps":["trace[253412965] 'process raft request' (duration: 749.794112ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:44.487637Z","caller":"traceutil/trace.go:171","msg":"trace[137243618] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"784.744781ms","start":"2026-07-17T11:06:43.702866Z","end":"2026-07-17T11:06:44.487611Z","steps":["trace[137243618] 'process raft request' (duration: 779.918284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:44.487737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:43.737178Z","time spent":"750.4677ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:06:44.487754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:43.702836Z","time spent":"784.860004ms","remote":"127.0.0.1:50356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:06:49.868622Z","caller":"traceutil/trace.go:171","msg":"trace[990765163] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"105.536734ms","start":"2026-07-17T11:06:49.761975Z","end":"2026-07-17T11:06:49.867512Z","steps":["trace[990765163] 'process raft request' (duration: 104.519268ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:50.458126Z","caller":"traceutil/trace.go:171","msg":"trace[1437907487] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"114.400708ms","start":"2026-07-17T11:06:50.343654Z","end":"2026-07-17T11:06:50.458054Z","steps":["trace[1437907487] 'process raft request' (duration: 75.336361ms)","trace[1437907487] 'compare' (duration: 38.738518ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:50.482847Z","caller":"traceutil/trace.go:171","msg":"trace[1395168487] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"127.473372ms","start":"2026-07-17T11:06:50.355341Z","end":"2026-07-17T11:06:50.482814Z","steps":["trace[1395168487] 'process raft request' (duration: 127.217685ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:50.733408Z","caller":"traceutil/trace.go:171","msg":"trace[801136327] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"309.951048ms","start":"2026-07-17T11:06:50.423429Z","end":"2026-07-17T11:06:50.73338Z","steps":["trace[801136327] 'process raft request' (duration: 308.993893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:50.733708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:50.423384Z","time spent":"310.117492ms","remote":"127.0.0.1:50404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:06:50.733683Z","caller":"traceutil/trace.go:171","msg":"trace[1969507117] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"271.94683ms","start":"2026-07-17T11:06:50.461697Z","end":"2026-07-17T11:06:50.733644Z","steps":["trace[1969507117] 'read index received' (duration: 20.830588ms)","trace[1969507117] 'applied index is now lower than readState.Index' (duration: 251.113452ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:50.734054Z","caller":"traceutil/trace.go:171","msg":"trace[400982595] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"273.47779ms","start":"2026-07-17T11:06:50.460523Z","end":"2026-07-17T11:06:50.734Z","steps":["trace[400982595] 'process raft request' (duration: 272.785811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:50.734222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.499795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-17T11:06:50.734315Z","caller":"traceutil/trace.go:171","msg":"trace[1119985277] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:611; }","duration":"272.637969ms","start":"2026-07-17T11:06:50.46166Z","end":"2026-07-17T11:06:50.734298Z","steps":["trace[1119985277] 'agreement among raft nodes before linearized reading' (duration: 272.449344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:51.170636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.759011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:06:51.17076Z","caller":"traceutil/trace.go:171","msg":"trace[1613519764] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"304.649589ms","start":"2026-07-17T11:06:50.866079Z","end":"2026-07-17T11:06:51.170729Z","steps":["trace[1613519764] 'read index received' (duration: 201.708323ms)","trace[1613519764] 'applied index is now lower than readState.Index' (duration: 102.939836ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:51.170774Z","caller":"traceutil/trace.go:171","msg":"trace[1090004855] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"375.267966ms","start":"2026-07-17T11:06:50.795477Z","end":"2026-07-17T11:06:51.170745Z","steps":["trace[1090004855] 'process raft request' (duration: 272.304099ms)","trace[1090004855] 'compare' (duration: 102.609898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:06:51.170864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:50.795443Z","time spent":"375.38271ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:06:51.171014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.930517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:06:51.171046Z","caller":"traceutil/trace.go:171","msg":"trace[1335058935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"305.008409ms","start":"2026-07-17T11:06:50.866027Z","end":"2026-07-17T11:06:51.171036Z","steps":["trace[1335058935] 'agreement among raft nodes before linearized reading' (duration: 304.815734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:51.171076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:50.866004Z","time spent":"305.065131ms","remote":"127.0.0.1:50572","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-07-17T11:06:51.171182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.494516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T11:06:51.171264Z","caller":"traceutil/trace.go:171","msg":"trace[1214812764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"304.625849ms","start":"2026-07-17T11:06:50.866612Z","end":"2026-07-17T11:06:51.171238Z","steps":["trace[1214812764] 'agreement among raft nodes before linearized reading' (duration: 304.377813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:51.17127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.096866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:06:51.171312Z","caller":"traceutil/trace.go:171","msg":"trace[1148017150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"304.156947ms","start":"2026-07-17T11:06:50.867143Z","end":"2026-07-17T11:06:51.1713Z","steps":["trace[1148017150] 'agreement among raft nodes before linearized reading' (duration: 304.046844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:51.171313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:50.866587Z","time spent":"304.717022ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:06:51.171341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:50.867138Z","time spent":"304.196388ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:06:51.171398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.623739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T11:06:51.171441Z","caller":"traceutil/trace.go:171","msg":"trace[311420214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"304.67244ms","start":"2026-07-17T11:06:50.866754Z","end":"2026-07-17T11:06:51.171426Z","steps":["trace[311420214] 'agreement among raft nodes before linearized reading' (duration: 304.575918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:51.171492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:50.866749Z","time spent":"304.733732ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:06:53.147902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147841852,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:06:53.366524Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.721618161s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:06:53.368567Z","caller":"traceutil/trace.go:171","msg":"trace[1231319135] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"720.722317ms","start":"2026-07-17T11:06:52.64726Z","end":"2026-07-17T11:06:53.367982Z","steps":["trace[1231319135] 'read index received' (duration: 719.588436ms)","trace[1231319135] 'applied index is now lower than readState.Index' (duration: 1.132681ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:53.368108Z","caller":"traceutil/trace.go:171","msg":"trace[453595086] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.724789756s","start":"2026-07-17T11:06:51.643286Z","end":"2026-07-17T11:06:53.368075Z","steps":["trace[453595086] 'process raft request' (duration: 1.723890583s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:53.368756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.462806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:53.368888Z","caller":"traceutil/trace.go:171","msg":"trace[1139688305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"721.60724ms","start":"2026-07-17T11:06:52.647203Z","end":"2026-07-17T11:06:53.36881Z","steps":["trace[1139688305] 'agreement among raft nodes before linearized reading' (duration: 720.983714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:53.368942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:52.647178Z","time spent":"721.751813ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:06:53.368945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:06:51.643235Z","time spent":"1.725567026s","remote":"127.0.0.1:50404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:06:53.473208Z","caller":"traceutil/trace.go:171","msg":"trace[1479727699] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"104.508617ms","start":"2026-07-17T11:06:53.368648Z","end":"2026-07-17T11:06:53.473157Z","steps":["trace[1479727699] 'read index received' (duration: 100.072381ms)","trace[1479727699] 'applied index is now lower than readState.Index' (duration: 4.434976ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:53.473573Z","caller":"traceutil/trace.go:171","msg":"trace[1597928902] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"137.061204ms","start":"2026-07-17T11:06:53.336491Z","end":"2026-07-17T11:06:53.473552Z","steps":["trace[1597928902] 'process raft request' (duration: 132.254648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:53.473843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.903092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-17T11:06:53.47384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.920422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:06:53.473931Z","caller":"traceutil/trace.go:171","msg":"trace[1893781369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"244.050756ms","start":"2026-07-17T11:06:53.229859Z","end":"2026-07-17T11:06:53.47391Z","steps":["trace[1893781369] 'agreement among raft nodes before linearized reading' (duration: 243.758218ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:53.473971Z","caller":"traceutil/trace.go:171","msg":"trace[1134594519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"244.1582ms","start":"2026-07-17T11:06:53.229766Z","end":"2026-07-17T11:06:53.473924Z","steps":["trace[1134594519] 'agreement among raft nodes before linearized reading' (duration: 243.807351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:53.473962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.956148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-17T11:06:53.473883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.086696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T11:06:53.474078Z","caller":"traceutil/trace.go:171","msg":"trace[1053223812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"278.101172ms","start":"2026-07-17T11:06:53.195932Z","end":"2026-07-17T11:06:53.474033Z","steps":["trace[1053223812] 'agreement among raft nodes before linearized reading' (duration: 277.852155ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:53.474127Z","caller":"traceutil/trace.go:171","msg":"trace[1139508086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"244.370335ms","start":"2026-07-17T11:06:53.229738Z","end":"2026-07-17T11:06:53.474108Z","steps":["trace[1139508086] 'agreement among raft nodes before linearized reading' (duration: 244.022886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:53.479833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.821353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:53.479891Z","caller":"traceutil/trace.go:171","msg":"trace[213119581] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"105.916034ms","start":"2026-07-17T11:06:53.373961Z","end":"2026-07-17T11:06:53.479877Z","steps":["trace[213119581] 'agreement among raft nodes before linearized reading' (duration: 105.783221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:54.894479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.906599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:54.894584Z","caller":"traceutil/trace.go:171","msg":"trace[608793838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"245.142005ms","start":"2026-07-17T11:06:54.649411Z","end":"2026-07-17T11:06:54.894553Z","steps":["trace[608793838] 'range keys from in-memory index tree' (duration: 244.823467ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:06:58.827532Z","caller":"traceutil/trace.go:171","msg":"trace[1653905423] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"177.002854ms","start":"2026-07-17T11:06:58.650498Z","end":"2026-07-17T11:06:58.827501Z","steps":["trace[1653905423] 'read index received' (duration: 176.779558ms)","trace[1653905423] 'applied index is now lower than readState.Index' (duration: 222.106µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:06:58.827669Z","caller":"traceutil/trace.go:171","msg":"trace[1590837595] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"238.778819ms","start":"2026-07-17T11:06:58.588874Z","end":"2026-07-17T11:06:58.827653Z","steps":["trace[1590837595] 'process raft request' (duration: 238.406349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:06:58.827999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.479996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:06:58.828044Z","caller":"traceutil/trace.go:171","msg":"trace[125919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"177.599591ms","start":"2026-07-17T11:06:58.650433Z","end":"2026-07-17T11:06:58.828032Z","steps":["trace[125919] 'agreement among raft nodes before linearized reading' (duration: 177.481638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.926037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276859107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:07:10.926074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.208851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T11:07:10.92615Z","caller":"traceutil/trace.go:171","msg":"trace[194254442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"1.277018062s","start":"2026-07-17T11:07:09.649114Z","end":"2026-07-17T11:07:10.926132Z","steps":["trace[194254442] 'range keys from in-memory index tree' (duration: 1.276724523s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:10.926166Z","caller":"traceutil/trace.go:171","msg":"trace[1478597562] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:781; }","duration":"602.372396ms","start":"2026-07-17T11:07:10.323771Z","end":"2026-07-17T11:07:10.926143Z","steps":["trace[1478597562] 'range keys from in-memory index tree' (duration: 602.009116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.926189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.649094Z","time spent":"1.277086964s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:07:10.926219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:10.323746Z","time spent":"602.459859ms","remote":"127.0.0.1:50326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-07-17T11:07:10.926364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267239396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-17T11:07:10.926085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041696105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:07:10.926426Z","caller":"traceutil/trace.go:171","msg":"trace[1926942158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"1.267339818s","start":"2026-07-17T11:07:09.659066Z","end":"2026-07-17T11:07:10.926406Z","steps":["trace[1926942158] 'range keys from in-memory index tree' (duration: 1.267034361s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:10.926492Z","caller":"traceutil/trace.go:171","msg":"trace[1512688632] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:781; }","duration":"1.042188569s","start":"2026-07-17T11:07:09.884292Z","end":"2026-07-17T11:07:10.926481Z","steps":["trace[1512688632] 'count revisions from in-memory index tree' (duration: 1.041586402s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.926465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259974614s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:07:10.926579Z","caller":"traceutil/trace.go:171","msg":"trace[457148846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"1.260128008s","start":"2026-07-17T11:07:09.666428Z","end":"2026-07-17T11:07:10.926556Z","steps":["trace[457148846] 'range keys from in-memory index tree' (duration: 1.259728338s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.926637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.66642Z","time spent":"1.2602038s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:07:10.926515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.659023Z","time spent":"1.267480452s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:07:10.926742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259879202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-17T11:07:10.926533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.88426Z","time spent":"1.042257051s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-07-17T11:07:10.926872Z","caller":"traceutil/trace.go:171","msg":"trace[948778154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"1.260043346s","start":"2026-07-17T11:07:09.666793Z","end":"2026-07-17T11:07:10.926837Z","steps":["trace[948778154] 'range keys from in-memory index tree' (duration: 1.259615665s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.926942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.666788Z","time spent":"1.260139169s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:07:10.927003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26514883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:07:10.927049Z","caller":"traceutil/trace.go:171","msg":"trace[750694252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"1.265209323s","start":"2026-07-17T11:07:09.661826Z","end":"2026-07-17T11:07:10.927035Z","steps":["trace[750694252] 'range keys from in-memory index tree' (duration: 1.265060149s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.927084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.661818Z","time spent":"1.265258144s","remote":"127.0.0.1:50572","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-07-17T11:07:10.926147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251218524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:07:10.927241Z","caller":"traceutil/trace.go:171","msg":"trace[1582094051] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:781; }","duration":"1.252313512s","start":"2026-07-17T11:07:09.674905Z","end":"2026-07-17T11:07:10.927218Z","steps":["trace[1582094051] 'count revisions from in-memory index tree' (duration: 1.251141972s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:10.927288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:09.674897Z","time spent":"1.252373285s","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-07-17T11:07:11.130064Z","caller":"traceutil/trace.go:171","msg":"trace[1469720916] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"201.654212ms","start":"2026-07-17T11:07:10.928378Z","end":"2026-07-17T11:07:11.130033Z","steps":["trace[1469720916] 'read index received' (duration: 200.03281ms)","trace[1469720916] 'applied index is now lower than readState.Index' (duration: 1.619302ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:07:11.13015Z","caller":"traceutil/trace.go:171","msg":"trace[508403513] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"643.260591ms","start":"2026-07-17T11:07:10.486847Z","end":"2026-07-17T11:07:11.130107Z","steps":["trace[508403513] 'process raft request' (duration: 641.455044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:11.130387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.963611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:07:11.130435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:10.486816Z","time spent":"643.509927ms","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:07:11.130511Z","caller":"traceutil/trace.go:171","msg":"trace[664940637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:782; }","duration":"202.146815ms","start":"2026-07-17T11:07:10.928333Z","end":"2026-07-17T11:07:11.13048Z","steps":["trace[664940637] 'agreement among raft nodes before linearized reading' (duration: 201.885679ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:11.134209Z","caller":"traceutil/trace.go:171","msg":"trace[1350544017] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"202.270147ms","start":"2026-07-17T11:07:10.931922Z","end":"2026-07-17T11:07:11.134192Z","steps":["trace[1350544017] 'process raft request' (duration: 201.927919ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:11.134824Z","caller":"traceutil/trace.go:171","msg":"trace[1749338043] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"202.773881ms","start":"2026-07-17T11:07:10.932017Z","end":"2026-07-17T11:07:11.134791Z","steps":["trace[1749338043] 'process raft request' (duration: 202.397551ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:11.135105Z","caller":"traceutil/trace.go:171","msg":"trace[730923051] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"203.078689ms","start":"2026-07-17T11:07:10.932016Z","end":"2026-07-17T11:07:11.135095Z","steps":["trace[730923051] 'process raft request' (duration: 202.024671ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:11.13518Z","caller":"traceutil/trace.go:171","msg":"trace[1644741610] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"203.022758ms","start":"2026-07-17T11:07:10.932142Z","end":"2026-07-17T11:07:11.135164Z","steps":["trace[1644741610] 'process raft request' (duration: 202.32534ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:46.353928Z","caller":"traceutil/trace.go:171","msg":"trace[195259596] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"493.995542ms","start":"2026-07-17T11:07:45.859901Z","end":"2026-07-17T11:07:46.353896Z","steps":["trace[195259596] 'read index received' (duration: 493.657343ms)","trace[195259596] 'applied index is now lower than readState.Index' (duration: 337.049µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:07:46.354022Z","caller":"traceutil/trace.go:171","msg":"trace[903606429] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"531.294744ms","start":"2026-07-17T11:07:45.822668Z","end":"2026-07-17T11:07:46.353963Z","steps":["trace[903606429] 'process raft request' (duration: 531.003016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:46.354082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.158516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:07:46.354118Z","caller":"traceutil/trace.go:171","msg":"trace[293224751] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1001; }","duration":"494.286349ms","start":"2026-07-17T11:07:45.859821Z","end":"2026-07-17T11:07:46.354107Z","steps":["trace[293224751] 'agreement among raft nodes before linearized reading' (duration: 494.188627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:46.354194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:45.859786Z","time spent":"494.358801ms","remote":"127.0.0.1:50470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:07:46.35432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:45.822635Z","time spent":"531.486438ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:07:47.202621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.080218ms","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-07-17T11:07:47.202754Z","caller":"traceutil/trace.go:171","msg":"trace[1345292796] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1001; }","duration":"498.311385ms","start":"2026-07-17T11:07:46.704405Z","end":"2026-07-17T11:07:47.202717Z","steps":["trace[1345292796] 'count revisions from in-memory index tree' (duration: 497.922635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:47.202837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:46.704371Z","time spent":"498.448778ms","remote":"127.0.0.1:50456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:07:47.203201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.41361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:07:47.203232Z","caller":"traceutil/trace.go:171","msg":"trace[1940082205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"555.503633ms","start":"2026-07-17T11:07:46.647718Z","end":"2026-07-17T11:07:47.203221Z","steps":["trace[1940082205] 'range keys from in-memory index tree' (duration: 555.324308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:47.203344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:46.647696Z","time spent":"555.637545ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:07:47.925577Z","caller":"traceutil/trace.go:171","msg":"trace[1626107720] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"564.431275ms","start":"2026-07-17T11:07:47.361117Z","end":"2026-07-17T11:07:47.925549Z","steps":["trace[1626107720] 'process raft request' (duration: 564.164918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:47.925771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.361076Z","time spent":"564.590969ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:07:48.707402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.922028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:07:48.707593Z","caller":"traceutil/trace.go:171","msg":"trace[1474794230] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"1.346112521s","start":"2026-07-17T11:07:47.361462Z","end":"2026-07-17T11:07:48.707574Z","steps":["trace[1474794230] 'process raft request' (duration: 1.346040179s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:48.707648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.36144Z","time spent":"1.346183463s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:07:48.707722Z","caller":"traceutil/trace.go:171","msg":"trace[49161718] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.346311456s","start":"2026-07-17T11:07:47.361352Z","end":"2026-07-17T11:07:48.707663Z","steps":["trace[49161718] 'process raft request' (duration: 1.173009278s)","trace[49161718] 'compare' (duration: 172.720804ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:07:48.707735Z","caller":"traceutil/trace.go:171","msg":"trace[1893474652] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1046; }","duration":"1.277219899s","start":"2026-07-17T11:07:47.430472Z","end":"2026-07-17T11:07:48.707692Z","steps":["trace[1893474652] 'read index received' (duration: 494.772543ms)","trace[1893474652] 'applied index is now lower than readState.Index' (duration: 782.445586ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:07:48.707845Z","caller":"traceutil/trace.go:171","msg":"trace[1518734665] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.346011299s","start":"2026-07-17T11:07:47.361824Z","end":"2026-07-17T11:07:48.707835Z","steps":["trace[1518734665] 'process raft request' (duration: 1.345718701s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:48.707852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.361321Z","time spent":"1.34646043s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:07:48.707884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.361813Z","time spent":"1.34605222s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:07:48.708042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277552437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:07:48.708082Z","caller":"traceutil/trace.go:171","msg":"trace[67535469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"1.277662211s","start":"2026-07-17T11:07:47.430409Z","end":"2026-07-17T11:07:48.708071Z","steps":["trace[67535469] 'agreement among raft nodes before linearized reading' (duration: 1.277458686s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:48.70812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.430372Z","time spent":"1.277738582s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:07:48.710813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128819808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:07:48.710926Z","caller":"traceutil/trace.go:171","msg":"trace[1941713414] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1006; }","duration":"1.128966021s","start":"2026-07-17T11:07:47.581937Z","end":"2026-07-17T11:07:48.710903Z","steps":["trace[1941713414] 'agreement among raft nodes before linearized reading' (duration: 1.128743176s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:07:48.710996Z","caller":"traceutil/trace.go:171","msg":"trace[1083789493] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"350.450797ms","start":"2026-07-17T11:07:48.360484Z","end":"2026-07-17T11:07:48.710935Z","steps":["trace[1083789493] 'process raft request' (duration: 349.863102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:48.711018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061790724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:07:48.711091Z","caller":"traceutil/trace.go:171","msg":"trace[1178618509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"1.061865776s","start":"2026-07-17T11:07:47.649206Z","end":"2026-07-17T11:07:48.711072Z","steps":["trace[1178618509] 'agreement among raft nodes before linearized reading' (duration: 1.061739543s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:48.711136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:48.360452Z","time spent":"350.618812ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:07:48.711151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.649118Z","time spent":"1.062021801s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:07:48.711072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.581915Z","time spent":"1.129114536s","remote":"127.0.0.1:50392","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-07-17T11:07:48.711219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106464166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:07:48.712427Z","caller":"traceutil/trace.go:171","msg":"trace[205089393] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1006; }","duration":"1.10777092s","start":"2026-07-17T11:07:47.604627Z","end":"2026-07-17T11:07:48.712398Z","steps":["trace[205089393] 'agreement among raft nodes before linearized reading' (duration: 1.106500776s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:07:48.712646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:07:47.604597Z","time spent":"1.107879024s","remote":"127.0.0.1:50570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:08:17.104727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.154765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:08:17.105062Z","caller":"traceutil/trace.go:171","msg":"trace[557502794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"454.540065ms","start":"2026-07-17T11:08:16.650474Z","end":"2026-07-17T11:08:17.105014Z","steps":["trace[557502794] 'range keys from in-memory index tree' (duration: 454.046723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.105137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:16.650441Z","time spent":"454.669408ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:08:17.418215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147842959,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:08:17.610477Z","caller":"traceutil/trace.go:171","msg":"trace[2024160088] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"692.788095ms","start":"2026-07-17T11:08:16.917663Z","end":"2026-07-17T11:08:17.610451Z","steps":["trace[2024160088] 'read index received' (duration: 692.57267ms)","trace[2024160088] 'applied index is now lower than readState.Index' (duration: 214.605µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:08:17.610575Z","caller":"traceutil/trace.go:171","msg":"trace[447309471] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"712.417485ms","start":"2026-07-17T11:08:16.898111Z","end":"2026-07-17T11:08:17.610528Z","steps":["trace[447309471] 'process raft request' (duration: 712.169939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.610734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.062402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T11:08:17.610772Z","caller":"traceutil/trace.go:171","msg":"trace[106548442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"693.152544ms","start":"2026-07-17T11:08:16.917609Z","end":"2026-07-17T11:08:17.610762Z","steps":["trace[106548442] 'agreement among raft nodes before linearized reading' (duration: 692.944839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.610798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:16.917593Z","time spent":"693.198055ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:08:17.610805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.82816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:08:17.610761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.730816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:08:17.610844Z","caller":"traceutil/trace.go:171","msg":"trace[146079476] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1096; }","duration":"156.929303ms","start":"2026-07-17T11:08:17.453904Z","end":"2026-07-17T11:08:17.610833Z","steps":["trace[146079476] 'agreement among raft nodes before linearized reading' (duration: 156.853911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.610844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.331123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:08:17.610904Z","caller":"traceutil/trace.go:171","msg":"trace[1212898695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"503.949661ms","start":"2026-07-17T11:08:17.106924Z","end":"2026-07-17T11:08:17.610874Z","steps":["trace[1212898695] 'agreement among raft nodes before linearized reading' (duration: 503.734786ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:17.610903Z","caller":"traceutil/trace.go:171","msg":"trace[674841213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"687.421495ms","start":"2026-07-17T11:08:16.923465Z","end":"2026-07-17T11:08:17.610887Z","steps":["trace[674841213] 'agreement among raft nodes before linearized reading' (duration: 687.183209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.610825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.180548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:08:17.610965Z","caller":"traceutil/trace.go:171","msg":"trace[464468343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"662.392514ms","start":"2026-07-17T11:08:16.948563Z","end":"2026-07-17T11:08:17.610955Z","steps":["trace[464468343] 'agreement among raft nodes before linearized reading' (duration: 662.120677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.610968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:17.106898Z","time spent":"504.057324ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:08:17.610968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:16.923407Z","time spent":"687.548198ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:17.611003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:16.948529Z","time spent":"662.466476ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:17.611269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:16.898078Z","time spent":"712.57882ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:17.611429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.783942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:08:17.611534Z","caller":"traceutil/trace.go:171","msg":"trace[1019734364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1096; }","duration":"686.351477ms","start":"2026-07-17T11:08:16.925152Z","end":"2026-07-17T11:08:17.611504Z","steps":["trace[1019734364] 'agreement among raft nodes before linearized reading' (duration: 685.595088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.611593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:16.925135Z","time spent":"686.445469ms","remote":"127.0.0.1:50572","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-07-17T11:08:17.943776Z","caller":"traceutil/trace.go:171","msg":"trace[593907106] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"327.624254ms","start":"2026-07-17T11:08:17.616116Z","end":"2026-07-17T11:08:17.94374Z","steps":["trace[593907106] 'process raft request' (duration: 284.683456ms)","trace[593907106] 'compare' (duration: 42.516346ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:17.944295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:17.616089Z","time spent":"328.013054ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:17.978083Z","caller":"traceutil/trace.go:171","msg":"trace[418205373] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"356.688491ms","start":"2026-07-17T11:08:17.621355Z","end":"2026-07-17T11:08:17.978044Z","steps":["trace[418205373] 'process raft request' (duration: 356.635139ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:17.978137Z","caller":"traceutil/trace.go:171","msg":"trace[924485376] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"360.562891ms","start":"2026-07-17T11:08:17.617531Z","end":"2026-07-17T11:08:17.978094Z","steps":["trace[924485376] 'process raft request' (duration: 360.423717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.978377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:17.617523Z","time spent":"360.697574ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:17.97839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:17.621344Z","time spent":"356.852325ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:17.978592Z","caller":"traceutil/trace.go:171","msg":"trace[389116886] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"361.155516ms","start":"2026-07-17T11:08:17.617415Z","end":"2026-07-17T11:08:17.978571Z","steps":["trace[389116886] 'process raft request' (duration: 360.357826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:17.978664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:17.617382Z","time spent":"361.252529ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:20.150313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147842971,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:08:20.24202Z","caller":"traceutil/trace.go:171","msg":"trace[1745643617] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"624.735744ms","start":"2026-07-17T11:08:19.617247Z","end":"2026-07-17T11:08:20.241983Z","steps":["trace[1745643617] 'process raft request' (duration: 624.458377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:20.242176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.28062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:08:20.242213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:19.617201Z","time spent":"624.918209ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:20.242282Z","caller":"traceutil/trace.go:171","msg":"trace[725431788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"592.467713ms","start":"2026-07-17T11:08:19.649793Z","end":"2026-07-17T11:08:20.242261Z","steps":["trace[725431788] 'agreement among raft nodes before linearized reading' (duration: 592.279639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:20.242325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:19.64977Z","time spent":"592.543926ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:08:20.24253Z","caller":"traceutil/trace.go:171","msg":"trace[644498106] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"592.101974ms","start":"2026-07-17T11:08:19.649836Z","end":"2026-07-17T11:08:20.241938Z","steps":["trace[644498106] 'read index received' (duration: 591.673563ms)","trace[644498106] 'applied index is now lower than readState.Index' (duration: 426.871µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:20.242844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.954557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:08:20.242916Z","caller":"traceutil/trace.go:171","msg":"trace[1643516291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"259.07503ms","start":"2026-07-17T11:08:19.983823Z","end":"2026-07-17T11:08:20.242898Z","steps":["trace[1643516291] 'agreement among raft nodes before linearized reading' (duration: 258.894385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:20.242976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.725447ms","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-07-17T11:08:20.24287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.682761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T11:08:20.243026Z","caller":"traceutil/trace.go:171","msg":"trace[973022051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"259.8384ms","start":"2026-07-17T11:08:19.983175Z","end":"2026-07-17T11:08:20.243014Z","steps":["trace[973022051] 'agreement among raft nodes before linearized reading' (duration: 259.715167ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:20.243067Z","caller":"traceutil/trace.go:171","msg":"trace[1749354068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"293.937488ms","start":"2026-07-17T11:08:19.949092Z","end":"2026-07-17T11:08:20.24303Z","steps":["trace[1749354068] 'agreement among raft nodes before linearized reading' (duration: 293.588129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:20.242852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.294446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:08:20.243246Z","caller":"traceutil/trace.go:171","msg":"trace[150301268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"259.705046ms","start":"2026-07-17T11:08:19.983515Z","end":"2026-07-17T11:08:20.24322Z","steps":["trace[150301268] 'agreement among raft nodes before linearized reading' (duration: 259.213763ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:20.808044Z","caller":"traceutil/trace.go:171","msg":"trace[828369058] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"558.930692ms","start":"2026-07-17T11:08:20.24909Z","end":"2026-07-17T11:08:20.808021Z","steps":["trace[828369058] 'process raft request' (duration: 558.825019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:20.808216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.249064Z","time spent":"559.061105ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:20.80821Z","caller":"traceutil/trace.go:171","msg":"trace[352870652] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"559.074425ms","start":"2026-07-17T11:08:20.24909Z","end":"2026-07-17T11:08:20.808164Z","steps":["trace[352870652] 'process raft request' (duration: 542.791672ms)","trace[352870652] 'compare' (duration: 15.556015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:20.80839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.249062Z","time spent":"559.219359ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:21.183712Z","caller":"traceutil/trace.go:171","msg":"trace[308866536] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"934.343968ms","start":"2026-07-17T11:08:20.249324Z","end":"2026-07-17T11:08:21.183668Z","steps":["trace[308866536] 'process raft request' (duration: 914.888982ms)","trace[308866536] 'compare' (duration: 19.118147ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:08:21.183771Z","caller":"traceutil/trace.go:171","msg":"trace[1571394995] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"934.42102ms","start":"2026-07-17T11:08:20.249325Z","end":"2026-07-17T11:08:21.183746Z","steps":["trace[1571394995] 'process raft request' (duration: 934.197314ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:21.183843Z","caller":"traceutil/trace.go:171","msg":"trace[1417786327] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"933.21772ms","start":"2026-07-17T11:08:20.250599Z","end":"2026-07-17T11:08:21.183817Z","steps":["trace[1417786327] 'process raft request' (duration: 932.997834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:21.183947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.366252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T11:08:21.184039Z","caller":"traceutil/trace.go:171","msg":"trace[1821634321] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1107; }","duration":"858.489105ms","start":"2026-07-17T11:08:20.325518Z","end":"2026-07-17T11:08:21.184007Z","steps":["trace[1821634321] 'agreement among raft nodes before linearized reading' (duration: 858.267339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:21.183958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.249294Z","time spent":"934.547443ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:21.184088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.325494Z","time spent":"858.580767ms","remote":"127.0.0.1:50326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-07-17T11:08:21.183713Z","caller":"traceutil/trace.go:171","msg":"trace[651996010] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1153; }","duration":"858.113775ms","start":"2026-07-17T11:08:20.325565Z","end":"2026-07-17T11:08:21.183679Z","steps":["trace[651996010] 'read index received' (duration: 466.279151ms)","trace[651996010] 'applied index is now lower than readState.Index' (duration: 391.832854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:21.184429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.757805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T11:08:21.184481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.250591Z","time spent":"933.301612ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:21.184502Z","caller":"traceutil/trace.go:171","msg":"trace[1075870076] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1107; }","duration":"827.891449ms","start":"2026-07-17T11:08:20.356588Z","end":"2026-07-17T11:08:21.184479Z","steps":["trace[1075870076] 'agreement among raft nodes before linearized reading' (duration: 827.718934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:21.184563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.356562Z","time spent":"827.989762ms","remote":"127.0.0.1:50668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:08:21.184568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.06614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:08:21.184626Z","caller":"traceutil/trace.go:171","msg":"trace[164959934] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1107; }","duration":"337.185102ms","start":"2026-07-17T11:08:20.847426Z","end":"2026-07-17T11:08:21.184611Z","steps":["trace[164959934] 'agreement among raft nodes before linearized reading' (duration: 337.08537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:21.184662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.847401Z","time spent":"337.251914ms","remote":"127.0.0.1:59412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:08:21.185519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:20.249305Z","time spent":"934.508953ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:21.605919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.87523ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-17T11:08:21.606339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:21.185888Z","time spent":"420.446249ms","remote":"127.0.0.1:50326","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T11:08:22.148366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147842987,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:08:22.252271Z","caller":"traceutil/trace.go:171","msg":"trace[42118348] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"604.456796ms","start":"2026-07-17T11:08:21.647788Z","end":"2026-07-17T11:08:22.252245Z","steps":["trace[42118348] 'read index received' (duration: 582.364762ms)","trace[42118348] 'applied index is now lower than readState.Index' (duration: 22.091084ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:22.252457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.646591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:08:22.25249Z","caller":"traceutil/trace.go:171","msg":"trace[267304737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"604.741873ms","start":"2026-07-17T11:08:21.64774Z","end":"2026-07-17T11:08:22.252481Z","steps":["trace[267304737] 'agreement among raft nodes before linearized reading' (duration: 604.633271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:22.252519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:21.647717Z","time spent":"604.794035ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:08:22.252538Z","caller":"traceutil/trace.go:171","msg":"trace[1380878450] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"644.518439ms","start":"2026-07-17T11:08:21.607775Z","end":"2026-07-17T11:08:22.252294Z","steps":["trace[1380878450] 'process raft request' (duration: 622.636199ms)","trace[1380878450] 'compare' (duration: 21.676453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:22.252834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:21.607738Z","time spent":"644.982001ms","remote":"127.0.0.1:50326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:22.284449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.898937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:08:22.284541Z","caller":"traceutil/trace.go:171","msg":"trace[2082285912] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1109; }","duration":"113.05543ms","start":"2026-07-17T11:08:22.171458Z","end":"2026-07-17T11:08:22.284514Z","steps":["trace[2082285912] 'agreement among raft nodes before linearized reading' (duration: 112.887906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:23.069661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.510254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T11:08:23.069761Z","caller":"traceutil/trace.go:171","msg":"trace[1739089939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"256.694127ms","start":"2026-07-17T11:08:22.813046Z","end":"2026-07-17T11:08:23.06974Z","steps":["trace[1739089939] 'range keys from in-memory index tree' (duration: 256.270977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:23.069873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.110543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:08:23.069953Z","caller":"traceutil/trace.go:171","msg":"trace[2132835543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"256.245356ms","start":"2026-07-17T11:08:22.81369Z","end":"2026-07-17T11:08:23.069935Z","steps":["trace[2132835543] 'range keys from in-memory index tree' (duration: 255.919558ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:23.188709Z","caller":"traceutil/trace.go:171","msg":"trace[94552738] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"114.93441ms","start":"2026-07-17T11:08:23.073743Z","end":"2026-07-17T11:08:23.188677Z","steps":["trace[94552738] 'process raft request' (duration: 114.732945ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:23.204148Z","caller":"traceutil/trace.go:171","msg":"trace[1152100776] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1162; }","duration":"107.731072ms","start":"2026-07-17T11:08:23.096385Z","end":"2026-07-17T11:08:23.204116Z","steps":["trace[1152100776] 'read index received' (duration: 92.120957ms)","trace[1152100776] 'applied index is now lower than readState.Index' (duration: 15.608715ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:08:23.204238Z","caller":"traceutil/trace.go:171","msg":"trace[1530069660] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"129.653113ms","start":"2026-07-17T11:08:23.074557Z","end":"2026-07-17T11:08:23.20421Z","steps":["trace[1530069660] 'process raft request' (duration: 129.091048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:23.204445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.971708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:08:23.204637Z","caller":"traceutil/trace.go:171","msg":"trace[847199068] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1112; }","duration":"108.296958ms","start":"2026-07-17T11:08:23.096312Z","end":"2026-07-17T11:08:23.204609Z","steps":["trace[847199068] 'agreement among raft nodes before linearized reading' (duration: 107.947488ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:23.526888Z","caller":"traceutil/trace.go:171","msg":"trace[161428247] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"315.854398ms","start":"2026-07-17T11:08:23.210996Z","end":"2026-07-17T11:08:23.52685Z","steps":["trace[161428247] 'process raft request' (duration: 315.722263ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:23.526915Z","caller":"traceutil/trace.go:171","msg":"trace[668532950] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"316.039402ms","start":"2026-07-17T11:08:23.210836Z","end":"2026-07-17T11:08:23.526875Z","steps":["trace[668532950] 'process raft request' (duration: 290.905929ms)","trace[668532950] 'compare' (duration: 24.592299ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:23.527076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:23.210973Z","time spent":"315.999032ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:23.527154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:23.210795Z","time spent":"316.185946ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:08:39.96571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.642257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:08:39.965817Z","caller":"traceutil/trace.go:171","msg":"trace[342347898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"316.799732ms","start":"2026-07-17T11:08:39.648998Z","end":"2026-07-17T11:08:39.965797Z","steps":["trace[342347898] 'range keys from in-memory index tree' (duration: 316.536735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:39.965857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:39.648975Z","time spent":"316.872514ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:08:39.965878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.263875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:08:39.965905Z","caller":"traceutil/trace.go:171","msg":"trace[1498321680] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1216; }","duration":"515.335097ms","start":"2026-07-17T11:08:39.450561Z","end":"2026-07-17T11:08:39.965896Z","steps":["trace[1498321680] 'count revisions from in-memory index tree' (duration: 514.007463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:39.965923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:39.450539Z","time spent":"515.378869ms","remote":"127.0.0.1:50828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:08:39.965997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.942337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T11:08:39.9661Z","caller":"traceutil/trace.go:171","msg":"trace[2062171969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1216; }","duration":"337.097281ms","start":"2026-07-17T11:08:39.628979Z","end":"2026-07-17T11:08:39.966076Z","steps":["trace[2062171969] 'range keys from in-memory index tree' (duration: 336.726841ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:39.966139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:39.628963Z","time spent":"337.168062ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:39.966424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.026229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:08:39.966543Z","caller":"traceutil/trace.go:171","msg":"trace[2075072317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"347.197463ms","start":"2026-07-17T11:08:39.619321Z","end":"2026-07-17T11:08:39.966518Z","steps":["trace[2075072317] 'range keys from in-memory index tree' (duration: 346.28755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:39.966601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:39.619302Z","time spent":"347.285795ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:40.963691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:08:41.464324Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:08:41.964472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843240,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:08:41.966757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.638781736s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-17T11:08:41.967095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:40.327796Z","time spent":"1.63929267s","remote":"127.0.0.1:50326","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T11:08:42.650806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999756528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T11:08:42.650903Z","caller":"traceutil/trace.go:171","msg":"trace[732271476] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999922473s","start":"2026-07-17T11:08:40.650958Z","end":"2026-07-17T11:08:42.650881Z","steps":["trace[732271476] 'agreement among raft nodes before linearized reading' (duration: 1.999794149s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.650965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:40.650937Z","time spent":"2.000014235s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 11:08:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-17T11:08:42.943767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.798223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:08:42.943945Z","caller":"traceutil/trace.go:171","msg":"trace[1508207066] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"2.595101818s","start":"2026-07-17T11:08:40.348806Z","end":"2026-07-17T11:08:42.943908Z","steps":["trace[1508207066] 'process raft request' (duration: 2.484059649s)","trace[1508207066] 'compare' (duration: 110.490615ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:08:42.944033Z","caller":"traceutil/trace.go:171","msg":"trace[1500032769] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"281.567575ms","start":"2026-07-17T11:08:42.662444Z","end":"2026-07-17T11:08:42.944011Z","steps":["trace[1500032769] 'process raft request' (duration: 281.522004ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:08:42.944035Z","caller":"traceutil/trace.go:171","msg":"trace[1943616852] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1275; }","duration":"2.497502358s","start":"2026-07-17T11:08:40.446495Z","end":"2026-07-17T11:08:42.943998Z","steps":["trace[1943616852] 'read index received' (duration: 1.520382956s)","trace[1943616852] 'applied index is now lower than readState.Index' (duration: 977.115672ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:08:42.944118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:40.348777Z","time spent":"2.595243461s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:42.944143Z","caller":"traceutil/trace.go:171","msg":"trace[472076180] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"975.52548ms","start":"2026-07-17T11:08:41.968571Z","end":"2026-07-17T11:08:42.944097Z","steps":["trace[472076180] 'process raft request' (duration: 975.313514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.94432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.497803125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:08:42.944379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.133777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:08:42.944378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:41.968538Z","time spent":"975.721965ms","remote":"127.0.0.1:50326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:08:42.944424Z","caller":"traceutil/trace.go:171","msg":"trace[1748864218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"293.215159ms","start":"2026-07-17T11:08:42.651197Z","end":"2026-07-17T11:08:42.944412Z","steps":["trace[1748864218] 'agreement among raft nodes before linearized reading' (duration: 293.161287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.944452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.322173079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:08:42.944494Z","caller":"traceutil/trace.go:171","msg":"trace[1924864320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1221; }","duration":"1.322283822s","start":"2026-07-17T11:08:41.622199Z","end":"2026-07-17T11:08:42.944483Z","steps":["trace[1924864320] 'agreement among raft nodes before linearized reading' (duration: 1.32222884s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.944535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:41.622173Z","time spent":"1.322352234s","remote":"127.0.0.1:59518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:08:42.944689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.066609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T11:08:42.944738Z","caller":"traceutil/trace.go:171","msg":"trace[1871912465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"877.159371ms","start":"2026-07-17T11:08:42.067567Z","end":"2026-07-17T11:08:42.944727Z","steps":["trace[1871912465] 'agreement among raft nodes before linearized reading' (duration: 877.004707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.944779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.651746294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T11:08:42.944827Z","caller":"traceutil/trace.go:171","msg":"trace[1826242733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"1.651805365s","start":"2026-07-17T11:08:41.293002Z","end":"2026-07-17T11:08:42.944808Z","steps":["trace[1826242733] 'agreement among raft nodes before linearized reading' (duration: 1.651721053s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.944836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:42.067542Z","time spent":"877.227243ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:42.944865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:41.292988Z","time spent":"1.651869467s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:42.944888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.689127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-17T11:08:42.944928Z","caller":"traceutil/trace.go:171","msg":"trace[897522214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1221; }","duration":"879.762759ms","start":"2026-07-17T11:08:42.065156Z","end":"2026-07-17T11:08:42.944918Z","steps":["trace[897522214] 'agreement among raft nodes before linearized reading' (duration: 879.594494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.944959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:42.065134Z","time spent":"879.81875ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:08:42.944702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617954984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T11:08:42.945032Z","caller":"traceutil/trace.go:171","msg":"trace[193582737] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"1.618346104s","start":"2026-07-17T11:08:41.326674Z","end":"2026-07-17T11:08:42.94502Z","steps":["trace[193582737] 'agreement among raft nodes before linearized reading' (duration: 1.617909303s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.945041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66547447s","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-07-17T11:08:42.945099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:41.326643Z","time spent":"1.618443247s","remote":"127.0.0.1:50572","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":"info","ts":"2026-07-17T11:08:42.945107Z","caller":"traceutil/trace.go:171","msg":"trace[144130649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"1.665588363s","start":"2026-07-17T11:08:41.279503Z","end":"2026-07-17T11:08:42.945092Z","steps":["trace[144130649] 'agreement among raft nodes before linearized reading' (duration: 1.665364567s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.945152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:41.279475Z","time spent":"1.665666344s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T11:08:42.944383Z","caller":"traceutil/trace.go:171","msg":"trace[1782582842] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1221; }","duration":"2.49793287s","start":"2026-07-17T11:08:40.44643Z","end":"2026-07-17T11:08:42.944363Z","steps":["trace[1782582842] 'agreement among raft nodes before linearized reading' (duration: 2.497741065s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.944326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.191915587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T11:08:42.945254Z","caller":"traceutil/trace.go:171","msg":"trace[368311295] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1221; }","duration":"2.192891703s","start":"2026-07-17T11:08:40.752349Z","end":"2026-07-17T11:08:42.945241Z","steps":["trace[368311295] 'agreement among raft nodes before linearized reading' (duration: 2.191931328s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:08:42.945302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:40.752313Z","time spent":"2.192975175s","remote":"127.0.0.1:50412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:08:42.945212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:08:40.446382Z","time spent":"2.498818652s","remote":"127.0.0.1:51788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:09:01.554633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843428,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T11:09:01.828301Z","caller":"traceutil/trace.go:171","msg":"trace[277885440] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"774.433729ms","start":"2026-07-17T11:09:01.053836Z","end":"2026-07-17T11:09:01.82827Z","steps":["trace[277885440] 'read index received' (duration: 774.10408ms)","trace[277885440] 'applied index is now lower than readState.Index' (duration: 328.509µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:09:01.828452Z","caller":"traceutil/trace.go:171","msg":"trace[1557566392] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"786.013461ms","start":"2026-07-17T11:09:01.04238Z","end":"2026-07-17T11:09:01.828394Z","steps":["trace[1557566392] 'process raft request' (duration: 785.624251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:01.82882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.786735ms","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-07-17T11:09:01.828806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.145182ms","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-07-17T11:09:01.828861Z","caller":"traceutil/trace.go:171","msg":"trace[1686016447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1301; }","duration":"751.903658ms","start":"2026-07-17T11:09:01.076948Z","end":"2026-07-17T11:09:01.828851Z","steps":["trace[1686016447] 'agreement among raft nodes before linearized reading' (duration: 751.687782ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:01.828883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.076924Z","time spent":"751.952959ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T11:09:01.828891Z","caller":"traceutil/trace.go:171","msg":"trace[1832773970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"750.266205ms","start":"2026-07-17T11:09:01.078601Z","end":"2026-07-17T11:09:01.828868Z","steps":["trace[1832773970] 'agreement among raft nodes before linearized reading' (duration: 750.100501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:01.828943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.078572Z","time spent":"750.363398ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:09:01.82904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.689741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T11:09:01.829188Z","caller":"traceutil/trace.go:171","msg":"trace[479568280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"746.936598ms","start":"2026-07-17T11:09:01.082225Z","end":"2026-07-17T11:09:01.829162Z","steps":["trace[479568280] 'agreement among raft nodes before linearized reading' (duration: 746.466576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:01.829321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.082216Z","time spent":"747.084661ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:09:01.829421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.042351Z","time spent":"786.213396ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:01.829075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.796588ms","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-07-17T11:09:01.82958Z","caller":"traceutil/trace.go:171","msg":"trace[2082906409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"775.787113ms","start":"2026-07-17T11:09:01.053785Z","end":"2026-07-17T11:09:01.829572Z","steps":["trace[2082906409] 'agreement among raft nodes before linearized reading' (duration: 774.625863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:01.829602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.053761Z","time spent":"775.835204ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:09:01.83044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.765465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-17T11:09:01.830508Z","caller":"traceutil/trace.go:171","msg":"trace[1809190065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1301; }","duration":"751.889278ms","start":"2026-07-17T11:09:01.078604Z","end":"2026-07-17T11:09:01.830493Z","steps":["trace[1809190065] 'agreement among raft nodes before linearized reading' (duration: 751.706234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:01.830545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.078577Z","time spent":"751.95994ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T11:09:02.597123Z","caller":"traceutil/trace.go:171","msg":"trace[1726937393] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"760.425781ms","start":"2026-07-17T11:09:01.83665Z","end":"2026-07-17T11:09:02.597076Z","steps":["trace[1726937393] 'process raft request' (duration: 738.642231ms)","trace[1726937393] 'compare' (duration: 21.469661ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:02.597372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.836627Z","time spent":"760.617416ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:02.628364Z","caller":"traceutil/trace.go:171","msg":"trace[1654483908] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"791.448632ms","start":"2026-07-17T11:09:01.836882Z","end":"2026-07-17T11:09:02.62833Z","steps":["trace[1654483908] 'process raft request' (duration: 791.223786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:02.628697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.836872Z","time spent":"791.590376ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:02.628938Z","caller":"traceutil/trace.go:171","msg":"trace[16329888] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"788.208108ms","start":"2026-07-17T11:09:01.840709Z","end":"2026-07-17T11:09:02.628917Z","steps":["trace[16329888] 'process raft request' (duration: 788.153467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:02.629057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.840702Z","time spent":"788.289511ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:02.629172Z","caller":"traceutil/trace.go:171","msg":"trace[1432495649] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"791.108754ms","start":"2026-07-17T11:09:01.838046Z","end":"2026-07-17T11:09:02.629155Z","steps":["trace[1432495649] 'process raft request' (duration: 790.235011ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:02.62925Z","caller":"traceutil/trace.go:171","msg":"trace[779390967] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"790.429816ms","start":"2026-07-17T11:09:01.838808Z","end":"2026-07-17T11:09:02.629237Z","steps":["trace[779390967] 'process raft request' (duration: 789.877312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:02.629267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.838037Z","time spent":"791.170295ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:02.629311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:01.8388Z","time spent":"790.478397ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:03.925153Z","caller":"traceutil/trace.go:171","msg":"trace[1230371471] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"601.170595ms","start":"2026-07-17T11:09:03.323953Z","end":"2026-07-17T11:09:03.925124Z","steps":["trace[1230371471] 'process raft request' (duration: 600.96672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:03.925348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:03.323941Z","time spent":"601.296908ms","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T11:09:04.674421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.274599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T11:09:04.674648Z","caller":"traceutil/trace.go:171","msg":"trace[671237556] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1373; }","duration":"1.024564101s","start":"2026-07-17T11:09:03.650065Z","end":"2026-07-17T11:09:04.674629Z","steps":["trace[671237556] 'read index received' (duration: 274.873801ms)","trace[671237556] 'applied index is now lower than readState.Index' (duration: 749.68908ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:09:04.674954Z","caller":"traceutil/trace.go:171","msg":"trace[1329095432] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"831.646955ms","start":"2026-07-17T11:09:03.843276Z","end":"2026-07-17T11:09:04.674923Z","steps":["trace[1329095432] 'process raft request' (duration: 831.313726ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:04.674978Z","caller":"traceutil/trace.go:171","msg":"trace[1448167789] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.173161918s","start":"2026-07-17T11:09:03.501799Z","end":"2026-07-17T11:09:04.674961Z","steps":["trace[1448167789] 'process raft request' (duration: 1.172733257s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:04.674979Z","caller":"traceutil/trace.go:171","msg":"trace[2066988115] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.347174229s","start":"2026-07-17T11:09:03.327764Z","end":"2026-07-17T11:09:04.674938Z","steps":["trace[2066988115] 'process raft request' (duration: 771.274254ms)","trace[2066988115] 'compare' (duration: 575.094854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:04.675073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:03.50176Z","time spent":"1.173270671s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:04.675119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:03.843241Z","time spent":"831.784958ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:04.675146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:03.327731Z","time spent":"1.347320943s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:04.675263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025227438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:04.675292Z","caller":"traceutil/trace.go:171","msg":"trace[815634908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1315; }","duration":"1.02529971s","start":"2026-07-17T11:09:03.649984Z","end":"2026-07-17T11:09:04.675284Z","steps":["trace[815634908] 'agreement among raft nodes before linearized reading' (duration: 1.025246579s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:04.675321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:03.64992Z","time spent":"1.025396362s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:09:05.03854Z","caller":"traceutil/trace.go:171","msg":"trace[357504161] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"356.584147ms","start":"2026-07-17T11:09:04.681919Z","end":"2026-07-17T11:09:05.038503Z","steps":["trace[357504161] 'process raft request' (duration: 328.247016ms)","trace[357504161] 'compare' (duration: 27.779397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:05.038724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:04.681883Z","time spent":"356.743671ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:05.113568Z","caller":"traceutil/trace.go:171","msg":"trace[342393487] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"431.512877ms","start":"2026-07-17T11:09:04.682024Z","end":"2026-07-17T11:09:05.113537Z","steps":["trace[342393487] 'process raft request' (duration: 431.288801ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:05.113768Z","caller":"traceutil/trace.go:171","msg":"trace[1963954677] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"431.566159ms","start":"2026-07-17T11:09:04.682176Z","end":"2026-07-17T11:09:05.113742Z","steps":["trace[1963954677] 'process raft request' (duration: 431.318792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:05.113954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:04.682156Z","time spent":"431.678702ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:05.113974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:04.681997Z","time spent":"431.659341ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:05.114178Z","caller":"traceutil/trace.go:171","msg":"trace[835604352] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"431.901887ms","start":"2026-07-17T11:09:04.682263Z","end":"2026-07-17T11:09:05.114165Z","steps":["trace[835604352] 'process raft request' (duration: 431.411085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:05.11431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:04.682231Z","time spent":"431.98054ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:05.114535Z","caller":"traceutil/trace.go:171","msg":"trace[485446841] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1377; }","duration":"426.125317ms","start":"2026-07-17T11:09:04.688395Z","end":"2026-07-17T11:09:05.11452Z","steps":["trace[485446841] 'read index received' (duration: 321.791197ms)","trace[485446841] 'applied index is now lower than readState.Index' (duration: 104.33281ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:09:05.114684Z","caller":"traceutil/trace.go:171","msg":"trace[1302682027] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"427.224295ms","start":"2026-07-17T11:09:04.68744Z","end":"2026-07-17T11:09:05.114664Z","steps":["trace[1302682027] 'process raft request' (duration: 426.678161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:05.114727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.321682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:05.114787Z","caller":"traceutil/trace.go:171","msg":"trace[1748524392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"426.391534ms","start":"2026-07-17T11:09:04.688362Z","end":"2026-07-17T11:09:05.114753Z","steps":["trace[1748524392] 'agreement among raft nodes before linearized reading' (duration: 426.214959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:05.114793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:04.687423Z","time spent":"427.316508ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:05.114829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:04.688354Z","time spent":"426.463745ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T11:09:06.67397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.806069ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T11:09:06.674231Z","caller":"traceutil/trace.go:171","msg":"trace[1654604471] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"582.897877ms","start":"2026-07-17T11:09:06.091303Z","end":"2026-07-17T11:09:06.674201Z","steps":["trace[1654604471] 'read index received' (duration: 91.333468ms)","trace[1654604471] 'applied index is now lower than readState.Index' (duration: 491.560679ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:06.674361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.020681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:06.674411Z","caller":"traceutil/trace.go:171","msg":"trace[151454221] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1320; }","duration":"583.154624ms","start":"2026-07-17T11:09:06.091237Z","end":"2026-07-17T11:09:06.674392Z","steps":["trace[151454221] 'agreement among raft nodes before linearized reading' (duration: 583.038621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:06.674456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:06.091208Z","time spent":"583.237506ms","remote":"127.0.0.1:50442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-17T11:09:06.675158Z","caller":"traceutil/trace.go:171","msg":"trace[2057033162] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"396.397478ms","start":"2026-07-17T11:09:06.278736Z","end":"2026-07-17T11:09:06.675133Z","steps":["trace[2057033162] 'process raft request' (duration: 395.359431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:06.675264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:06.278706Z","time spent":"396.488041ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:06.829868Z","caller":"traceutil/trace.go:171","msg":"trace[1436145419] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"155.537477ms","start":"2026-07-17T11:09:06.674301Z","end":"2026-07-17T11:09:06.829838Z","steps":["trace[1436145419] 'read index received' (duration: 122.466292ms)","trace[1436145419] 'applied index is now lower than readState.Index' (duration: 33.069705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:06.830037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.492681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:06.830087Z","caller":"traceutil/trace.go:171","msg":"trace[243540321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"180.615545ms","start":"2026-07-17T11:09:06.649458Z","end":"2026-07-17T11:09:06.830074Z","steps":["trace[243540321] 'agreement among raft nodes before linearized reading' (duration: 180.489591ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:06.830234Z","caller":"traceutil/trace.go:171","msg":"trace[147213900] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"190.499483ms","start":"2026-07-17T11:09:06.639679Z","end":"2026-07-17T11:09:06.830178Z","steps":["trace[147213900] 'process raft request' (duration: 157.093069ms)","trace[147213900] 'compare' (duration: 32.780728ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T11:09:06.844534Z","caller":"traceutil/trace.go:171","msg":"trace[1114176813] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"160.221021ms","start":"2026-07-17T11:09:06.684289Z","end":"2026-07-17T11:09:06.84451Z","steps":["trace[1114176813] 'process raft request' (duration: 159.973205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:14.847284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:09:15.348473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:09:15.848935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:09:16.349789Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:09:16.650235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999815891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-17T11:09:16.65034Z","caller":"traceutil/trace.go:171","msg":"trace[1957378808] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999944934s","start":"2026-07-17T11:09:14.650377Z","end":"2026-07-17T11:09:16.650322Z","steps":["trace[1957378808] 'agreement among raft nodes before linearized reading' (duration: 1.999813611s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.650417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.650217Z","time spent":"2.000185891s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/17 11:09:16 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-17T11:09:16.850775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827833220147843582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T11:09:16.950424Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.132748707s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T11:09:16.951032Z","caller":"traceutil/trace.go:171","msg":"trace[154582488] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"3.133500136s","start":"2026-07-17T11:09:13.817497Z","end":"2026-07-17T11:09:16.950998Z","steps":["trace[154582488] 'process raft request' (duration: 3.13327152s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.951257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:13.817459Z","time spent":"3.13367695s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:16.953103Z","caller":"traceutil/trace.go:171","msg":"trace[897466418] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"2.079091215s","start":"2026-07-17T11:09:14.87397Z","end":"2026-07-17T11:09:16.953062Z","steps":["trace[897466418] 'process raft request' (duration: 2.078808958s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:16.953189Z","caller":"traceutil/trace.go:171","msg":"trace[582462931] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"2.077283158s","start":"2026-07-17T11:09:14.875759Z","end":"2026-07-17T11:09:16.953042Z","steps":["trace[582462931] 'process raft request' (duration: 2.077210677s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.953336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.873944Z","time spent":"2.07926486s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T11:09:16.953382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.875727Z","time spent":"2.077543435s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T11:09:16.960655Z","caller":"traceutil/trace.go:171","msg":"trace[1492145768] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1446; }","duration":"2.614406079s","start":"2026-07-17T11:09:14.34623Z","end":"2026-07-17T11:09:16.960636Z","steps":["trace[1492145768] 'read index received' (duration: 2.604704535s)","trace[1492145768] 'applied index is now lower than readState.Index' (duration: 9.700064ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:16.960812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.614530472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:16.960884Z","caller":"traceutil/trace.go:171","msg":"trace[2037020660] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1385; }","duration":"2.614676406s","start":"2026-07-17T11:09:14.346188Z","end":"2026-07-17T11:09:16.960864Z","steps":["trace[2037020660] 'agreement among raft nodes before linearized reading' (duration: 2.614523782s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.960923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.346167Z","time spent":"2.614744787s","remote":"127.0.0.1:59340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:09:16.960944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.753911209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T11:09:16.960983Z","caller":"traceutil/trace.go:171","msg":"trace[793343222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1385; }","duration":"1.753959501s","start":"2026-07-17T11:09:15.207011Z","end":"2026-07-17T11:09:16.960971Z","steps":["trace[793343222] 'agreement among raft nodes before linearized reading' (duration: 1.753811257s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.961011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:15.207006Z","time spent":"1.754000092s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T11:09:16.961044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.754926536s","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-07-17T11:09:16.961105Z","caller":"traceutil/trace.go:171","msg":"trace[895121632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1385; }","duration":"1.755039399s","start":"2026-07-17T11:09:15.206049Z","end":"2026-07-17T11:09:16.961088Z","steps":["trace[895121632] 'agreement among raft nodes before linearized reading' (duration: 1.754825553s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.961124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303948219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:09:16.961099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.221245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:16.961155Z","caller":"traceutil/trace.go:171","msg":"trace[1037264814] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1385; }","duration":"1.30397978s","start":"2026-07-17T11:09:15.657164Z","end":"2026-07-17T11:09:16.961144Z","steps":["trace[1037264814] 'agreement among raft nodes before linearized reading' (duration: 1.303935199s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.961161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:15.206022Z","time spent":"1.755122281s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T11:09:16.961184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:15.657072Z","time spent":"1.304106254s","remote":"127.0.0.1:50816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-17T11:09:16.961184Z","caller":"traceutil/trace.go:171","msg":"trace[1507749352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"310.363349ms","start":"2026-07-17T11:09:16.650801Z","end":"2026-07-17T11:09:16.961164Z","steps":["trace[1507749352] 'agreement among raft nodes before linearized reading' (duration: 310.207775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.961214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752206875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-17T11:09:16.961237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:16.650779Z","time spent":"310.449411ms","remote":"127.0.0.1:35198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T11:09:16.96125Z","caller":"traceutil/trace.go:171","msg":"trace[854137612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1385; }","duration":"1.752285817s","start":"2026-07-17T11:09:15.208955Z","end":"2026-07-17T11:09:16.961241Z","steps":["trace[854137612] 'agreement among raft nodes before linearized reading' (duration: 1.752120732s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.961275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:15.208934Z","time spent":"1.752335968s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T11:09:16.961286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124848673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-17T11:09:16.961327Z","caller":"traceutil/trace.go:171","msg":"trace[778088188] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1385; }","duration":"2.124926395s","start":"2026-07-17T11:09:14.836388Z","end":"2026-07-17T11:09:16.961315Z","steps":["trace[778088188] 'agreement among raft nodes before linearized reading' (duration: 2.124778521s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.96136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.836359Z","time spent":"2.124991887s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T11:09:16.961102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.108781672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T11:09:16.961372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.710988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-17T11:09:16.961387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.269329701s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-17T11:09:16.961406Z","caller":"traceutil/trace.go:171","msg":"trace[1908892278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1385; }","duration":"2.109133941s","start":"2026-07-17T11:09:14.85226Z","end":"2026-07-17T11:09:16.961394Z","steps":["trace[1908892278] 'agreement among raft nodes before linearized reading' (duration: 2.108785892s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:16.961415Z","caller":"traceutil/trace.go:171","msg":"trace[1402586030] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1385; }","duration":"284.79458ms","start":"2026-07-17T11:09:16.676611Z","end":"2026-07-17T11:09:16.961405Z","steps":["trace[1402586030] 'agreement among raft nodes before linearized reading' (duration: 284.616975ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:16.961421Z","caller":"traceutil/trace.go:171","msg":"trace[1683147604] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1385; }","duration":"2.269405483s","start":"2026-07-17T11:09:14.692009Z","end":"2026-07-17T11:09:16.961414Z","steps":["trace[1683147604] 'agreement among raft nodes before linearized reading' (duration: 2.268802098s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.961441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.691982Z","time spent":"2.269454115s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T11:09:16.961443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:14.85224Z","time spent":"2.109186162s","remote":"127.0.0.1:59352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T11:09:16.961377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.833078289s","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-07-17T11:09:16.961488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756265351s","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-07-17T11:09:16.961516Z","caller":"traceutil/trace.go:171","msg":"trace[1620255474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"1.756350733s","start":"2026-07-17T11:09:15.205157Z","end":"2026-07-17T11:09:16.961508Z","steps":["trace[1620255474] 'agreement among raft nodes before linearized reading' (duration: 1.755734557s)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:09:16.961522Z","caller":"traceutil/trace.go:171","msg":"trace[1726467524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1385; }","duration":"1.833289795s","start":"2026-07-17T11:09:15.128211Z","end":"2026-07-17T11:09:16.961501Z","steps":["trace[1726467524] 'agreement among raft nodes before linearized reading' (duration: 1.832908916s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:09:16.96154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:15.205124Z","time spent":"1.756410404s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T11:09:16.961577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T11:09:15.128186Z","time spent":"1.833379238s","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T11:09:40.585483Z","caller":"traceutil/trace.go:171","msg":"trace[801877514] linearizableReadLoop","detail":"{readStateIndex:1566; appliedIndex:1565; }","duration":"148.28214ms","start":"2026-07-17T11:09:40.437174Z","end":"2026-07-17T11:09:40.585456Z","steps":["trace[801877514] 'read index received' (duration: 148.149717ms)","trace[801877514] 'applied index is now lower than readState.Index' (duration: 130.413µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:09:40.58566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.453115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:09:40.585724Z","caller":"traceutil/trace.go:171","msg":"trace[350247315] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1496; }","duration":"148.592868ms","start":"2026-07-17T11:09:40.437114Z","end":"2026-07-17T11:09:40.585707Z","steps":["trace[350247315] 'agreement among raft nodes before linearized reading' (duration: 148.456864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T11:13:35.93732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.601202ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-17T11:13:35.937946Z","caller":"traceutil/trace.go:171","msg":"trace[1542235181] linearizableReadLoop","detail":"{readStateIndex:2714; appliedIndex:2713; }","duration":"287.182827ms","start":"2026-07-17T11:13:35.650735Z","end":"2026-07-17T11:13:35.937918Z","steps":["trace[1542235181] 'read index received' (duration: 61.801µs)","trace[1542235181] 'applied index is now lower than readState.Index' (duration: 287.117166ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T11:13:35.938083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.326381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T11:13:35.938124Z","caller":"traceutil/trace.go:171","msg":"trace[570754467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2596; }","duration":"287.427524ms","start":"2026-07-17T11:13:35.65068Z","end":"2026-07-17T11:13:35.938107Z","steps":["trace[570754467] 'agreement among raft nodes before linearized reading' (duration: 287.325061ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T11:13:44.331741Z","caller":"traceutil/trace.go:171","msg":"trace[809536586] transaction","detail":"{read_only:false; response_revision:2642; number_of_response:1; }","duration":"294.51493ms","start":"2026-07-17T11:13:44.037197Z","end":"2026-07-17T11:13:44.331712Z","steps":["trace[809536586] 'process raft request' (duration: 294.280944ms)"],"step_count":1}