{"level":"warn","ts":"2026-05-07T13:48:17.201092Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T13:48:17.201181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-05-07T13:48:17.20125Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T13:48:17.201263Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-07T13:48:17.201282Z","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-05-07T13:48:17.201968Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-05-07T13:48:17.202045Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-05-07T13:48:17.20734Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.074379ms"} {"level":"info","ts":"2026-05-07T13:48:17.213847Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-05-07T13:48:17.214177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T13:48:17.214206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-05-07T13:48:17.214219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T13:48:17.214484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-05-07T13:48:17.214569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-05-07T13:48:17.22461Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T13:48:17.227712Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T13:48:17.228841Z","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-05-07T13:48:17.232213Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T13:48:17.232335Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T13:48:17.232386Z","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-05-07T13:48:17.232515Z","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-05-07T13:48:17.232537Z","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-05-07T13:48:17.234138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-05-07T13:48:17.234523Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-05-07T13:48:17.237078Z","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-05-07T13:48:17.237184Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-07T13:48:17.237225Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-05-07T13:48:17.237325Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T13:48:17.237372Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T13:48:17.715096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T13:48:17.715178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T13:48:17.715199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-05-07T13:48:17.715215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-05-07T13:48:17.715224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-07T13:48:17.715237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-05-07T13:48:17.715247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-05-07T13:48:17.715956Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:48:17.716649Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T13:48:17.716857Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:48:17.716902Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:48:17.717008Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:48:17.716969Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T13:48:17.717037Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T13:48:17.720127Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T13:48:17.720165Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T13:48:17.72036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T13:48:17.721047Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-05-07T13:48:19.61085Z","caller":"traceutil/trace.go:171","msg":"trace[2084752273] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"284.578277ms","start":"2026-05-07T13:48:19.326249Z","end":"2026-05-07T13:48:19.610828Z","steps":["trace[2084752273] 'process raft request' (duration: 284.309079ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.610909Z","caller":"traceutil/trace.go:171","msg":"trace[361747784] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"237.674941ms","start":"2026-05-07T13:48:19.373205Z","end":"2026-05-07T13:48:19.61088Z","steps":["trace[361747784] 'process raft request' (duration: 237.485126ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611013Z","caller":"traceutil/trace.go:171","msg":"trace[1403839354] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"274.85389ms","start":"2026-05-07T13:48:19.336134Z","end":"2026-05-07T13:48:19.610988Z","steps":["trace[1403839354] 'read index received' (duration: 87.274477ms)","trace[1403839354] 'applied index is now lower than readState.Index' (duration: 187.578173ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:48:19.611113Z","caller":"traceutil/trace.go:171","msg":"trace[92853755] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"207.689602ms","start":"2026-05-07T13:48:19.403378Z","end":"2026-05-07T13:48:19.611067Z","steps":["trace[92853755] 'process raft request' (duration: 207.486198ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611231Z","caller":"traceutil/trace.go:171","msg":"trace[443529381] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"214.750562ms","start":"2026-05-07T13:48:19.39644Z","end":"2026-05-07T13:48:19.61119Z","steps":["trace[443529381] 'process raft request' (duration: 214.304261ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.61126Z","caller":"traceutil/trace.go:171","msg":"trace[466219013] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"198.681896ms","start":"2026-05-07T13:48:19.412564Z","end":"2026-05-07T13:48:19.611246Z","steps":["trace[466219013] 'process raft request' (duration: 198.392248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:19.611348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.287836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:48:19.611421Z","caller":"traceutil/trace.go:171","msg":"trace[1986705450] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:0; response_revision:11; }","duration":"240.388389ms","start":"2026-05-07T13:48:19.371017Z","end":"2026-05-07T13:48:19.611406Z","steps":["trace[1986705450] 'agreement among raft nodes before linearized reading' (duration: 240.240286ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611431Z","caller":"traceutil/trace.go:171","msg":"trace[1798690616] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"207.540639ms","start":"2026-05-07T13:48:19.403866Z","end":"2026-05-07T13:48:19.611406Z","steps":["trace[1798690616] 'process raft request' (duration: 207.060917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:19.611472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.227717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T13:48:19.611575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.974131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:48:19.611598Z","caller":"traceutil/trace.go:171","msg":"trace[284707612] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"233.015233ms","start":"2026-05-07T13:48:19.378576Z","end":"2026-05-07T13:48:19.611592Z","steps":["trace[284707612] 'agreement among raft nodes before linearized reading' (duration: 232.986732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:19.611741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.261026ms","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":"warn","ts":"2026-05-07T13:48:19.6118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.240246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:48:19.611808Z","caller":"traceutil/trace.go:171","msg":"trace[1186936709] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"238.341098ms","start":"2026-05-07T13:48:19.373441Z","end":"2026-05-07T13:48:19.611782Z","steps":["trace[1186936709] 'agreement among raft nodes before linearized reading' (duration: 238.247125ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611829Z","caller":"traceutil/trace.go:171","msg":"trace[1187328984] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"138.268437ms","start":"2026-05-07T13:48:19.473554Z","end":"2026-05-07T13:48:19.611823Z","steps":["trace[1187328984] 'agreement among raft nodes before linearized reading' (duration: 138.229076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:19.611862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.526243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:48:19.611914Z","caller":"traceutil/trace.go:171","msg":"trace[403110223] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"138.607575ms","start":"2026-05-07T13:48:19.473294Z","end":"2026-05-07T13:48:19.611902Z","steps":["trace[403110223] 'agreement among raft nodes before linearized reading' (duration: 138.509333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:19.611974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.39851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-07T13:48:19.61192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.783664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:48:19.612019Z","caller":"traceutil/trace.go:171","msg":"trace[1721727466] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"238.472912ms","start":"2026-05-07T13:48:19.373536Z","end":"2026-05-07T13:48:19.612009Z","steps":["trace[1721727466] 'agreement among raft nodes before linearized reading' (duration: 238.40738ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.612037Z","caller":"traceutil/trace.go:171","msg":"trace[1807949209] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"275.898427ms","start":"2026-05-07T13:48:19.336131Z","end":"2026-05-07T13:48:19.612029Z","steps":["trace[1807949209] 'agreement among raft nodes before linearized reading' (duration: 275.772414ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611966Z","caller":"traceutil/trace.go:171","msg":"trace[1407805695] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"159.433572ms","start":"2026-05-07T13:48:19.452141Z","end":"2026-05-07T13:48:19.611574Z","steps":["trace[1407805695] 'agreement among raft nodes before linearized reading' (duration: 159.129125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:19.611541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.681439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-07T13:48:19.612029Z","caller":"traceutil/trace.go:171","msg":"trace[549280699] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"209.622981ms","start":"2026-05-07T13:48:19.402389Z","end":"2026-05-07T13:48:19.612012Z","steps":["trace[549280699] 'process raft request' (duration: 208.38946ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.612137Z","caller":"traceutil/trace.go:171","msg":"trace[231746055] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"162.312594ms","start":"2026-05-07T13:48:19.449819Z","end":"2026-05-07T13:48:19.612132Z","steps":["trace[231746055] 'agreement among raft nodes before linearized reading' (duration: 161.703089ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611391Z","caller":"traceutil/trace.go:171","msg":"trace[1429751512] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"208.113743ms","start":"2026-05-07T13:48:19.403243Z","end":"2026-05-07T13:48:19.611357Z","steps":["trace[1429751512] 'process raft request' (duration: 207.5941ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:19.611154Z","caller":"traceutil/trace.go:171","msg":"trace[554388784] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"207.691023ms","start":"2026-05-07T13:48:19.403447Z","end":"2026-05-07T13:48:19.611138Z","steps":["trace[554388784] 'process raft request' (duration: 207.441047ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:23.142557Z","caller":"traceutil/trace.go:171","msg":"trace[1308756396] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"112.30725ms","start":"2026-05-07T13:48:23.030204Z","end":"2026-05-07T13:48:23.142511Z","steps":["trace[1308756396] 'process raft request' (duration: 101.999769ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:48.346406Z","caller":"traceutil/trace.go:171","msg":"trace[900782786] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"226.502451ms","start":"2026-05-07T13:48:48.11937Z","end":"2026-05-07T13:48:48.345873Z","steps":["trace[900782786] 'process raft request' (duration: 225.211489ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:48.346421Z","caller":"traceutil/trace.go:171","msg":"trace[390583516] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"231.374612ms","start":"2026-05-07T13:48:48.114741Z","end":"2026-05-07T13:48:48.346115Z","steps":["trace[390583516] 'process raft request' (duration: 222.02073ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:48.348704Z","caller":"traceutil/trace.go:171","msg":"trace[2042869903] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:609; }","duration":"224.837691ms","start":"2026-05-07T13:48:48.123823Z","end":"2026-05-07T13:48:48.348661Z","steps":["trace[2042869903] 'read index received' (duration: 212.654948ms)","trace[2042869903] 'applied index is now lower than readState.Index' (duration: 12.182143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:48:48.34882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.980154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-96b9z\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-05-07T13:48:48.34885Z","caller":"traceutil/trace.go:171","msg":"trace[105161579] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-96b9z; range_end:; response_count:1; response_revision:595; }","duration":"225.048295ms","start":"2026-05-07T13:48:48.123792Z","end":"2026-05-07T13:48:48.348841Z","steps":["trace[105161579] 'agreement among raft nodes before linearized reading' (duration: 224.963203ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:48.348809Z","caller":"traceutil/trace.go:171","msg":"trace[8067763] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"150.001206ms","start":"2026-05-07T13:48:48.198788Z","end":"2026-05-07T13:48:48.348789Z","steps":["trace[8067763] 'process raft request' (duration: 146.267553ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:48.348865Z","caller":"traceutil/trace.go:171","msg":"trace[1141911372] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"126.863959ms","start":"2026-05-07T13:48:48.221969Z","end":"2026-05-07T13:48:48.348833Z","steps":["trace[1141911372] 'process raft request' (duration: 126.643404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:54.123094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.241807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:48:54.12322Z","caller":"traceutil/trace.go:171","msg":"trace[924534101] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"303.813808ms","start":"2026-05-07T13:48:53.819389Z","end":"2026-05-07T13:48:54.123202Z","steps":["trace[924534101] 'read index received' (duration: 143.127315ms)","trace[924534101] 'applied index is now lower than readState.Index' (duration: 160.685113ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:48:54.123281Z","caller":"traceutil/trace.go:171","msg":"trace[406215995] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"306.565606ms","start":"2026-05-07T13:48:53.816689Z","end":"2026-05-07T13:48:54.123254Z","steps":["trace[406215995] 'process raft request' (duration: 145.814892ms)","trace[406215995] 'compare' (duration: 159.104353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:48:54.123428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.032663ms","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-05-07T13:48:54.123457Z","caller":"traceutil/trace.go:171","msg":"trace[80217397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"304.076513ms","start":"2026-05-07T13:48:53.81937Z","end":"2026-05-07T13:48:54.123446Z","steps":["trace[80217397] 'agreement among raft nodes before linearized reading' (duration: 303.93002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:54.123488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:48:53.819362Z","time spent":"304.118424ms","remote":"127.0.0.1:55736","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-05-07T13:48:54.12361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:48:53.816672Z","time spent":"306.72545ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:48:54.507041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.464214ms","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-05-07T13:48:54.50714Z","caller":"traceutil/trace.go:171","msg":"trace[1434875444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"139.628829ms","start":"2026-05-07T13:48:54.367479Z","end":"2026-05-07T13:48:54.507108Z","steps":["trace[1434875444] 'range keys from in-memory index tree' (duration: 139.197446ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:48:55.600024Z","caller":"traceutil/trace.go:171","msg":"trace[290324315] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"118.604184ms","start":"2026-05-07T13:48:55.481395Z","end":"2026-05-07T13:48:55.6Z","steps":["trace[290324315] 'read index received' (duration: 118.336327ms)","trace[290324315] 'applied index is now lower than readState.Index' (duration: 267.067µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:48:55.600108Z","caller":"traceutil/trace.go:171","msg":"trace[1087553688] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"444.189114ms","start":"2026-05-07T13:48:55.15587Z","end":"2026-05-07T13:48:55.600059Z","steps":["trace[1087553688] 'process raft request' (duration: 443.982099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:55.600266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.854941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T13:48:55.600315Z","caller":"traceutil/trace.go:171","msg":"trace[349138748] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"118.966114ms","start":"2026-05-07T13:48:55.481337Z","end":"2026-05-07T13:48:55.600304Z","steps":["trace[349138748] 'agreement among raft nodes before linearized reading' (duration: 118.79654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:48:55.600362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:48:55.155833Z","time spent":"444.394629ms","remote":"127.0.0.1:55656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:49:17.822471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.662541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-07T13:49:17.822571Z","caller":"traceutil/trace.go:171","msg":"trace[1597864060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"113.815955ms","start":"2026-05-07T13:49:17.708736Z","end":"2026-05-07T13:49:17.822552Z","steps":["trace[1597864060] 'range keys from in-memory index tree' (duration: 113.451626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:49:17.822486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.991914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T13:49:17.823026Z","caller":"traceutil/trace.go:171","msg":"trace[311780100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"111.59561ms","start":"2026-05-07T13:49:17.711409Z","end":"2026-05-07T13:49:17.823004Z","steps":["trace[311780100] 'range keys from in-memory index tree' (duration: 110.82727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:49:52.82183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.908801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:49:52.822035Z","caller":"traceutil/trace.go:171","msg":"trace[543560970] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"174.269039ms","start":"2026-05-07T13:49:52.647747Z","end":"2026-05-07T13:49:52.822016Z","steps":["trace[543560970] 'read index received' (duration: 153.401837ms)","trace[543560970] 'applied index is now lower than readState.Index' (duration: 20.866091ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:49:52.822119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.375282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:49:52.822147Z","caller":"traceutil/trace.go:171","msg":"trace[1614185690] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1020; }","duration":"174.450113ms","start":"2026-05-07T13:49:52.647687Z","end":"2026-05-07T13:49:52.822137Z","steps":["trace[1614185690] 'agreement among raft nodes before linearized reading' (duration: 174.379922ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:49:52.82236Z","caller":"traceutil/trace.go:171","msg":"trace[1555616416] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"384.468187ms","start":"2026-05-07T13:49:52.437858Z","end":"2026-05-07T13:49:52.822327Z","steps":["trace[1555616416] 'process raft request' (duration: 151.957601ms)","trace[1555616416] 'compare' (duration: 231.787998ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:49:52.822374Z","caller":"traceutil/trace.go:171","msg":"trace[2002802340] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"348.20712ms","start":"2026-05-07T13:49:52.474115Z","end":"2026-05-07T13:49:52.822322Z","steps":["trace[2002802340] 'process raft request' (duration: 347.841461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:49:52.822502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:49:52.437849Z","time spent":"384.57597ms","remote":"127.0.0.1:55736","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-05-07T13:49:52.822564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:49:52.474099Z","time spent":"348.380764ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T13:49:52.959721Z","caller":"traceutil/trace.go:171","msg":"trace[2040408194] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"130.899894ms","start":"2026-05-07T13:49:52.828794Z","end":"2026-05-07T13:49:52.959694Z","steps":["trace[2040408194] 'process raft request' (duration: 125.921919ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:50:14.658245Z","caller":"traceutil/trace.go:171","msg":"trace[765072493] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"125.166262ms","start":"2026-05-07T13:50:14.533047Z","end":"2026-05-07T13:50:14.658213Z","steps":["trace[765072493] 'process raft request' (duration: 124.895725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:50:15.179158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.51467ms","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-05-07T13:50:15.179252Z","caller":"traceutil/trace.go:171","msg":"trace[1750148636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"129.695934ms","start":"2026-05-07T13:50:15.049533Z","end":"2026-05-07T13:50:15.179229Z","steps":["trace[1750148636] 'range keys from in-memory index tree' (duration: 129.315955ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:50:27.393053Z","caller":"traceutil/trace.go:171","msg":"trace[1264252688] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"376.160539ms","start":"2026-05-07T13:50:27.016867Z","end":"2026-05-07T13:50:27.393027Z","steps":["trace[1264252688] 'read index received' (duration: 375.985274ms)","trace[1264252688] 'applied index is now lower than readState.Index' (duration: 174.065µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:50:27.393407Z","caller":"traceutil/trace.go:171","msg":"trace[2123754381] transaction","detail":"{read_only:false; response_revision:1182; number_of_response:1; }","duration":"393.320568ms","start":"2026-05-07T13:50:27.000075Z","end":"2026-05-07T13:50:27.393395Z","steps":["trace[2123754381] 'process raft request' (duration: 392.792195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:50:27.393525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:50:27.00004Z","time spent":"393.40489ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T13:50:27.393779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.913057ms","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-05-07T13:50:27.39382Z","caller":"traceutil/trace.go:171","msg":"trace[1551037415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1182; }","duration":"377.002239ms","start":"2026-05-07T13:50:27.016808Z","end":"2026-05-07T13:50:27.393811Z","steps":["trace[1551037415] 'agreement among raft nodes before linearized reading' (duration: 376.881096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:50:27.393849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:50:27.01677Z","time spent":"377.073501ms","remote":"127.0.0.1:55736","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-05-07T13:50:27.394001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.694376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:50:27.394034Z","caller":"traceutil/trace.go:171","msg":"trace[1270921742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1182; }","duration":"351.754648ms","start":"2026-05-07T13:50:27.042271Z","end":"2026-05-07T13:50:27.394026Z","steps":["trace[1270921742] 'agreement among raft nodes before linearized reading' (duration: 351.706857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:50:27.394065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:50:27.042257Z","time spent":"351.803439ms","remote":"127.0.0.1:55478","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-05-07T13:50:27.394333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.222156ms","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-05-07T13:50:27.394367Z","caller":"traceutil/trace.go:171","msg":"trace[110652022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1182; }","duration":"146.257377ms","start":"2026-05-07T13:50:27.248102Z","end":"2026-05-07T13:50:27.394359Z","steps":["trace[110652022] 'agreement among raft nodes before linearized reading' (duration: 146.167185ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:50:27.505069Z","caller":"traceutil/trace.go:171","msg":"trace[2109405025] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"105.675744ms","start":"2026-05-07T13:50:27.399367Z","end":"2026-05-07T13:50:27.505043Z","steps":["trace[2109405025] 'process raft request' (duration: 46.839122ms)","trace[2109405025] 'compare' (duration: 58.7353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:50:44.173263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.575605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:50:44.173403Z","caller":"traceutil/trace.go:171","msg":"trace[1163832303] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"131.447748ms","start":"2026-05-07T13:50:44.041937Z","end":"2026-05-07T13:50:44.173385Z","steps":["trace[1163832303] 'read index received' (duration: 4.663927ms)","trace[1163832303] 'applied index is now lower than readState.Index' (duration: 126.782561ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:50:44.173523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.580402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:50:44.173567Z","caller":"traceutil/trace.go:171","msg":"trace[2019839975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1260; }","duration":"131.661004ms","start":"2026-05-07T13:50:44.041897Z","end":"2026-05-07T13:50:44.173558Z","steps":["trace[2019839975] 'agreement among raft nodes before linearized reading' (duration: 131.564382ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:50:44.173591Z","caller":"traceutil/trace.go:171","msg":"trace[516797597] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"212.391292ms","start":"2026-05-07T13:50:43.961178Z","end":"2026-05-07T13:50:44.173569Z","steps":["trace[516797597] 'process raft request' (duration: 85.440897ms)","trace[516797597] 'compare' (duration: 126.464773ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:50:44.383159Z","caller":"traceutil/trace.go:171","msg":"trace[1891158701] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"204.431564ms","start":"2026-05-07T13:50:44.178709Z","end":"2026-05-07T13:50:44.383141Z","steps":["trace[1891158701] 'process raft request' (duration: 204.364562ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:50:44.383224Z","caller":"traceutil/trace.go:171","msg":"trace[1238343264] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"208.483286ms","start":"2026-05-07T13:50:44.17473Z","end":"2026-05-07T13:50:44.383213Z","steps":["trace[1238343264] 'read index received' (duration: 141.681835ms)","trace[1238343264] 'applied index is now lower than readState.Index' (duration: 66.799671ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:50:44.383157Z","caller":"traceutil/trace.go:171","msg":"trace[835453683] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"260.200199ms","start":"2026-05-07T13:50:44.122929Z","end":"2026-05-07T13:50:44.38313Z","steps":["trace[835453683] 'process raft request' (duration: 193.469199ms)","trace[835453683] 'compare' (duration: 66.399461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:50:44.383443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.6674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:50:44.383481Z","caller":"traceutil/trace.go:171","msg":"trace[939655865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"208.764102ms","start":"2026-05-07T13:50:44.174705Z","end":"2026-05-07T13:50:44.383469Z","steps":["trace[939655865] 'agreement among raft nodes before linearized reading' (duration: 208.608098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:50:44.383588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.533586ms","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-05-07T13:50:44.383616Z","caller":"traceutil/trace.go:171","msg":"trace[248792408] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1262; }","duration":"206.604038ms","start":"2026-05-07T13:50:44.177003Z","end":"2026-05-07T13:50:44.383607Z","steps":["trace[248792408] 'agreement among raft nodes before linearized reading' (duration: 206.492964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:50:45.04758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.975041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T13:50:45.047652Z","caller":"traceutil/trace.go:171","msg":"trace[119214127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1267; }","duration":"122.093574ms","start":"2026-05-07T13:50:44.925543Z","end":"2026-05-07T13:50:45.047636Z","steps":["trace[119214127] 'count revisions from in-memory index tree' (duration: 121.90579ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:51:00.673046Z","caller":"traceutil/trace.go:171","msg":"trace[169859569] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"150.79349ms","start":"2026-05-07T13:51:00.522225Z","end":"2026-05-07T13:51:00.673018Z","steps":["trace[169859569] 'process raft request' (duration: 150.625186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:51:00.968476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.374217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T13:51:00.968587Z","caller":"traceutil/trace.go:171","msg":"trace[1114943090] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"121.072435ms","start":"2026-05-07T13:51:00.847496Z","end":"2026-05-07T13:51:00.968568Z","steps":["trace[1114943090] 'read index received' (duration: 37.871µs)","trace[1114943090] 'applied index is now lower than readState.Index' (duration: 121.033074ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T13:51:00.968829Z","caller":"traceutil/trace.go:171","msg":"trace[2086036057] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"444.203183ms","start":"2026-05-07T13:51:00.524592Z","end":"2026-05-07T13:51:00.968795Z","steps":["trace[2086036057] 'process raft request' (duration: 249.420165ms)","trace[2086036057] 'compare' (duration: 194.181002ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T13:51:00.968913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.2702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T13:51:00.96891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.406264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-07T13:51:00.968953Z","caller":"traceutil/trace.go:171","msg":"trace[1256719983] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1366; }","duration":"108.331582ms","start":"2026-05-07T13:51:00.86061Z","end":"2026-05-07T13:51:00.968942Z","steps":["trace[1256719983] 'agreement among raft nodes before linearized reading' (duration: 108.2571ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T13:51:00.968977Z","caller":"traceutil/trace.go:171","msg":"trace[1307538342] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1366; }","duration":"121.531266ms","start":"2026-05-07T13:51:00.847429Z","end":"2026-05-07T13:51:00.96896Z","steps":["trace[1307538342] 'agreement among raft nodes before linearized reading' (duration: 121.354242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T13:51:00.968981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T13:51:00.524566Z","time spent":"444.336285ms","remote":"127.0.0.1:55736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}