{"level":"warn","ts":"2026-07-07T03:29:24.951596Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T03:29:24.951683Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-07T03:29:24.951746Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T03:29:24.951759Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-07T03:29:24.951774Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-07T03:29:24.952404Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-07-07T03:29:24.952474Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-07T03:29:24.956226Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.501644ms"} {"level":"info","ts":"2026-07-07T03:29:24.961456Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-07-07T03:29:24.961583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T03:29:24.961605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-07-07T03:29:24.96166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T03:29:24.961711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-07-07T03:29:24.961841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-07-07T03:29:24.96724Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T03:29:24.968307Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T03:29:24.969526Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-07T03:29:24.971131Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T03:29:24.975163Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T03:29:24.975803Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-07T03:29:24.975919Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-07T03:29:24.977549Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-07T03:29:24.978025Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-07T03:29:24.978156Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-07T03:29:24.978217Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-07T03:29:24.978441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-07-07T03:29:24.978534Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T03:29:24.978576Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-07T03:29:24.978591Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T03:29:25.863091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T03:29:25.863137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T03:29:25.863165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-07-07T03:29:25.863178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-07-07T03:29:25.863185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-07T03:29:25.863195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-07-07T03:29:25.863204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-07T03:29:25.864109Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T03:29:25.864826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T03:29:25.864837Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T03:29:25.864818Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T03:29:25.865423Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T03:29:25.865387Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T03:29:25.865476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T03:29:25.865481Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T03:29:25.865668Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T03:29:25.867515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T03:29:25.868807Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"warn","ts":"2026-07-07T03:29:32.431613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.089754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-07T03:29:32.431651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.621055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-07T03:29:32.431689Z","caller":"traceutil/trace.go:171","msg":"trace[736193064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:280; }","duration":"212.224047ms","start":"2026-07-07T03:29:32.219452Z","end":"2026-07-07T03:29:32.431676Z","steps":["trace[736193064] 'range keys from in-memory index tree' (duration: 212.007852ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:29:32.431738Z","caller":"traceutil/trace.go:171","msg":"trace[728401154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:280; }","duration":"221.770468ms","start":"2026-07-07T03:29:32.209946Z","end":"2026-07-07T03:29:32.431717Z","steps":["trace[728401154] 'range keys from in-memory index tree' (duration: 221.47869ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:29:32.577519Z","caller":"traceutil/trace.go:171","msg":"trace[1989091035] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"132.635613ms","start":"2026-07-07T03:29:32.444858Z","end":"2026-07-07T03:29:32.577493Z","steps":["trace[1989091035] 'read index received' (duration: 132.456449ms)","trace[1989091035] 'applied index is now lower than readState.Index' (duration: 178.294µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:29:32.577816Z","caller":"traceutil/trace.go:171","msg":"trace[1181094303] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"142.118112ms","start":"2026-07-07T03:29:32.435686Z","end":"2026-07-07T03:29:32.577804Z","steps":["trace[1181094303] 'process raft request' (duration: 141.69674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:29:32.578016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.139897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T03:29:32.578057Z","caller":"traceutil/trace.go:171","msg":"trace[301606310] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:281; }","duration":"133.21604ms","start":"2026-07-07T03:29:32.444831Z","end":"2026-07-07T03:29:32.578047Z","steps":["trace[301606310] 'agreement among raft nodes before linearized reading' (duration: 133.145518ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:29:32.739974Z","caller":"traceutil/trace.go:171","msg":"trace[1033764897] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"158.677665ms","start":"2026-07-07T03:29:32.581265Z","end":"2026-07-07T03:29:32.739942Z","steps":["trace[1033764897] 'process raft request' (duration: 127.221131ms)","trace[1033764897] 'compare' (duration: 31.188837ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:29:32.739983Z","caller":"traceutil/trace.go:171","msg":"trace[570674056] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"156.570029ms","start":"2026-07-07T03:29:32.583385Z","end":"2026-07-07T03:29:32.739955Z","steps":["trace[570674056] 'process raft request' (duration: 156.489897ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:30:03.320271Z","caller":"traceutil/trace.go:171","msg":"trace[705263885] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"110.432541ms","start":"2026-07-07T03:30:03.209808Z","end":"2026-07-07T03:30:03.320241Z","steps":["trace[705263885] 'process raft request' (duration: 88.243353ms)","trace[705263885] 'compare' (duration: 21.935511ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:30:03.320575Z","caller":"traceutil/trace.go:171","msg":"trace[2097274226] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"106.687688ms","start":"2026-07-07T03:30:03.213851Z","end":"2026-07-07T03:30:03.320538Z","steps":["trace[2097274226] 'process raft request' (duration: 106.327969ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:30:08.708233Z","caller":"traceutil/trace.go:171","msg":"trace[816295252] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"145.006468ms","start":"2026-07-07T03:30:08.563188Z","end":"2026-07-07T03:30:08.708194Z","steps":["trace[816295252] 'process raft request' (duration: 144.410124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:30:08.972797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.673466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T03:30:08.972884Z","caller":"traceutil/trace.go:171","msg":"trace[507398788] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"190.22152ms","start":"2026-07-07T03:30:08.78265Z","end":"2026-07-07T03:30:08.972872Z","steps":["trace[507398788] 'read index received' (duration: 10.708874ms)","trace[507398788] 'applied index is now lower than readState.Index' (duration: 179.511786ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:30:08.972938Z","caller":"traceutil/trace.go:171","msg":"trace[372940591] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"201.205299ms","start":"2026-07-07T03:30:08.771695Z","end":"2026-07-07T03:30:08.9729Z","steps":["trace[372940591] 'process raft request' (duration: 21.781475ms)","trace[372940591] 'compare' (duration: 178.533702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T03:30:08.973224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.565889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-07-07T03:30:08.973273Z","caller":"traceutil/trace.go:171","msg":"trace[1230312299] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:587; }","duration":"190.61921ms","start":"2026-07-07T03:30:08.782645Z","end":"2026-07-07T03:30:08.973265Z","steps":["trace[1230312299] 'agreement among raft nodes before linearized reading' (duration: 190.549948ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:30:09.769666Z","caller":"traceutil/trace.go:171","msg":"trace[121142818] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"322.125977ms","start":"2026-07-07T03:30:09.447508Z","end":"2026-07-07T03:30:09.769633Z","steps":["trace[121142818] 'read index received' (duration: 321.974123ms)","trace[121142818] 'applied index is now lower than readState.Index' (duration: 150.994µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:30:09.769709Z","caller":"traceutil/trace.go:171","msg":"trace[1885108927] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"341.079903ms","start":"2026-07-07T03:30:09.428592Z","end":"2026-07-07T03:30:09.769672Z","steps":["trace[1885108927] 'process raft request' (duration: 340.913869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:30:09.769907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.375633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T03:30:09.769956Z","caller":"traceutil/trace.go:171","msg":"trace[1680913885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"322.479356ms","start":"2026-07-07T03:30:09.447465Z","end":"2026-07-07T03:30:09.769945Z","steps":["trace[1680913885] 'agreement among raft nodes before linearized reading' (duration: 322.291781ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:30:09.769993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T03:30:09.447446Z","time spent":"322.539028ms","remote":"127.0.0.1:35496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-07T03:30:09.77002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T03:30:09.428571Z","time spent":"341.206787ms","remote":"127.0.0.1:35674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T03:30:09.837978Z","caller":"traceutil/trace.go:171","msg":"trace[1881404371] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"388.241271ms","start":"2026-07-07T03:30:09.449688Z","end":"2026-07-07T03:30:09.83793Z","steps":["trace[1881404371] 'process raft request' (duration: 387.697788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:30:09.838042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.367833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T03:30:09.8382Z","caller":"traceutil/trace.go:171","msg":"trace[943678116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"204.480316ms","start":"2026-07-07T03:30:09.633613Z","end":"2026-07-07T03:30:09.838094Z","steps":["trace[943678116] 'agreement among raft nodes before linearized reading' (duration: 204.294532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:30:09.838191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T03:30:09.44967Z","time spent":"388.421366ms","remote":"127.0.0.1:35594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T03:30:09.83872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.269683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-07T03:30:09.838875Z","caller":"traceutil/trace.go:171","msg":"trace[127425563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"211.444197ms","start":"2026-07-07T03:30:09.627386Z","end":"2026-07-07T03:30:09.83883Z","steps":["trace[127425563] 'agreement among raft nodes before linearized reading' (duration: 211.121168ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:30:25.014286Z","caller":"traceutil/trace.go:171","msg":"trace[1147135294] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"117.424414ms","start":"2026-07-07T03:30:24.896839Z","end":"2026-07-07T03:30:25.014264Z","steps":["trace[1147135294] 'process raft request' (duration: 117.208069ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:30:53.848613Z","caller":"traceutil/trace.go:171","msg":"trace[1636702657] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"125.11956ms","start":"2026-07-07T03:30:53.723462Z","end":"2026-07-07T03:30:53.848581Z","steps":["trace[1636702657] 'process raft request' (duration: 125.005599ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:31:37.7911Z","caller":"traceutil/trace.go:171","msg":"trace[235746238] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"188.506059ms","start":"2026-07-07T03:31:37.602563Z","end":"2026-07-07T03:31:37.791069Z","steps":["trace[235746238] 'process raft request' (duration: 188.315895ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:31:37.932799Z","caller":"traceutil/trace.go:171","msg":"trace[1989802506] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"266.041963ms","start":"2026-07-07T03:31:37.666721Z","end":"2026-07-07T03:31:37.932763Z","steps":["trace[1989802506] 'process raft request' (duration: 231.10575ms)","trace[1989802506] 'compare' (duration: 34.75398ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:32:11.778866Z","caller":"traceutil/trace.go:171","msg":"trace[704563045] transaction","detail":"{read_only:false; response_revision:2048; number_of_response:1; }","duration":"102.70976ms","start":"2026-07-07T03:32:11.676122Z","end":"2026-07-07T03:32:11.778831Z","steps":["trace[704563045] 'process raft request' (duration: 102.548147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:32:12.454378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.806023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T03:32:12.454812Z","caller":"traceutil/trace.go:171","msg":"trace[542407858] transaction","detail":"{read_only:false; response_revision:2052; number_of_response:1; }","duration":"280.411056ms","start":"2026-07-07T03:32:12.174371Z","end":"2026-07-07T03:32:12.454783Z","steps":["trace[542407858] 'process raft request' (duration: 157.103293ms)","trace[542407858] 'compare' (duration: 122.67735ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:32:12.629872Z","caller":"traceutil/trace.go:171","msg":"trace[864348697] transaction","detail":"{read_only:false; response_revision:2054; number_of_response:1; }","duration":"130.774982ms","start":"2026-07-07T03:32:12.499071Z","end":"2026-07-07T03:32:12.629846Z","steps":["trace[864348697] 'process raft request' (duration: 130.635809ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.206662Z","caller":"traceutil/trace.go:171","msg":"trace[1754660618] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"276.151073ms","start":"2026-07-07T03:37:38.930484Z","end":"2026-07-07T03:37:39.206635Z","steps":["trace[1754660618] 'process raft request' (duration: 268.904151ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.206777Z","caller":"traceutil/trace.go:171","msg":"trace[1069214751] transaction","detail":"{read_only:false; response_revision:4623; number_of_response:1; }","duration":"275.08238ms","start":"2026-07-07T03:37:38.931643Z","end":"2026-07-07T03:37:39.206725Z","steps":["trace[1069214751] 'process raft request' (duration: 274.951467ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.207346Z","caller":"traceutil/trace.go:171","msg":"trace[1848513190] linearizableReadLoop","detail":"{readStateIndex:4792; appliedIndex:4789; }","duration":"274.231171ms","start":"2026-07-07T03:37:38.933099Z","end":"2026-07-07T03:37:39.207331Z","steps":["trace[1848513190] 'read index received' (duration: 549.622µs)","trace[1848513190] 'applied index is now lower than readState.Index' (duration: 273.679259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T03:37:39.207604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.499896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:125211"} {"level":"info","ts":"2026-07-07T03:37:39.207661Z","caller":"traceutil/trace.go:171","msg":"trace[414151328] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4623; }","duration":"274.623538ms","start":"2026-07-07T03:37:38.933022Z","end":"2026-07-07T03:37:39.207646Z","steps":["trace[414151328] 'agreement among raft nodes before linearized reading' (duration: 274.381723ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.800467Z","caller":"traceutil/trace.go:171","msg":"trace[291827331] transaction","detail":"{read_only:false; response_revision:4650; number_of_response:1; }","duration":"205.975167ms","start":"2026-07-07T03:37:39.594467Z","end":"2026-07-07T03:37:39.800442Z","steps":["trace[291827331] 'process raft request' (duration: 205.527336ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.800561Z","caller":"traceutil/trace.go:171","msg":"trace[495813595] transaction","detail":"{read_only:false; response_revision:4651; number_of_response:1; }","duration":"202.94111ms","start":"2026-07-07T03:37:39.597575Z","end":"2026-07-07T03:37:39.800516Z","steps":["trace[495813595] 'process raft request' (duration: 202.769376ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.80129Z","caller":"traceutil/trace.go:171","msg":"trace[1171512882] transaction","detail":"{read_only:false; response_revision:4653; number_of_response:1; }","duration":"195.093404ms","start":"2026-07-07T03:37:39.606171Z","end":"2026-07-07T03:37:39.801265Z","steps":["trace[1171512882] 'process raft request' (duration: 194.286457ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.80071Z","caller":"traceutil/trace.go:171","msg":"trace[2146098826] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"202.612902ms","start":"2026-07-07T03:37:39.597972Z","end":"2026-07-07T03:37:39.800585Z","steps":["trace[2146098826] 'process raft request' (duration: 202.433388ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.802824Z","caller":"traceutil/trace.go:171","msg":"trace[791773235] linearizableReadLoop","detail":"{readStateIndex:4821; appliedIndex:4817; }","duration":"200.049574ms","start":"2026-07-07T03:37:39.602756Z","end":"2026-07-07T03:37:39.802805Z","steps":["trace[791773235] 'read index received' (duration: 30.606113ms)","trace[791773235] 'applied index is now lower than readState.Index' (duration: 169.442501ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T03:37:39.80322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.441084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-07T03:37:39.803298Z","caller":"traceutil/trace.go:171","msg":"trace[1531731655] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4654; }","duration":"200.550475ms","start":"2026-07-07T03:37:39.602727Z","end":"2026-07-07T03:37:39.803278Z","steps":["trace[1531731655] 'agreement among raft nodes before linearized reading' (duration: 200.333341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:37:39.803511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.432999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-07-07T03:37:39.804001Z","caller":"traceutil/trace.go:171","msg":"trace[171919151] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4654; }","duration":"181.981281ms","start":"2026-07-07T03:37:39.621977Z","end":"2026-07-07T03:37:39.803958Z","steps":["trace[171919151] 'agreement among raft nodes before linearized reading' (duration: 181.220065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:37:39.807314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.726607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-07T03:37:39.807394Z","caller":"traceutil/trace.go:171","msg":"trace[268783124] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4654; }","duration":"108.8568ms","start":"2026-07-07T03:37:39.698521Z","end":"2026-07-07T03:37:39.807378Z","steps":["trace[268783124] 'agreement among raft nodes before linearized reading' (duration: 105.703639ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:39.803726Z","caller":"traceutil/trace.go:171","msg":"trace[49950296] transaction","detail":"{read_only:false; response_revision:4654; number_of_response:1; }","duration":"194.705606ms","start":"2026-07-07T03:37:39.608991Z","end":"2026-07-07T03:37:39.803697Z","steps":["trace[49950296] 'process raft request' (duration: 193.609711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:37:39.807661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.239247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-07T03:37:39.811655Z","caller":"traceutil/trace.go:171","msg":"trace[667714956] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4654; }","duration":"182.249667ms","start":"2026-07-07T03:37:39.62939Z","end":"2026-07-07T03:37:39.811639Z","steps":["trace[667714956] 'agreement among raft nodes before linearized reading' (duration: 178.190386ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:51.507329Z","caller":"traceutil/trace.go:171","msg":"trace[352924416] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"177.933973ms","start":"2026-07-07T03:37:51.32936Z","end":"2026-07-07T03:37:51.507294Z","steps":["trace[352924416] 'process raft request' (duration: 169.510035ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:37:51.519605Z","caller":"traceutil/trace.go:171","msg":"trace[749150469] linearizableReadLoop","detail":"{readStateIndex:5029; appliedIndex:5027; }","duration":"131.059826ms","start":"2026-07-07T03:37:51.388529Z","end":"2026-07-07T03:37:51.519589Z","steps":["trace[749150469] 'read index received' (duration: 110.490308ms)","trace[749150469] 'applied index is now lower than readState.Index' (duration: 20.568668ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T03:37:51.519834Z","caller":"traceutil/trace.go:171","msg":"trace[696579431] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"158.627851ms","start":"2026-07-07T03:37:51.361194Z","end":"2026-07-07T03:37:51.519822Z","steps":["trace[696579431] 'process raft request' (duration: 158.297574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:37:51.519993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.404383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-07T03:37:51.520177Z","caller":"traceutil/trace.go:171","msg":"trace[2092127737] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:4855; }","duration":"131.647819ms","start":"2026-07-07T03:37:51.388472Z","end":"2026-07-07T03:37:51.52012Z","steps":["trace[2092127737] 'agreement among raft nodes before linearized reading' (duration: 131.333142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T03:37:51.520231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.949636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-07T03:37:51.520288Z","caller":"traceutil/trace.go:171","msg":"trace[643776906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4855; }","duration":"102.050397ms","start":"2026-07-07T03:37:51.418223Z","end":"2026-07-07T03:37:51.520274Z","steps":["trace[643776906] 'agreement among raft nodes before linearized reading' (duration: 101.866203ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:39:25.8995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2867} {"level":"info","ts":"2026-07-07T03:39:25.99325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2867,"took":"92.334456ms","hash":3636448148,"current-db-size-bytes":55144448,"current-db-size":"55 MB","current-db-size-in-use-bytes":30121984,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-07T03:39:25.993307Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3636448148,"revision":2867,"compact-revision":-1} {"level":"warn","ts":"2026-07-07T03:41:41.938453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.362923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T03:41:41.938649Z","caller":"traceutil/trace.go:171","msg":"trace[2095303677] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:6554; }","duration":"102.564177ms","start":"2026-07-07T03:41:41.836037Z","end":"2026-07-07T03:41:41.938601Z","steps":["trace[2095303677] 'count revisions from in-memory index tree' (duration: 102.302742ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T03:44:25.905329Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5606} {"level":"info","ts":"2026-07-07T03:44:25.979903Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5606,"took":"73.326813ms","hash":1606404814,"current-db-size-bytes":55144448,"current-db-size":"55 MB","current-db-size-in-use-bytes":18456576,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-07T03:44:25.979965Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1606404814,"revision":5606,"compact-revision":2867}