{"level":"warn","ts":"2026-07-01T00:45:37.294424Z","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-01T00:45:37.294542Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-01T00:45:37.294614Z","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-01T00:45:37.294626Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-01T00:45:37.294648Z","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-01T00:45:37.295426Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-01T00:45:37.295543Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-01T00:45:37.343298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"47.477081ms"} {"level":"info","ts":"2026-07-01T00:45:37.621294Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-01T00:45:37.621479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:45:37.621553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-01T00:45:37.621585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:45:37.621624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-01T00:45:37.621759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-01T00:45:37.717574Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:45:37.790327Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:45:37.922349Z","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-01T00:45:37.94356Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:45:37.948343Z","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-01T00:45:37.948557Z","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-01T00:45:37.948569Z","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-01T00:45:37.948115Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:45:37.953169Z","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-01T00:45:37.953414Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:45:37.953452Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:45:37.953587Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-01T00:45:37.953612Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-01T00:45:38.030115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-01T00:45:38.030434Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-01T00:45:38.630116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:45:38.630183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:45:38.630239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-01T00:45:38.630265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:45:38.630277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-01T00:45:38.630293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-01T00:45:38.630307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-01T00:45:38.640515Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:45:38.65252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:45:38.652546Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:45:38.652613Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:45:38.652936Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:45:38.653016Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:45:38.654383Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:45:38.654653Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:45:38.654747Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:45:38.656712Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-01T00:45:38.657686Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:45:40.115848Z","caller":"traceutil/trace.go:171","msg":"trace[1571241945] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"106.937496ms","start":"2026-07-01T00:45:40.008865Z","end":"2026-07-01T00:45:40.115803Z","steps":["trace[1571241945] 'process raft request' (duration: 88.0585ms)","trace[1571241945] 'compare' (duration: 18.534443ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:40.115973Z","caller":"traceutil/trace.go:171","msg":"trace[1210260357] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"100.327271ms","start":"2026-07-01T00:45:40.015608Z","end":"2026-07-01T00:45:40.115936Z","steps":["trace[1210260357] 'process raft request' (duration: 100.127826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:40.32081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.751981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-07-01T00:45:40.321255Z","caller":"traceutil/trace.go:171","msg":"trace[1393105124] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"106.399148ms","start":"2026-07-01T00:45:40.214819Z","end":"2026-07-01T00:45:40.321218Z","steps":["trace[1393105124] 'compare' (duration: 103.555784ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:40.321475Z","caller":"traceutil/trace.go:171","msg":"trace[1104994871] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"105.092198ms","start":"2026-07-01T00:45:40.216359Z","end":"2026-07-01T00:45:40.321451Z","steps":["trace[1104994871] 'process raft request' (duration: 104.685838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:40.814179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.671879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-01T00:45:40.814291Z","caller":"traceutil/trace.go:171","msg":"trace[108074326] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"323.566612ms","start":"2026-07-01T00:45:40.490704Z","end":"2026-07-01T00:45:40.81427Z","steps":["trace[108074326] 'process raft request' (duration: 181.709538ms)","trace[108074326] 'compare' (duration: 141.535342ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:40.81435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:40.490674Z","time spent":"323.65385ms","remote":"127.0.0.1:45062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:45:40.81724Z","caller":"traceutil/trace.go:171","msg":"trace[101883846] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"325.30299ms","start":"2026-07-01T00:45:40.491897Z","end":"2026-07-01T00:45:40.8172Z","steps":["trace[101883846] 'process raft request' (duration: 325.233161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:40.817371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:40.491883Z","time spent":"325.412358ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1199,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:45:40.817472Z","caller":"traceutil/trace.go:171","msg":"trace[810939465] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"326.651048ms","start":"2026-07-01T00:45:40.490815Z","end":"2026-07-01T00:45:40.817466Z","steps":["trace[810939465] 'process raft request' (duration: 326.218249ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:40.817537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:40.490794Z","time spent":"326.705348ms","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:45:41.02106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-07-01T00:45:41.021261Z","caller":"traceutil/trace.go:171","msg":"trace[209317619] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"193.28911ms","start":"2026-07-01T00:45:40.827945Z","end":"2026-07-01T00:45:41.021234Z","steps":["trace[209317619] 'process raft request' (duration: 86.904826ms)","trace[209317619] 'compare' (duration: 106.049552ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:41.021397Z","caller":"traceutil/trace.go:171","msg":"trace[276179904] linearizableReadLoop","detail":"{readStateIndex:63; appliedIndex:61; }","duration":"123.097842ms","start":"2026-07-01T00:45:40.89828Z","end":"2026-07-01T00:45:41.021377Z","steps":["trace[276179904] 'read index received' (duration: 13.392356ms)","trace[276179904] 'applied index is now lower than readState.Index' (duration: 109.703396ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:41.021755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.394721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:45:41.02176Z","caller":"traceutil/trace.go:171","msg":"trace[349307145] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"106.373256ms","start":"2026-07-01T00:45:40.915288Z","end":"2026-07-01T00:45:41.021661Z","steps":["trace[349307145] 'process raft request' (duration: 105.888867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:41.021768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.411314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:45:41.021808Z","caller":"traceutil/trace.go:171","msg":"trace[1331973435] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:60; }","duration":"121.488ms","start":"2026-07-01T00:45:40.900304Z","end":"2026-07-01T00:45:41.021792Z","steps":["trace[1331973435] 'agreement among raft nodes before linearized reading' (duration: 121.323443ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:41.021848Z","caller":"traceutil/trace.go:171","msg":"trace[492454744] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:60; }","duration":"123.61577ms","start":"2026-07-01T00:45:40.898211Z","end":"2026-07-01T00:45:41.021827Z","steps":["trace[492454744] 'agreement among raft nodes before linearized reading' (duration: 123.367965ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:41.190214Z","caller":"traceutil/trace.go:171","msg":"trace[1595700434] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"164.729173ms","start":"2026-07-01T00:45:41.02545Z","end":"2026-07-01T00:45:41.19018Z","steps":["trace[1595700434] 'process raft request' (duration: 115.659673ms)","trace[1595700434] 'compare' (duration: 48.648479ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:41.190782Z","caller":"traceutil/trace.go:171","msg":"trace[939985835] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"164.652424ms","start":"2026-07-01T00:45:41.026102Z","end":"2026-07-01T00:45:41.190754Z","steps":["trace[939985835] 'process raft request' (duration: 163.864812ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:41.366518Z","caller":"traceutil/trace.go:171","msg":"trace[2084414829] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"171.278077ms","start":"2026-07-01T00:45:41.195204Z","end":"2026-07-01T00:45:41.366482Z","steps":["trace[2084414829] 'process raft request' (duration: 115.052556ms)","trace[2084414829] 'compare' (duration: 55.907378ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:41.367009Z","caller":"traceutil/trace.go:171","msg":"trace[569207354] linearizableReadLoop","detail":"{readStateIndex:71; appliedIndex:69; }","duration":"130.810575ms","start":"2026-07-01T00:45:41.236158Z","end":"2026-07-01T00:45:41.366968Z","steps":["trace[569207354] 'read index received' (duration: 74.122504ms)","trace[569207354] 'applied index is now lower than readState.Index' (duration: 56.680461ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:41.367324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.145198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:45:41.367325Z","caller":"traceutil/trace.go:171","msg":"trace[1162381878] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"171.688208ms","start":"2026-07-01T00:45:41.195601Z","end":"2026-07-01T00:45:41.367289Z","steps":["trace[1162381878] 'process raft request' (duration: 171.195199ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:41.367378Z","caller":"traceutil/trace.go:171","msg":"trace[1859779709] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:68; }","duration":"131.271764ms","start":"2026-07-01T00:45:41.236089Z","end":"2026-07-01T00:45:41.367361Z","steps":["trace[1859779709] 'agreement among raft nodes before linearized reading' (duration: 131.00876ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:41.367553Z","caller":"traceutil/trace.go:171","msg":"trace[1845876246] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"129.684171ms","start":"2026-07-01T00:45:41.237264Z","end":"2026-07-01T00:45:41.366948Z","steps":["trace[1845876246] 'process raft request' (duration: 129.612872ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:43.97726Z","caller":"traceutil/trace.go:171","msg":"trace[2089321286] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"110.052219ms","start":"2026-07-01T00:45:43.867182Z","end":"2026-07-01T00:45:43.977235Z","steps":["trace[2089321286] 'process raft request' (duration: 109.99738ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:43.977328Z","caller":"traceutil/trace.go:171","msg":"trace[1134883444] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"111.214847ms","start":"2026-07-01T00:45:43.866062Z","end":"2026-07-01T00:45:43.977277Z","steps":["trace[1134883444] 'process raft request' (duration: 69.553665ms)","trace[1134883444] 'compare' (duration: 41.372348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:44.306924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.594689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:45:44.307143Z","caller":"traceutil/trace.go:171","msg":"trace[313214175] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"256.783091ms","start":"2026-07-01T00:45:44.050341Z","end":"2026-07-01T00:45:44.307124Z","steps":["trace[313214175] 'process raft request' (duration: 256.708203ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:44.307292Z","caller":"traceutil/trace.go:171","msg":"trace[235062885] linearizableReadLoop","detail":"{readStateIndex:229; appliedIndex:227; }","duration":"274.228711ms","start":"2026-07-01T00:45:44.033033Z","end":"2026-07-01T00:45:44.307262Z","steps":["trace[235062885] 'read index received' (duration: 13.690278ms)","trace[235062885] 'applied index is now lower than readState.Index' (duration: 260.534273ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:44.30752Z","caller":"traceutil/trace.go:171","msg":"trace[871917124] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"275.897801ms","start":"2026-07-01T00:45:44.031606Z","end":"2026-07-01T00:45:44.307504Z","steps":["trace[871917124] 'process raft request' (duration: 132.649914ms)","trace[871917124] 'compare' (duration: 142.386793ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:45:44.307671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.623993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-01T00:45:44.307817Z","caller":"traceutil/trace.go:171","msg":"trace[2043985004] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:224; }","duration":"274.791731ms","start":"2026-07-01T00:45:44.033009Z","end":"2026-07-01T00:45:44.3078Z","steps":["trace[2043985004] 'agreement among raft nodes before linearized reading' (duration: 274.422257ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:47.748415Z","caller":"traceutil/trace.go:171","msg":"trace[429561085] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"433.403686ms","start":"2026-07-01T00:45:47.314986Z","end":"2026-07-01T00:45:47.748389Z","steps":["trace[429561085] 'process raft request' (duration: 433.231458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:47.748596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:47.314969Z","time spent":"433.535694ms","remote":"127.0.0.1:45062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:45:47.750297Z","caller":"traceutil/trace.go:171","msg":"trace[300080367] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"425.446844ms","start":"2026-07-01T00:45:47.324834Z","end":"2026-07-01T00:45:47.750281Z","steps":["trace[300080367] 'process raft request' (duration: 425.186268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:47.750381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:45:47.324819Z","time spent":"425.520683ms","remote":"127.0.0.1:45088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:45:55.510302Z","caller":"traceutil/trace.go:171","msg":"trace[1408687913] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"155.470988ms","start":"2026-07-01T00:45:55.354789Z","end":"2026-07-01T00:45:55.51026Z","steps":["trace[1408687913] 'process raft request' (duration: 155.1851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:45:55.822692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.469605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7233"} {"level":"info","ts":"2026-07-01T00:45:55.82286Z","caller":"traceutil/trace.go:171","msg":"trace[1306661353] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:373; }","duration":"259.715534ms","start":"2026-07-01T00:45:55.563118Z","end":"2026-07-01T00:45:55.822833Z","steps":["trace[1306661353] 'range keys from in-memory index tree' (duration: 259.285637ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:55.823246Z","caller":"traceutil/trace.go:171","msg":"trace[1162764381] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"145.265178ms","start":"2026-07-01T00:45:55.67793Z","end":"2026-07-01T00:45:55.823195Z","steps":["trace[1162764381] 'process raft request' (duration: 69.619166ms)","trace[1162764381] 'compare' (duration: 75.159145ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:45:55.933307Z","caller":"traceutil/trace.go:171","msg":"trace[1256166387] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"101.224143ms","start":"2026-07-01T00:45:55.832058Z","end":"2026-07-01T00:45:55.933282Z","steps":["trace[1256166387] 'process raft request' (duration: 101.153273ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:55.933324Z","caller":"traceutil/trace.go:171","msg":"trace[754941948] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"104.954751ms","start":"2026-07-01T00:45:55.828342Z","end":"2026-07-01T00:45:55.933297Z","steps":["trace[754941948] 'process raft request' (duration: 101.246862ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:45:56.551109Z","caller":"traceutil/trace.go:171","msg":"trace[1792712620] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"172.448139ms","start":"2026-07-01T00:45:56.378637Z","end":"2026-07-01T00:45:56.551085Z","steps":["trace[1792712620] 'process raft request' (duration: 172.32145ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:02.321368Z","caller":"traceutil/trace.go:171","msg":"trace[679075702] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"371.609394ms","start":"2026-07-01T00:46:01.949726Z","end":"2026-07-01T00:46:02.321336Z","steps":["trace[679075702] 'read index received' (duration: 371.376983ms)","trace[679075702] 'applied index is now lower than readState.Index' (duration: 230.501µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:02.321416Z","caller":"traceutil/trace.go:171","msg":"trace[1261974200] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"376.164865ms","start":"2026-07-01T00:46:01.945212Z","end":"2026-07-01T00:46:02.321376Z","steps":["trace[1261974200] 'process raft request' (duration: 375.934504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:02.321558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.775334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T00:46:02.32161Z","caller":"traceutil/trace.go:171","msg":"trace[21715239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:392; }","duration":"371.885074ms","start":"2026-07-01T00:46:01.949708Z","end":"2026-07-01T00:46:02.321593Z","steps":["trace[21715239] 'agreement among raft nodes before linearized reading' (duration: 371.754954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:02.321626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:01.945199Z","time spent":"376.300985ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:46:02.321659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:01.949701Z","time spent":"371.944404ms","remote":"127.0.0.1:45156","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":"info","ts":"2026-07-01T00:46:19.633896Z","caller":"traceutil/trace.go:171","msg":"trace[2015791006] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"108.037835ms","start":"2026-07-01T00:46:19.525838Z","end":"2026-07-01T00:46:19.633876Z","steps":["trace[2015791006] 'process raft request' (duration: 105.827011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.245353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.745285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:46:20.245538Z","caller":"traceutil/trace.go:171","msg":"trace[1649134101] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"353.224445ms","start":"2026-07-01T00:46:19.892256Z","end":"2026-07-01T00:46:20.245481Z","steps":["trace[1649134101] 'process raft request' (duration: 14.214395ms)","trace[1649134101] 'compare' (duration: 338.585163ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:20.245683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:19.892231Z","time spent":"353.419337ms","remote":"127.0.0.1:45336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:46:20.648842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.692635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:46:20.649099Z","caller":"traceutil/trace.go:171","msg":"trace[1789966089] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"751.85766ms","start":"2026-07-01T00:46:19.897209Z","end":"2026-07-01T00:46:20.649066Z","steps":["trace[1789966089] 'process raft request' (duration: 571.839289ms)","trace[1789966089] 'compare' (duration: 179.403311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:20.649223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:19.897168Z","time spent":"751.998341ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:46:20.649784Z","caller":"traceutil/trace.go:171","msg":"trace[573424163] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"746.987295ms","start":"2026-07-01T00:46:19.902783Z","end":"2026-07-01T00:46:20.64977Z","steps":["trace[573424163] 'read index received' (duration: 3.800007ms)","trace[573424163] 'applied index is now lower than readState.Index' (duration: 743.186548ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:20.649903Z","caller":"traceutil/trace.go:171","msg":"trace[46129213] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"749.540765ms","start":"2026-07-01T00:46:19.900318Z","end":"2026-07-01T00:46:20.649858Z","steps":["trace[46129213] 'process raft request' (duration: 749.283381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.650006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:19.900293Z","time spent":"749.642706ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:46:20.650172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.371581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-07-01T00:46:20.650241Z","caller":"traceutil/trace.go:171","msg":"trace[1965008873] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:594; }","duration":"747.450933ms","start":"2026-07-01T00:46:19.902775Z","end":"2026-07-01T00:46:20.650226Z","steps":["trace[1965008873] 'agreement among raft nodes before linearized reading' (duration: 747.095487ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.650341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:19.902704Z","time spent":"747.619916ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80725,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-01T00:46:20.672204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.931904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T00:46:20.672277Z","caller":"traceutil/trace.go:171","msg":"trace[1729629038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"181.056766ms","start":"2026-07-01T00:46:20.491202Z","end":"2026-07-01T00:46:20.672258Z","steps":["trace[1729629038] 'agreement among raft nodes before linearized reading' (duration: 180.922404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.672459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.437491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:46:20.672485Z","caller":"traceutil/trace.go:171","msg":"trace[1904772440] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:595; }","duration":"265.496373ms","start":"2026-07-01T00:46:20.40698Z","end":"2026-07-01T00:46:20.672477Z","steps":["trace[1904772440] 'agreement among raft nodes before linearized reading' (duration: 265.459162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.67254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.966583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:46:20.672562Z","caller":"traceutil/trace.go:171","msg":"trace[1620149166] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:595; }","duration":"659.028124ms","start":"2026-07-01T00:46:20.013527Z","end":"2026-07-01T00:46:20.672556Z","steps":["trace[1620149166] 'agreement among raft nodes before linearized reading' (duration: 659.000194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.672579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:20.013499Z","time spent":"659.075394ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"warn","ts":"2026-07-01T00:46:20.672204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.093789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T00:46:20.672635Z","caller":"traceutil/trace.go:171","msg":"trace[1668156139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"178.568236ms","start":"2026-07-01T00:46:20.494061Z","end":"2026-07-01T00:46:20.67263Z","steps":["trace[1668156139] 'agreement among raft nodes before linearized reading' (duration: 178.056428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.67276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.364333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:46:20.672867Z","caller":"traceutil/trace.go:171","msg":"trace[784309251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"752.532487ms","start":"2026-07-01T00:46:19.920306Z","end":"2026-07-01T00:46:20.672838Z","steps":["trace[784309251] 'agreement among raft nodes before linearized reading' (duration: 752.304573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.672926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:19.920291Z","time spent":"752.626199ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:46:20.672465Z","caller":"traceutil/trace.go:171","msg":"trace[1925600906] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"463.066481ms","start":"2026-07-01T00:46:20.209377Z","end":"2026-07-01T00:46:20.672443Z","steps":["trace[1925600906] 'process raft request' (duration: 462.640595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:20.673132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:20.209351Z","time spent":"463.631751ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:46:27.210465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680220874275676814,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T00:46:27.25021Z","caller":"traceutil/trace.go:171","msg":"trace[1176385671] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"540.503601ms","start":"2026-07-01T00:46:26.709677Z","end":"2026-07-01T00:46:27.250181Z","steps":["trace[1176385671] 'process raft request' (duration: 540.316238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:27.250555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:26.709644Z","time spent":"540.734686ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:46:27.467803Z","caller":"traceutil/trace.go:171","msg":"trace[1501431772] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"758.084305ms","start":"2026-07-01T00:46:26.709676Z","end":"2026-07-01T00:46:27.467761Z","steps":["trace[1501431772] 'read index received' (duration: 540.239816ms)","trace[1501431772] 'applied index is now lower than readState.Index' (duration: 217.842939ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:27.467933Z","caller":"traceutil/trace.go:171","msg":"trace[773276973] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"755.429502ms","start":"2026-07-01T00:46:26.71246Z","end":"2026-07-01T00:46:27.467889Z","steps":["trace[773276973] 'process raft request' (duration: 734.944251ms)","trace[773276973] 'compare' (duration: 20.183985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:27.468103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:26.712429Z","time spent":"755.578365ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:46:27.468178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.462243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T00:46:27.468248Z","caller":"traceutil/trace.go:171","msg":"trace[400359223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"758.578054ms","start":"2026-07-01T00:46:26.709648Z","end":"2026-07-01T00:46:27.468226Z","steps":["trace[400359223] 'agreement among raft nodes before linearized reading' (duration: 758.33957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:27.468306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:26.709618Z","time spent":"758.672067ms","remote":"127.0.0.1:45156","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-01T00:46:27.468388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.92106ms","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-01T00:46:27.468391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.122132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:46:27.468431Z","caller":"traceutil/trace.go:171","msg":"trace[407372866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"533.98931ms","start":"2026-07-01T00:46:26.934431Z","end":"2026-07-01T00:46:27.46842Z","steps":["trace[407372866] 'agreement among raft nodes before linearized reading' (duration: 533.828147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:27.468466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:26.934416Z","time spent":"534.042821ms","remote":"127.0.0.1:45156","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-01T00:46:27.468445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.107365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7zdg8\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-01T00:46:27.468522Z","caller":"traceutil/trace.go:171","msg":"trace[1048293053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"544.306586ms","start":"2026-07-01T00:46:26.924189Z","end":"2026-07-01T00:46:27.468496Z","steps":["trace[1048293053] 'agreement among raft nodes before linearized reading' (duration: 544.104922ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:27.468548Z","caller":"traceutil/trace.go:171","msg":"trace[1491367373] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7zdg8; range_end:; response_count:1; response_revision:662; }","duration":"605.252737ms","start":"2026-07-01T00:46:26.863273Z","end":"2026-07-01T00:46:27.468525Z","steps":["trace[1491367373] 'agreement among raft nodes before linearized reading' (duration: 604.90369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:27.46857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:26.924159Z","time spent":"544.403068ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:46:27.468576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.383634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-01T00:46:27.46861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:26.863208Z","time spent":"605.39232ms","remote":"127.0.0.1:45062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-7zdg8\" "} {"level":"info","ts":"2026-07-01T00:46:27.468628Z","caller":"traceutil/trace.go:171","msg":"trace[837447142] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:662; }","duration":"170.482206ms","start":"2026-07-01T00:46:27.298129Z","end":"2026-07-01T00:46:27.468612Z","steps":["trace[837447142] 'agreement among raft nodes before linearized reading' (duration: 170.339663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:27.635498Z","caller":"traceutil/trace.go:171","msg":"trace[1094716970] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"159.887599ms","start":"2026-07-01T00:46:27.475582Z","end":"2026-07-01T00:46:27.63547Z","steps":["trace[1094716970] 'process raft request' (duration: 159.823418ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:27.635832Z","caller":"traceutil/trace.go:171","msg":"trace[1261516387] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"160.243276ms","start":"2026-07-01T00:46:27.47549Z","end":"2026-07-01T00:46:27.635733Z","steps":["trace[1261516387] 'process raft request' (duration: 153.256594ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:27.770763Z","caller":"traceutil/trace.go:171","msg":"trace[1462498721] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"282.899244ms","start":"2026-07-01T00:46:27.48783Z","end":"2026-07-01T00:46:27.770729Z","steps":["trace[1462498721] 'process raft request' (duration: 268.796554ms)","trace[1462498721] 'compare' (duration: 13.895175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:28.361922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.868763ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T00:46:28.362314Z","caller":"traceutil/trace.go:171","msg":"trace[193613246] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"437.158564ms","start":"2026-07-01T00:46:27.925095Z","end":"2026-07-01T00:46:28.362253Z","steps":["trace[193613246] 'read index received' (duration: 308.935253ms)","trace[193613246] 'applied index is now lower than readState.Index' (duration: 128.219781ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:28.36249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.275576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:46:28.362608Z","caller":"traceutil/trace.go:171","msg":"trace[146350054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"437.552213ms","start":"2026-07-01T00:46:27.925035Z","end":"2026-07-01T00:46:28.362587Z","steps":["trace[146350054] 'agreement among raft nodes before linearized reading' (duration: 437.249105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:28.362758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:27.925011Z","time spent":"437.728117ms","remote":"127.0.0.1:44878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:46:29.842504Z","caller":"traceutil/trace.go:171","msg":"trace[1791046161] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"367.69249ms","start":"2026-07-01T00:46:29.474783Z","end":"2026-07-01T00:46:29.842476Z","steps":["trace[1791046161] 'read index received' (duration: 367.497855ms)","trace[1791046161] 'applied index is now lower than readState.Index' (duration: 193.055µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:29.842686Z","caller":"traceutil/trace.go:171","msg":"trace[91300184] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"580.927921ms","start":"2026-07-01T00:46:29.261736Z","end":"2026-07-01T00:46:29.842664Z","steps":["trace[91300184] 'process raft request' (duration: 580.573663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:29.842844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:29.2617Z","time spent":"581.026523ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:46:29.842946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.944703ms","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-01T00:46:29.843052Z","caller":"traceutil/trace.go:171","msg":"trace[514729867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:667; }","duration":"355.132787ms","start":"2026-07-01T00:46:29.487888Z","end":"2026-07-01T00:46:29.843021Z","steps":["trace[514729867] 'agreement among raft nodes before linearized reading' (duration: 354.844661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:29.843129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:29.487861Z","time spent":"355.255739ms","remote":"127.0.0.1:45156","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-01T00:46:29.843342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.303081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T00:46:29.843415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.485995ms","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-01T00:46:29.843414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.61231ms","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-01T00:46:29.84345Z","caller":"traceutil/trace.go:171","msg":"trace[1975669327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"202.577937ms","start":"2026-07-01T00:46:29.640862Z","end":"2026-07-01T00:46:29.84344Z","steps":["trace[1975669327] 'agreement among raft nodes before linearized reading' (duration: 202.482035ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:29.843451Z","caller":"traceutil/trace.go:171","msg":"trace[406688450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"202.489396ms","start":"2026-07-01T00:46:29.640939Z","end":"2026-07-01T00:46:29.843428Z","steps":["trace[406688450] 'agreement among raft nodes before linearized reading' (duration: 202.106948ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:29.843472Z","caller":"traceutil/trace.go:171","msg":"trace[1281291888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"368.735552ms","start":"2026-07-01T00:46:29.474719Z","end":"2026-07-01T00:46:29.843455Z","steps":["trace[1281291888] 'agreement among raft nodes before linearized reading' (duration: 368.532628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:29.843694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:29.474691Z","time spent":"368.995547ms","remote":"127.0.0.1:45156","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-01T00:46:38.207499Z","caller":"traceutil/trace.go:171","msg":"trace[1792337151] transaction","detail":"{read_only:false; number_of_response:0; response_revision:724; }","duration":"176.19026ms","start":"2026-07-01T00:46:38.03128Z","end":"2026-07-01T00:46:38.20747Z","steps":["trace[1792337151] 'process raft request' (duration: 176.142139ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:38.207575Z","caller":"traceutil/trace.go:171","msg":"trace[824682674] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"176.59794ms","start":"2026-07-01T00:46:38.030934Z","end":"2026-07-01T00:46:38.207532Z","steps":["trace[824682674] 'process raft request' (duration: 91.608384ms)","trace[824682674] 'compare' (duration: 84.625987ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:38.38299Z","caller":"traceutil/trace.go:171","msg":"trace[1654546056] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"166.765946ms","start":"2026-07-01T00:46:38.216182Z","end":"2026-07-01T00:46:38.382948Z","steps":["trace[1654546056] 'process raft request' (duration: 92.631832ms)","trace[1654546056] 'compare' (duration: 73.926248ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:42.130854Z","caller":"traceutil/trace.go:171","msg":"trace[265265855] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"107.38648ms","start":"2026-07-01T00:46:42.023427Z","end":"2026-07-01T00:46:42.130814Z","steps":["trace[265265855] 'process raft request' (duration: 54.924081ms)","trace[265265855] 'compare' (duration: 52.223042ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:46:42.134879Z","caller":"traceutil/trace.go:171","msg":"trace[1849641762] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"110.794939ms","start":"2026-07-01T00:46:42.024052Z","end":"2026-07-01T00:46:42.134847Z","steps":["trace[1849641762] 'process raft request' (duration: 110.542122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:44.25543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.813808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-01T00:46:44.255442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.753627ms","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-01T00:46:44.255594Z","caller":"traceutil/trace.go:171","msg":"trace[754417601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"115.943782ms","start":"2026-07-01T00:46:44.139593Z","end":"2026-07-01T00:46:44.255537Z","steps":["trace[754417601] 'range keys from in-memory index tree' (duration: 115.5366ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:44.25566Z","caller":"traceutil/trace.go:171","msg":"trace[172423816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"120.006624ms","start":"2026-07-01T00:46:44.135513Z","end":"2026-07-01T00:46:44.255519Z","steps":["trace[172423816] 'range keys from in-memory index tree' (duration: 119.564471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:47.300339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028979123s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T00:46:47.301959Z","caller":"traceutil/trace.go:171","msg":"trace[1116978825] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"1.030723907s","start":"2026-07-01T00:46:46.271209Z","end":"2026-07-01T00:46:47.301933Z","steps":["trace[1116978825] 'process raft request' (duration: 1.030570352s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:47.302167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:46.27119Z","time spent":"1.030848121s","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:46:47.327078Z","caller":"traceutil/trace.go:171","msg":"trace[2115327588] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"1.055298597s","start":"2026-07-01T00:46:46.271754Z","end":"2026-07-01T00:46:47.327053Z","steps":["trace[2115327588] 'process raft request' (duration: 1.054767211s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:47.32722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:46.271728Z","time spent":"1.055414772s","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:46:47.327389Z","caller":"traceutil/trace.go:171","msg":"trace[1029907276] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"788.329099ms","start":"2026-07-01T00:46:46.539054Z","end":"2026-07-01T00:46:47.327383Z","steps":["trace[1029907276] 'process raft request' (duration: 787.603186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:47.327428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:46.53902Z","time spent":"788.38736ms","remote":"127.0.0.1:45048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:46:47.327566Z","caller":"traceutil/trace.go:171","msg":"trace[1972538992] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"322.077291ms","start":"2026-07-01T00:46:47.005484Z","end":"2026-07-01T00:46:47.327562Z","steps":["trace[1972538992] 'process raft request' (duration: 321.202244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:47.327609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:46:47.005456Z","time spent":"322.127953ms","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:46:59.465792Z","caller":"traceutil/trace.go:171","msg":"trace[635480419] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"245.56808ms","start":"2026-07-01T00:46:59.220195Z","end":"2026-07-01T00:46:59.465763Z","steps":["trace[635480419] 'read index received' (duration: 245.411015ms)","trace[635480419] 'applied index is now lower than readState.Index' (duration: 155.645µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:46:59.466133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.914353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"warn","ts":"2026-07-01T00:46:59.466135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.75297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-07-01T00:46:59.466203Z","caller":"traceutil/trace.go:171","msg":"trace[1288413072] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:925; }","duration":"246.011956ms","start":"2026-07-01T00:46:59.22016Z","end":"2026-07-01T00:46:59.466172Z","steps":["trace[1288413072] 'agreement among raft nodes before linearized reading' (duration: 245.787578ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:59.465905Z","caller":"traceutil/trace.go:171","msg":"trace[606529627] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"248.873601ms","start":"2026-07-01T00:46:59.216967Z","end":"2026-07-01T00:46:59.465841Z","steps":["trace[606529627] 'process raft request' (duration: 248.614802ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:46:59.466204Z","caller":"traceutil/trace.go:171","msg":"trace[395971291] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:925; }","duration":"197.875893ms","start":"2026-07-01T00:46:59.268309Z","end":"2026-07-01T00:46:59.466185Z","steps":["trace[395971291] 'agreement among raft nodes before linearized reading' (duration: 197.663106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:46:59.466135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.863252ms","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-01T00:46:59.466382Z","caller":"traceutil/trace.go:171","msg":"trace[92354502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"104.183044ms","start":"2026-07-01T00:46:59.362189Z","end":"2026-07-01T00:46:59.466372Z","steps":["trace[92354502] 'agreement among raft nodes before linearized reading' (duration: 103.829971ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:05.698448Z","caller":"traceutil/trace.go:171","msg":"trace[1002493862] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1009; }","duration":"113.75085ms","start":"2026-07-01T00:47:05.584673Z","end":"2026-07-01T00:47:05.698424Z","steps":["trace[1002493862] 'read index received' (duration: 113.667407ms)","trace[1002493862] 'applied index is now lower than readState.Index' (duration: 82.363µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:05.698542Z","caller":"traceutil/trace.go:171","msg":"trace[1791816965] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"145.709364ms","start":"2026-07-01T00:47:05.552782Z","end":"2026-07-01T00:47:05.698492Z","steps":["trace[1791816965] 'process raft request' (duration: 145.490616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:05.698633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.937557ms","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-01T00:47:05.698672Z","caller":"traceutil/trace.go:171","msg":"trace[1819490318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"114.015991ms","start":"2026-07-01T00:47:05.584644Z","end":"2026-07-01T00:47:05.69866Z","steps":["trace[1819490318] 'agreement among raft nodes before linearized reading' (duration: 113.881096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:28.037134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.977764ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T00:47:28.065693Z","caller":"traceutil/trace.go:171","msg":"trace[1190180706] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"117.156223ms","start":"2026-07-01T00:47:27.948511Z","end":"2026-07-01T00:47:28.065667Z","steps":["trace[1190180706] 'process raft request' (duration: 116.955044ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:32.406636Z","caller":"traceutil/trace.go:171","msg":"trace[71082623] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"112.148456ms","start":"2026-07-01T00:47:32.294448Z","end":"2026-07-01T00:47:32.406596Z","steps":["trace[71082623] 'process raft request' (duration: 111.827641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:32.780207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.904133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T00:47:32.780301Z","caller":"traceutil/trace.go:171","msg":"trace[669999524] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"349.05053ms","start":"2026-07-01T00:47:32.431229Z","end":"2026-07-01T00:47:32.78028Z","steps":["trace[669999524] 'range keys from in-memory index tree' (duration: 348.708254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:32.78036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:32.431204Z","time spent":"349.130023ms","remote":"127.0.0.1:45156","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-01T00:47:32.780499Z","caller":"traceutil/trace.go:171","msg":"trace[541267308] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"221.22361ms","start":"2026-07-01T00:47:32.559225Z","end":"2026-07-01T00:47:32.780449Z","steps":["trace[541267308] 'read index received' (duration: 213.294324ms)","trace[541267308] 'applied index is now lower than readState.Index' (duration: 7.927596ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:32.780711Z","caller":"traceutil/trace.go:171","msg":"trace[32594624] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"334.662035ms","start":"2026-07-01T00:47:32.446019Z","end":"2026-07-01T00:47:32.780681Z","steps":["trace[32594624] 'process raft request' (duration: 326.616874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:32.780821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:32.445999Z","time spent":"334.7503ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:47:32.780891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.640349ms","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-01T00:47:32.780948Z","caller":"traceutil/trace.go:171","msg":"trace[546057766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"221.746305ms","start":"2026-07-01T00:47:32.559184Z","end":"2026-07-01T00:47:32.780931Z","steps":["trace[546057766] 'agreement among raft nodes before linearized reading' (duration: 221.497152ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:32.953084Z","caller":"traceutil/trace.go:171","msg":"trace[138864892] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"167.371579ms","start":"2026-07-01T00:47:32.785675Z","end":"2026-07-01T00:47:32.953046Z","steps":["trace[138864892] 'process raft request' (duration: 167.285995ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:32.95316Z","caller":"traceutil/trace.go:171","msg":"trace[595779214] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"168.438789ms","start":"2026-07-01T00:47:32.784669Z","end":"2026-07-01T00:47:32.953107Z","steps":["trace[595779214] 'process raft request' (duration: 166.982821ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:32.953241Z","caller":"traceutil/trace.go:171","msg":"trace[2002442733] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"166.037728ms","start":"2026-07-01T00:47:32.787189Z","end":"2026-07-01T00:47:32.953227Z","steps":["trace[2002442733] 'process raft request' (duration: 165.820248ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:32.957541Z","caller":"traceutil/trace.go:171","msg":"trace[53971472] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"164.13627ms","start":"2026-07-01T00:47:32.793357Z","end":"2026-07-01T00:47:32.957493Z","steps":["trace[53971472] 'process raft request' (duration: 163.945101ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:39.465937Z","caller":"traceutil/trace.go:171","msg":"trace[576136825] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"285.264361ms","start":"2026-07-01T00:47:39.180645Z","end":"2026-07-01T00:47:39.46591Z","steps":["trace[576136825] 'read index received' (duration: 285.03013ms)","trace[576136825] 'applied index is now lower than readState.Index' (duration: 233.011µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:47:39.466073Z","caller":"traceutil/trace.go:171","msg":"trace[1999606818] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"285.941273ms","start":"2026-07-01T00:47:39.180119Z","end":"2026-07-01T00:47:39.46606Z","steps":["trace[1999606818] 'process raft request' (duration: 285.618548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:39.466407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.719603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:47:39.466497Z","caller":"traceutil/trace.go:171","msg":"trace[1572564118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"285.848029ms","start":"2026-07-01T00:47:39.180625Z","end":"2026-07-01T00:47:39.466473Z","steps":["trace[1572564118] 'agreement among raft nodes before linearized reading' (duration: 285.651269ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:39.46767Z","caller":"traceutil/trace.go:171","msg":"trace[1526846108] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"282.073228ms","start":"2026-07-01T00:47:39.185474Z","end":"2026-07-01T00:47:39.467548Z","steps":["trace[1526846108] 'process raft request' (duration: 281.860778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:39.468343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.566479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:47:39.468446Z","caller":"traceutil/trace.go:171","msg":"trace[1795909768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"279.78337ms","start":"2026-07-01T00:47:39.188649Z","end":"2026-07-01T00:47:39.468433Z","steps":["trace[1795909768] 'agreement among raft nodes before linearized reading' (duration: 279.593201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:39.467585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.028773ms","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-01T00:47:39.469157Z","caller":"traceutil/trace.go:171","msg":"trace[123324361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"280.475502ms","start":"2026-07-01T00:47:39.188511Z","end":"2026-07-01T00:47:39.468986Z","steps":["trace[123324361] 'agreement among raft nodes before linearized reading' (duration: 278.938008ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:58.163671Z","caller":"traceutil/trace.go:171","msg":"trace[998981494] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"590.285513ms","start":"2026-07-01T00:47:57.573357Z","end":"2026-07-01T00:47:58.163643Z","steps":["trace[998981494] 'process raft request' (duration: 589.429489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:58.163815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:57.573329Z","time spent":"590.41308ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:47:58.164676Z","caller":"traceutil/trace.go:171","msg":"trace[811208743] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1195; }","duration":"241.762919ms","start":"2026-07-01T00:47:57.922897Z","end":"2026-07-01T00:47:58.16466Z","steps":["trace[811208743] 'read index received' (duration: 204.884086ms)","trace[811208743] 'applied index is now lower than readState.Index' (duration: 36.878163ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:47:58.164765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.853743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:47:58.164791Z","caller":"traceutil/trace.go:171","msg":"trace[831927146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"241.949968ms","start":"2026-07-01T00:47:57.922834Z","end":"2026-07-01T00:47:58.164784Z","steps":["trace[831927146] 'agreement among raft nodes before linearized reading' (duration: 241.890205ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:58.587891Z","caller":"traceutil/trace.go:171","msg":"trace[1140058553] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"418.338904ms","start":"2026-07-01T00:47:58.16953Z","end":"2026-07-01T00:47:58.587869Z","steps":["trace[1140058553] 'process raft request' (duration: 416.364523ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:47:58.588004Z","caller":"traceutil/trace.go:171","msg":"trace[1810462675] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"332.849776ms","start":"2026-07-01T00:47:58.255131Z","end":"2026-07-01T00:47:58.587981Z","steps":["trace[1810462675] 'process raft request' (duration: 332.402152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:47:58.588023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:58.169494Z","time spent":"418.457679ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:47:58.588113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:47:58.255101Z","time spent":"332.94934ms","remote":"127.0.0.1:45156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:48:18.814114Z","caller":"traceutil/trace.go:171","msg":"trace[168749676] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"117.625654ms","start":"2026-07-01T00:48:18.696454Z","end":"2026-07-01T00:48:18.814079Z","steps":["trace[168749676] 'process raft request' (duration: 81.192008ms)","trace[168749676] 'compare' (duration: 36.328ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:48:50.797759Z","caller":"traceutil/trace.go:171","msg":"trace[319031329] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"265.838215ms","start":"2026-07-01T00:48:50.53189Z","end":"2026-07-01T00:48:50.797728Z","steps":["trace[319031329] 'read index received' (duration: 265.57067ms)","trace[319031329] 'applied index is now lower than readState.Index' (duration: 265.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:48:50.797926Z","caller":"traceutil/trace.go:171","msg":"trace[1627940298] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"268.251042ms","start":"2026-07-01T00:48:50.529609Z","end":"2026-07-01T00:48:50.79786Z","steps":["trace[1627940298] 'process raft request' (duration: 267.926724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:48:50.798027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.102821ms","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-01T00:48:50.798069Z","caller":"traceutil/trace.go:171","msg":"trace[1939500529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"266.237158ms","start":"2026-07-01T00:48:50.531819Z","end":"2026-07-01T00:48:50.798056Z","steps":["trace[1939500529] 'agreement among raft nodes before linearized reading' (duration: 266.038587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:48:51.585241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.457628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:48:51.58537Z","caller":"traceutil/trace.go:171","msg":"trace[1808582371] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1361; }","duration":"418.699052ms","start":"2026-07-01T00:48:51.166642Z","end":"2026-07-01T00:48:51.585341Z","steps":["trace[1808582371] 'count revisions from in-memory index tree' (duration: 418.328621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:48:51.585441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:48:51.166616Z","time spent":"418.807737ms","remote":"127.0.0.1:45300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:49:00.959225Z","caller":"traceutil/trace.go:171","msg":"trace[1050576217] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"104.586197ms","start":"2026-07-01T00:49:00.854594Z","end":"2026-07-01T00:49:00.95918Z","steps":["trace[1050576217] 'process raft request' (duration: 104.302261ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:49:01.065495Z","caller":"traceutil/trace.go:171","msg":"trace[1415806873] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"101.320135ms","start":"2026-07-01T00:49:00.964148Z","end":"2026-07-01T00:49:01.065469Z","steps":["trace[1415806873] 'process raft request' (duration: 87.585901ms)","trace[1415806873] 'compare' (duration: 13.450318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:49:02.115091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.069647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-01T00:49:02.115197Z","caller":"traceutil/trace.go:171","msg":"trace[1048626780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1399; }","duration":"112.258618ms","start":"2026-07-01T00:49:02.002917Z","end":"2026-07-01T00:49:02.115176Z","steps":["trace[1048626780] 'range keys from in-memory index tree' (duration: 111.76425ms)"],"step_count":1}