{"level":"warn","ts":"2026-04-20T05:48:55.615865Z","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-20T05:48:55.615947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-20T05:48:55.616022Z","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-20T05:48:55.616033Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-20T05:48:55.616051Z","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-20T05:48:55.61673Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-04-20T05:48:55.616803Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-20T05:48:55.621844Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.833684ms"} {"level":"info","ts":"2026-04-20T05:48:55.631691Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-04-20T05:48:55.631779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T05:48:55.631803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-04-20T05:48:55.631817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T05:48:55.631826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-04-20T05:48:55.631864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-04-20T05:48:55.640426Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T05:48:55.642962Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T05:48:55.644398Z","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-20T05:48:55.646799Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T05:48:55.647174Z","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-20T05:48:55.647264Z","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-20T05:48:55.647292Z","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-20T05:48:55.647198Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T05:48:55.647885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-04-20T05:48:55.648024Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-04-20T05:48:55.649684Z","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-20T05:48:55.649796Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-20T05:48:55.649886Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-04-20T05:48:55.649941Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T05:48:55.649984Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T05:48:56.033157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T05:48:56.033219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T05:48:56.033314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-04-20T05:48:56.0334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-04-20T05:48:56.03342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-20T05:48:56.033491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-04-20T05:48:56.033625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-04-20T05:48:56.035318Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:48:56.035701Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T05:48:56.035737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:48:56.035708Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T05:48:56.035956Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T05:48:56.036018Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T05:48:56.038146Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-04-20T05:48:56.038309Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:48:56.038444Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:48:56.038534Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T05:48:56.039244Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T05:49:24.907335Z","caller":"traceutil/trace.go:171","msg":"trace[1037860780] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"132.224816ms","start":"2026-04-20T05:49:24.775085Z","end":"2026-04-20T05:49:24.90731Z","steps":["trace[1037860780] 'process raft request' (duration: 132.113674ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.967689Z","caller":"traceutil/trace.go:171","msg":"trace[1407361623] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"160.351693ms","start":"2026-04-20T05:49:24.807315Z","end":"2026-04-20T05:49:24.967667Z","steps":["trace[1407361623] 'process raft request' (duration: 160.007106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.967827Z","caller":"traceutil/trace.go:171","msg":"trace[94531999] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"190.45136ms","start":"2026-04-20T05:49:24.777371Z","end":"2026-04-20T05:49:24.967822Z","steps":["trace[94531999] 'process raft request' (duration: 186.914713ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.96787Z","caller":"traceutil/trace.go:171","msg":"trace[1740034833] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"176.696793ms","start":"2026-04-20T05:49:24.791169Z","end":"2026-04-20T05:49:24.967866Z","steps":["trace[1740034833] 'process raft request' (duration: 176.0965ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.96791Z","caller":"traceutil/trace.go:171","msg":"trace[646389839] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"168.404795ms","start":"2026-04-20T05:49:24.799502Z","end":"2026-04-20T05:49:24.967906Z","steps":["trace[646389839] 'process raft request' (duration: 167.791232ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.967967Z","caller":"traceutil/trace.go:171","msg":"trace[1934490179] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"156.448018ms","start":"2026-04-20T05:49:24.811514Z","end":"2026-04-20T05:49:24.967962Z","steps":["trace[1934490179] 'process raft request' (duration: 155.860116ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.968008Z","caller":"traceutil/trace.go:171","msg":"trace[886733956] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"156.52921ms","start":"2026-04-20T05:49:24.811475Z","end":"2026-04-20T05:49:24.968004Z","steps":["trace[886733956] 'process raft request' (duration: 155.875296ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.968054Z","caller":"traceutil/trace.go:171","msg":"trace[596524306] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"140.250099ms","start":"2026-04-20T05:49:24.827799Z","end":"2026-04-20T05:49:24.96805Z","steps":["trace[596524306] 'process raft request' (duration: 139.659177ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.968115Z","caller":"traceutil/trace.go:171","msg":"trace[1728754219] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:530; }","duration":"180.713221ms","start":"2026-04-20T05:49:24.787394Z","end":"2026-04-20T05:49:24.968107Z","steps":["trace[1728754219] 'read index received' (duration: 120.235879ms)","trace[1728754219] 'applied index is now lower than readState.Index' (duration: 60.476862ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:49:24.968163Z","caller":"traceutil/trace.go:171","msg":"trace[1173583827] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"114.928044ms","start":"2026-04-20T05:49:24.853229Z","end":"2026-04-20T05:49:24.968157Z","steps":["trace[1173583827] 'process raft request' (duration: 114.349562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:49:24.968256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.846854ms","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-04-20T05:49:24.968296Z","caller":"traceutil/trace.go:171","msg":"trace[688154035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"180.937745ms","start":"2026-04-20T05:49:24.787351Z","end":"2026-04-20T05:49:24.968289Z","steps":["trace[688154035] 'agreement among raft nodes before linearized reading' (duration: 180.781302ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.968188Z","caller":"traceutil/trace.go:171","msg":"trace[575599146] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"156.411307ms","start":"2026-04-20T05:49:24.811585Z","end":"2026-04-20T05:49:24.967996Z","steps":["trace[575599146] 'process raft request' (duration: 155.807874ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:24.968438Z","caller":"traceutil/trace.go:171","msg":"trace[2034914640] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"131.161204ms","start":"2026-04-20T05:49:24.837083Z","end":"2026-04-20T05:49:24.968244Z","steps":["trace[2034914640] 'process raft request' (duration: 130.409508ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:30.490549Z","caller":"traceutil/trace.go:171","msg":"trace[539601685] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"160.461364ms","start":"2026-04-20T05:49:30.330057Z","end":"2026-04-20T05:49:30.490518Z","steps":["trace[539601685] 'process raft request' (duration: 159.755539ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:31.801617Z","caller":"traceutil/trace.go:171","msg":"trace[1422936766] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"318.623649ms","start":"2026-04-20T05:49:31.482967Z","end":"2026-04-20T05:49:31.80159Z","steps":["trace[1422936766] 'process raft request' (duration: 317.995126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:49:31.802213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T05:49:31.48292Z","time spent":"318.774873ms","remote":"127.0.0.1:47670","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-20T05:49:31.819609Z","caller":"traceutil/trace.go:171","msg":"trace[805483032] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"103.407056ms","start":"2026-04-20T05:49:31.716177Z","end":"2026-04-20T05:49:31.819584Z","steps":["trace[805483032] 'read index received' (duration: 84.582641ms)","trace[805483032] 'applied index is now lower than readState.Index' (duration: 18.823175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:49:31.819814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.62089ms","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-20T05:49:31.819845Z","caller":"traceutil/trace.go:171","msg":"trace[1932443651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"103.705743ms","start":"2026-04-20T05:49:31.71613Z","end":"2026-04-20T05:49:31.819836Z","steps":["trace[1932443651] 'agreement among raft nodes before linearized reading' (duration: 103.547469ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:49:31.819996Z","caller":"traceutil/trace.go:171","msg":"trace[497687256] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"207.737512ms","start":"2026-04-20T05:49:31.612249Z","end":"2026-04-20T05:49:31.819987Z","steps":["trace[497687256] 'process raft request' (duration: 207.212101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:49:46.173996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.813952ms","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-20T05:49:46.174094Z","caller":"traceutil/trace.go:171","msg":"trace[1743559372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"206.966456ms","start":"2026-04-20T05:49:45.967108Z","end":"2026-04-20T05:49:46.174074Z","steps":["trace[1743559372] 'range keys from in-memory index tree' (duration: 206.629778ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:43.630121Z","caller":"traceutil/trace.go:171","msg":"trace[617325395] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"125.692218ms","start":"2026-04-20T05:50:43.504392Z","end":"2026-04-20T05:50:43.630084Z","steps":["trace[617325395] 'read index received' (duration: 85.405023ms)","trace[617325395] 'applied index is now lower than readState.Index' (duration: 40.285875ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:43.630291Z","caller":"traceutil/trace.go:171","msg":"trace[731233891] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"167.440923ms","start":"2026-04-20T05:50:43.462835Z","end":"2026-04-20T05:50:43.630276Z","steps":["trace[731233891] 'process raft request' (duration: 126.934403ms)","trace[731233891] 'compare' (duration: 40.178573ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:50:43.630528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.121517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:50:43.630603Z","caller":"traceutil/trace.go:171","msg":"trace[146926182] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1071; }","duration":"126.251579ms","start":"2026-04-20T05:50:43.504333Z","end":"2026-04-20T05:50:43.630585Z","steps":["trace[146926182] 'agreement among raft nodes before linearized reading' (duration: 126.148447ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:43.913106Z","caller":"traceutil/trace.go:171","msg":"trace[1314369437] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"236.850983ms","start":"2026-04-20T05:50:43.676231Z","end":"2026-04-20T05:50:43.913082Z","steps":["trace[1314369437] 'read index received' (duration: 214.914482ms)","trace[1314369437] 'applied index is now lower than readState.Index' (duration: 21.935331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T05:50:43.913227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.968665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:50:43.913252Z","caller":"traceutil/trace.go:171","msg":"trace[1135225642] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1073; }","duration":"237.030697ms","start":"2026-04-20T05:50:43.676213Z","end":"2026-04-20T05:50:43.913243Z","steps":["trace[1135225642] 'agreement among raft nodes before linearized reading' (duration: 236.957555ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:43.913356Z","caller":"traceutil/trace.go:171","msg":"trace[2095631292] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"278.17241ms","start":"2026-04-20T05:50:43.635146Z","end":"2026-04-20T05:50:43.913319Z","steps":["trace[2095631292] 'process raft request' (duration: 255.853501ms)","trace[2095631292] 'compare' (duration: 21.873019ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:50:44.707441Z","caller":"traceutil/trace.go:171","msg":"trace[1436912808] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"154.013145ms","start":"2026-04-20T05:50:44.553403Z","end":"2026-04-20T05:50:44.707416Z","steps":["trace[1436912808] 'process raft request' (duration: 153.834931ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:50:56.977346Z","caller":"traceutil/trace.go:171","msg":"trace[1528380788] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"216.190169ms","start":"2026-04-20T05:50:56.761133Z","end":"2026-04-20T05:50:56.977323Z","steps":["trace[1528380788] 'process raft request' (duration: 216.053516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:16.381811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.815595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T05:51:16.381872Z","caller":"traceutil/trace.go:171","msg":"trace[943355625] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1265; }","duration":"218.914917ms","start":"2026-04-20T05:51:16.162935Z","end":"2026-04-20T05:51:16.38185Z","steps":["trace[943355625] 'count revisions from in-memory index tree' (duration: 218.691433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:16.382644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.491339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T05:51:16.382766Z","caller":"traceutil/trace.go:171","msg":"trace[1322414157] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"180.804029ms","start":"2026-04-20T05:51:16.201947Z","end":"2026-04-20T05:51:16.382751Z","steps":["trace[1322414157] 'process raft request' (duration: 35.804789ms)","trace[1322414157] 'compare' (duration: 144.276275ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:16.673511Z","caller":"traceutil/trace.go:171","msg":"trace[585437055] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"204.56447ms","start":"2026-04-20T05:51:16.468921Z","end":"2026-04-20T05:51:16.673486Z","steps":["trace[585437055] 'process raft request' (duration: 175.532118ms)","trace[585437055] 'compare' (duration: 28.91999ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:16.715716Z","caller":"traceutil/trace.go:171","msg":"trace[989959188] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"245.56351ms","start":"2026-04-20T05:51:16.470138Z","end":"2026-04-20T05:51:16.715702Z","steps":["trace[989959188] 'read index received' (duration: 174.326932ms)","trace[989959188] 'applied index is now lower than readState.Index' (duration: 71.235508ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:16.716079Z","caller":"traceutil/trace.go:171","msg":"trace[1190816785] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"246.307186ms","start":"2026-04-20T05:51:16.469757Z","end":"2026-04-20T05:51:16.716065Z","steps":["trace[1190816785] 'process raft request' (duration: 245.819505ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:16.716349Z","caller":"traceutil/trace.go:171","msg":"trace[897514907] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"190.800144ms","start":"2026-04-20T05:51:16.525538Z","end":"2026-04-20T05:51:16.716338Z","steps":["trace[897514907] 'process raft request' (duration: 190.12232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:16.71658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.421868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T05:51:16.716624Z","caller":"traceutil/trace.go:171","msg":"trace[1131241248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"246.485539ms","start":"2026-04-20T05:51:16.470126Z","end":"2026-04-20T05:51:16.716611Z","steps":["trace[1131241248] 'agreement among raft nodes before linearized reading' (duration: 246.409437ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T05:51:27.979603Z","caller":"traceutil/trace.go:171","msg":"trace[423839825] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"109.26403ms","start":"2026-04-20T05:51:27.870306Z","end":"2026-04-20T05:51:27.97957Z","steps":["trace[423839825] 'read index received' (duration: 108.956513ms)","trace[423839825] 'applied index is now lower than readState.Index' (duration: 306.267µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T05:51:27.980148Z","caller":"traceutil/trace.go:171","msg":"trace[1362836104] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"293.099205ms","start":"2026-04-20T05:51:27.687013Z","end":"2026-04-20T05:51:27.980112Z","steps":["trace[1362836104] 'process raft request' (duration: 292.38681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T05:51:27.980432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.102897ms","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-04-20T05:51:27.980528Z","caller":"traceutil/trace.go:171","msg":"trace[1501093786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1338; }","duration":"110.238809ms","start":"2026-04-20T05:51:27.870272Z","end":"2026-04-20T05:51:27.98051Z","steps":["trace[1501093786] 'agreement among raft nodes before linearized reading' (duration: 110.084626ms)"],"step_count":1}