{"level":"warn","ts":"2026-04-20T20:55:08.447299Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:55:08.447384Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205: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-04-20T20:55:08.447444Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:55:08.447458Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-20T20:55:08.447504Z","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-04-20T20:55:08.448064Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-04-20T20:55:08.448134Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205: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-04-20T20:55:08.453618Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.139488ms"} {"level":"info","ts":"2026-04-20T20:55:08.46261Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-04-20T20:55:08.46289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T20:55:08.463068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-04-20T20:55:08.463146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T20:55:08.46318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-04-20T20:55:08.46331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-04-20T20:55:08.471344Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T20:55:08.476803Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T20:55:08.484319Z","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-04-20T20:55:08.488112Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T20:55:08.488466Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T20:55:08.488624Z","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-04-20T20:55:08.488715Z","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-04-20T20:55:08.488732Z","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-04-20T20:55:08.492336Z","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-04-20T20:55:08.49245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-04-20T20:55:08.492585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-20T20:55:08.49258Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-04-20T20:55:08.492611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-04-20T20:55:08.492612Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T20:55:08.492678Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T20:55:09.26391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T20:55:09.263957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T20:55:09.264258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-04-20T20:55:09.264295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-04-20T20:55:09.264309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-20T20:55:09.264342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-04-20T20:55:09.264358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-04-20T20:55:09.265357Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:55:09.26649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:55:09.266492Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T20:55:09.266514Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:55:09.266957Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T20:55:09.267Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T20:55:09.267069Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:55:09.267309Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:55:09.267357Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:55:09.268713Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-04-20T20:55:09.268952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T20:55:37.942356Z","caller":"traceutil/trace.go:171","msg":"trace[268727056] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"181.24304ms","start":"2026-04-20T20:55:37.761099Z","end":"2026-04-20T20:55:37.942342Z","steps":["trace[268727056] 'process raft request' (duration: 178.620059ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:37.942567Z","caller":"traceutil/trace.go:171","msg":"trace[260680478] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"179.968261ms","start":"2026-04-20T20:55:37.76259Z","end":"2026-04-20T20:55:37.942558Z","steps":["trace[260680478] 'process raft request' (duration: 179.646583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:37.942492Z","caller":"traceutil/trace.go:171","msg":"trace[1974960915] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"178.596828ms","start":"2026-04-20T20:55:37.76389Z","end":"2026-04-20T20:55:37.942487Z","steps":["trace[1974960915] 'process raft request' (duration: 178.569357ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:37.946009Z","caller":"traceutil/trace.go:171","msg":"trace[637778340] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"174.103052ms","start":"2026-04-20T20:55:37.771882Z","end":"2026-04-20T20:55:37.945985Z","steps":["trace[637778340] 'process raft request' (duration: 173.787225ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:37.946372Z","caller":"traceutil/trace.go:171","msg":"trace[1945945646] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"126.889534ms","start":"2026-04-20T20:55:37.819472Z","end":"2026-04-20T20:55:37.946361Z","steps":["trace[1945945646] 'process raft request' (duration: 126.446124ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:44.352296Z","caller":"traceutil/trace.go:171","msg":"trace[1039683424] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"321.039982ms","start":"2026-04-20T20:55:44.031226Z","end":"2026-04-20T20:55:44.352266Z","steps":["trace[1039683424] 'process raft request' (duration: 320.402216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:44.35297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:44.030844Z","time spent":"321.549604ms","remote":"127.0.0.1:44542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:55:44.374896Z","caller":"traceutil/trace.go:171","msg":"trace[978791678] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"293.983931ms","start":"2026-04-20T20:55:44.080903Z","end":"2026-04-20T20:55:44.374887Z","steps":["trace[978791678] 'process raft request' (duration: 293.417086ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:44.374791Z","caller":"traceutil/trace.go:171","msg":"trace[2066816964] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:594; }","duration":"189.678472ms","start":"2026-04-20T20:55:44.185089Z","end":"2026-04-20T20:55:44.374768Z","steps":["trace[2066816964] 'read index received' (duration: 166.351429ms)","trace[2066816964] 'applied index is now lower than readState.Index' (duration: 23.326363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:44.375304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.201843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:55:44.375336Z","caller":"traceutil/trace.go:171","msg":"trace[19470085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"190.331107ms","start":"2026-04-20T20:55:44.184998Z","end":"2026-04-20T20:55:44.375329Z","steps":["trace[19470085] 'agreement among raft nodes before linearized reading' (duration: 190.239185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:55:44.847691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.953036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:55:44.847939Z","caller":"traceutil/trace.go:171","msg":"trace[376859101] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"438.169505ms","start":"2026-04-20T20:55:44.409756Z","end":"2026-04-20T20:55:44.847925Z","steps":["trace[376859101] 'process raft request' (duration: 27.783408ms)","trace[376859101] 'compare' (duration: 409.770842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:44.848018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:55:44.409736Z","time spent":"438.253407ms","remote":"127.0.0.1:44670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:55:44.848287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.721084ms","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-04-20T20:55:44.848406Z","caller":"traceutil/trace.go:171","msg":"trace[392332648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"282.891668ms","start":"2026-04-20T20:55:44.565495Z","end":"2026-04-20T20:55:44.848387Z","steps":["trace[392332648] 'agreement among raft nodes before linearized reading' (duration: 282.54296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:44.847888Z","caller":"traceutil/trace.go:171","msg":"trace[1165072889] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"282.329704ms","start":"2026-04-20T20:55:44.565541Z","end":"2026-04-20T20:55:44.847871Z","steps":["trace[1165072889] 'read index received' (duration: 37.151µs)","trace[1165072889] 'applied index is now lower than readState.Index' (duration: 282.291283ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:32.725515Z","caller":"traceutil/trace.go:171","msg":"trace[258862800] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"106.668425ms","start":"2026-04-20T20:56:32.618804Z","end":"2026-04-20T20:56:32.725472Z","steps":["trace[258862800] 'process raft request' (duration: 106.529172ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:55.484672Z","caller":"traceutil/trace.go:171","msg":"trace[1379765721] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"100.409714ms","start":"2026-04-20T20:56:55.384237Z","end":"2026-04-20T20:56:55.484646Z","steps":["trace[1379765721] 'process raft request' (duration: 100.242489ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:56.616261Z","caller":"traceutil/trace.go:171","msg":"trace[987140272] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"224.62481ms","start":"2026-04-20T20:56:56.391614Z","end":"2026-04-20T20:56:56.616238Z","steps":["trace[987140272] 'read index received' (duration: 224.486177ms)","trace[987140272] 'applied index is now lower than readState.Index' (duration: 137.893µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:56:56.616436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.815496ms","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-04-20T20:56:56.616467Z","caller":"traceutil/trace.go:171","msg":"trace[1477961889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1029; }","duration":"224.924508ms","start":"2026-04-20T20:56:56.391533Z","end":"2026-04-20T20:56:56.616457Z","steps":["trace[1477961889] 'agreement among raft nodes before linearized reading' (duration: 224.792215ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:56.61651Z","caller":"traceutil/trace.go:171","msg":"trace[163142892] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"237.655759ms","start":"2026-04-20T20:56:56.378841Z","end":"2026-04-20T20:56:56.616497Z","steps":["trace[163142892] 'process raft request' (duration: 237.257179ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:08.800036Z","caller":"traceutil/trace.go:171","msg":"trace[1775515582] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"123.533181ms","start":"2026-04-20T20:57:08.676204Z","end":"2026-04-20T20:57:08.799737Z","steps":["trace[1775515582] 'process raft request' (duration: 120.791526ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:27.491846Z","caller":"traceutil/trace.go:171","msg":"trace[313283394] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"100.69142ms","start":"2026-04-20T20:57:27.39113Z","end":"2026-04-20T20:57:27.491821Z","steps":["trace[313283394] 'process raft request' (duration: 100.541176ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:27.499326Z","caller":"traceutil/trace.go:171","msg":"trace[1265687941] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"105.439834ms","start":"2026-04-20T20:57:27.393873Z","end":"2026-04-20T20:57:27.499313Z","steps":["trace[1265687941] 'process raft request' (duration: 105.376412ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:40.231411Z","caller":"traceutil/trace.go:171","msg":"trace[322846134] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"176.892491ms","start":"2026-04-20T20:57:40.054496Z","end":"2026-04-20T20:57:40.231389Z","steps":["trace[322846134] 'read index received' (duration: 176.700187ms)","trace[322846134] 'applied index is now lower than readState.Index' (duration: 191.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:40.231704Z","caller":"traceutil/trace.go:171","msg":"trace[1626123557] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"186.440338ms","start":"2026-04-20T20:57:40.045251Z","end":"2026-04-20T20:57:40.231692Z","steps":["trace[1626123557] 'process raft request' (duration: 186.006488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:40.232018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.497636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-20T20:57:40.232155Z","caller":"traceutil/trace.go:171","msg":"trace[897978651] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1294; }","duration":"177.671509ms","start":"2026-04-20T20:57:40.054455Z","end":"2026-04-20T20:57:40.232127Z","steps":["trace[897978651] 'agreement among raft nodes before linearized reading' (duration: 177.374522ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:57:40.390045Z","caller":"traceutil/trace.go:171","msg":"trace[334350769] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"154.251686ms","start":"2026-04-20T20:57:40.235765Z","end":"2026-04-20T20:57:40.390017Z","steps":["trace[334350769] 'process raft request' (duration: 128.739034ms)","trace[334350769] 'compare' (duration: 25.40606ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:40.453548Z","caller":"traceutil/trace.go:171","msg":"trace[1383910929] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"132.033051ms","start":"2026-04-20T20:57:40.32149Z","end":"2026-04-20T20:57:40.453523Z","steps":["trace[1383910929] 'read index received' (duration: 43.000706ms)","trace[1383910929] 'applied index is now lower than readState.Index' (duration: 89.031245ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:57:40.45362Z","caller":"traceutil/trace.go:171","msg":"trace[2100127278] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"217.284158ms","start":"2026-04-20T20:57:40.236301Z","end":"2026-04-20T20:57:40.453585Z","steps":["trace[2100127278] 'process raft request' (duration: 217.092673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:57:40.453753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.246156ms","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-04-20T20:57:40.453796Z","caller":"traceutil/trace.go:171","msg":"trace[475154423] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1296; }","duration":"132.345548ms","start":"2026-04-20T20:57:40.321438Z","end":"2026-04-20T20:57:40.453783Z","steps":["trace[475154423] 'agreement among raft nodes before linearized reading' (duration: 132.195375ms)"],"step_count":1}