{"level":"warn","ts":"2026-03-27T01:56:11.127933Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-27T01:56:11.128026Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-03-27T01:56:11.128098Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-27T01:56:11.128111Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-27T01:56:11.128129Z","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-03-27T01:56:11.128844Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-03-27T01:56:11.128931Z","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":16,"max-cpu-available":16,"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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-03-27T01:56:11.132976Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.821473ms"} {"level":"info","ts":"2026-03-27T01:56:11.14092Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-03-27T01:56:11.141009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-03-27T01:56:11.141029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-03-27T01:56:11.141042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-27T01:56:11.14105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-03-27T01:56:11.141107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-03-27T01:56:11.150582Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-27T01:56:11.152477Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-27T01:56:11.154014Z","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-03-27T01:56:11.156124Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-27T01:56:11.156904Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-27T01:56:11.157452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-03-27T01:56:11.157618Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-03-27T01:56:11.157697Z","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-03-27T01:56:11.159595Z","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-03-27T01:56:11.159617Z","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-03-27T01:56:11.162621Z","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-03-27T01:56:11.162753Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-27T01:56:11.162783Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-03-27T01:56:11.163Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-27T01:56:11.16304Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-27T01:56:11.742111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-03-27T01:56:11.742213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-27T01:56:11.742258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-03-27T01:56:11.742283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-03-27T01:56:11.742298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-27T01:56:11.742316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-03-27T01:56:11.742331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-03-27T01:56:11.743324Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:56:11.744202Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-03-27T01:56:11.744218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-27T01:56:11.744267Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-27T01:56:11.744602Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-27T01:56:11.744649Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-27T01:56:11.744912Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:56:11.745053Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:56:11.745108Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-27T01:56:11.747988Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-03-27T01:56:11.748293Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-27T01:56:42.740367Z","caller":"traceutil/trace.go:171","msg":"trace[858883597] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"127.228472ms","start":"2026-03-27T01:56:42.613106Z","end":"2026-03-27T01:56:42.740334Z","steps":["trace[858883597] 'process raft request' (duration: 127.000215ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:42.903017Z","caller":"traceutil/trace.go:171","msg":"trace[1863540518] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"257.023318ms","start":"2026-03-27T01:56:42.645953Z","end":"2026-03-27T01:56:42.902977Z","steps":["trace[1863540518] 'process raft request' (duration: 250.081936ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:42.903077Z","caller":"traceutil/trace.go:171","msg":"trace[1439517824] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"186.44351ms","start":"2026-03-27T01:56:42.716592Z","end":"2026-03-27T01:56:42.903035Z","steps":["trace[1439517824] 'read index received' (duration: 23.474105ms)","trace[1439517824] 'applied index is now lower than readState.Index' (duration: 162.966095ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:42.903093Z","caller":"traceutil/trace.go:171","msg":"trace[1304488756] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"108.412122ms","start":"2026-03-27T01:56:42.794647Z","end":"2026-03-27T01:56:42.903059Z","steps":["trace[1304488756] 'process raft request' (duration: 108.304979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:42.903319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.691817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:42.903425Z","caller":"traceutil/trace.go:171","msg":"trace[1169942498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"186.865732ms","start":"2026-03-27T01:56:42.716528Z","end":"2026-03-27T01:56:42.903394Z","steps":["trace[1169942498] 'agreement among raft nodes before linearized reading' (duration: 186.600914ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:48.917747Z","caller":"traceutil/trace.go:171","msg":"trace[1619251156] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"272.980105ms","start":"2026-03-27T01:56:48.644733Z","end":"2026-03-27T01:56:48.917713Z","steps":["trace[1619251156] 'read index received' (duration: 272.08875ms)","trace[1619251156] 'applied index is now lower than readState.Index' (duration: 890.125µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:56:48.917863Z","caller":"traceutil/trace.go:171","msg":"trace[351762334] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"398.102026ms","start":"2026-03-27T01:56:48.519716Z","end":"2026-03-27T01:56:48.917818Z","steps":["trace[351762334] 'process raft request' (duration: 397.352305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:48.918058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.287764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x8tvz\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-27T01:56:48.918116Z","caller":"traceutil/trace.go:171","msg":"trace[1035378790] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x8tvz; range_end:; response_count:1; response_revision:579; }","duration":"273.418938ms","start":"2026-03-27T01:56:48.644675Z","end":"2026-03-27T01:56:48.918094Z","steps":["trace[1035378790] 'agreement among raft nodes before linearized reading' (duration: 273.207271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:56:48.918785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:48.519682Z","time spent":"398.276431ms","remote":"127.0.0.1:48372","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-03-27T01:56:49.697373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.66082ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T01:56:49.697567Z","caller":"traceutil/trace.go:171","msg":"trace[469230059] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:580; }","duration":"534.884787ms","start":"2026-03-27T01:56:49.162656Z","end":"2026-03-27T01:56:49.697541Z","steps":["trace[469230059] 'range keys from in-memory index tree' (duration: 534.63225ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:56:49.697686Z","caller":"traceutil/trace.go:171","msg":"trace[1914947638] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"715.580191ms","start":"2026-03-27T01:56:48.982065Z","end":"2026-03-27T01:56:49.697645Z","steps":["trace[1914947638] 'process raft request' (duration: 663.022514ms)","trace[1914947638] 'compare' (duration: 52.098525ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T01:56:49.697877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T01:56:48.982029Z","time spent":"715.764897ms","remote":"127.0.0.1:48760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-27T01:57:05.964352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.71754ms","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-03-27T01:57:05.964449Z","caller":"traceutil/trace.go:171","msg":"trace[1205563007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"105.862315ms","start":"2026-03-27T01:57:05.858566Z","end":"2026-03-27T01:57:05.964428Z","steps":["trace[1205563007] 'range keys from in-memory index tree' (duration: 105.546236ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:57:05.964338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.716151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-468zj\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-03-27T01:57:05.964553Z","caller":"traceutil/trace.go:171","msg":"trace[543049896] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-468zj; range_end:; response_count:1; response_revision:746; }","duration":"217.989959ms","start":"2026-03-27T01:57:05.746544Z","end":"2026-03-27T01:57:05.964534Z","steps":["trace[543049896] 'range keys from in-memory index tree' (duration: 217.551176ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:57:33.08657Z","caller":"traceutil/trace.go:171","msg":"trace[1143954148] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"122.200606ms","start":"2026-03-27T01:57:32.964334Z","end":"2026-03-27T01:57:33.086535Z","steps":["trace[1143954148] 'process raft request' (duration: 122.042762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:19.7115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.796301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97ff725a-1026-4898-bacb-7eb8501fe580\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-03-27T01:58:19.711573Z","caller":"traceutil/trace.go:171","msg":"trace[1503072133] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97ff725a-1026-4898-bacb-7eb8501fe580; range_end:; response_count:1; response_revision:1801; }","duration":"116.933624ms","start":"2026-03-27T01:58:19.594623Z","end":"2026-03-27T01:58:19.711557Z","steps":["trace[1503072133] 'range keys from in-memory index tree' (duration: 116.579015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:58:19.711641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.711521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T01:58:19.711777Z","caller":"traceutil/trace.go:171","msg":"trace[1165153125] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1801; }","duration":"153.896696ms","start":"2026-03-27T01:58:19.557854Z","end":"2026-03-27T01:58:19.71175Z","steps":["trace[1165153125] 'count revisions from in-memory index tree' (duration: 153.615439ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:58:20.188613Z","caller":"traceutil/trace.go:171","msg":"trace[1362936424] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"147.509238ms","start":"2026-03-27T01:58:20.04107Z","end":"2026-03-27T01:58:20.188579Z","steps":["trace[1362936424] 'process raft request' (duration: 147.242271ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:09.993814Z","caller":"traceutil/trace.go:171","msg":"trace[1503190238] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"122.920413ms","start":"2026-03-27T01:59:09.870872Z","end":"2026-03-27T01:59:09.993793Z","steps":["trace[1503190238] 'process raft request' (duration: 122.805ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:16.363236Z","caller":"traceutil/trace.go:171","msg":"trace[2063098529] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"296.096863ms","start":"2026-03-27T01:59:16.067054Z","end":"2026-03-27T01:59:16.363151Z","steps":["trace[2063098529] 'process raft request' (duration: 295.937029ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:16.365264Z","caller":"traceutil/trace.go:171","msg":"trace[1303914355] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2235; }","duration":"140.828317ms","start":"2026-03-27T01:59:16.224419Z","end":"2026-03-27T01:59:16.365247Z","steps":["trace[1303914355] 'read index received' (duration: 138.652527ms)","trace[1303914355] 'applied index is now lower than readState.Index' (duration: 2.175ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T01:59:16.365434Z","caller":"traceutil/trace.go:171","msg":"trace[147468435] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"296.034562ms","start":"2026-03-27T01:59:16.069385Z","end":"2026-03-27T01:59:16.36542Z","steps":["trace[147468435] 'process raft request' (duration: 295.785995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T01:59:16.36575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.31703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-03-27T01:59:16.365795Z","caller":"traceutil/trace.go:171","msg":"trace[570433651] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2155; }","duration":"141.412703ms","start":"2026-03-27T01:59:16.224371Z","end":"2026-03-27T01:59:16.365784Z","steps":["trace[570433651] 'agreement among raft nodes before linearized reading' (duration: 140.955861ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T01:59:50.634383Z","caller":"traceutil/trace.go:171","msg":"trace[686388184] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"107.352573ms","start":"2026-03-27T01:59:50.526987Z","end":"2026-03-27T01:59:50.634339Z","steps":["trace[686388184] 'process raft request' (duration: 107.195229ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:02:23.80634Z","caller":"traceutil/trace.go:171","msg":"trace[1487433471] transaction","detail":"{read_only:false; response_revision:3475; number_of_response:1; }","duration":"123.811429ms","start":"2026-03-27T02:02:23.682491Z","end":"2026-03-27T02:02:23.806302Z","steps":["trace[1487433471] 'process raft request' (duration: 111.018352ms)","trace[1487433471] 'compare' (duration: 12.53244ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:04:22.139183Z","caller":"traceutil/trace.go:171","msg":"trace[1173530974] transaction","detail":"{read_only:false; response_revision:4888; number_of_response:1; }","duration":"173.713305ms","start":"2026-03-27T02:04:21.965444Z","end":"2026-03-27T02:04:22.139158Z","steps":["trace[1173530974] 'process raft request' (duration: 173.566171ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:04:22.299968Z","caller":"traceutil/trace.go:171","msg":"trace[1375254769] transaction","detail":"{read_only:false; response_revision:4889; number_of_response:1; }","duration":"334.36763ms","start":"2026-03-27T02:04:21.965547Z","end":"2026-03-27T02:04:22.299914Z","steps":["trace[1375254769] 'process raft request' (duration: 291.99243ms)","trace[1375254769] 'compare' (duration: 41.973209ms)"],"step_count":2} {"level":"warn","ts":"2026-03-27T02:04:22.300227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T02:04:21.965528Z","time spent":"334.567717ms","remote":"127.0.0.1:48460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-27T02:04:22.300009Z","caller":"traceutil/trace.go:171","msg":"trace[440864640] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"134.547385ms","start":"2026-03-27T02:04:22.165442Z","end":"2026-03-27T02:04:22.299989Z","steps":["trace[440864640] 'process raft request' (duration: 134.38045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:04:41.685577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.59313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-27T02:04:41.685674Z","caller":"traceutil/trace.go:171","msg":"trace[520605014] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5288; }","duration":"186.777046ms","start":"2026-03-27T02:04:41.498876Z","end":"2026-03-27T02:04:41.685653Z","steps":["trace[520605014] 'count revisions from in-memory index tree' (duration: 186.472437ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:04:52.827494Z","caller":"traceutil/trace.go:171","msg":"trace[1171376969] linearizableReadLoop","detail":"{readStateIndex:5647; appliedIndex:5646; }","duration":"111.424826ms","start":"2026-03-27T02:04:52.716036Z","end":"2026-03-27T02:04:52.827461Z","steps":["trace[1171376969] 'read index received' (duration: 111.127028ms)","trace[1171376969] 'applied index is now lower than readState.Index' (duration: 296.218µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:04:52.827564Z","caller":"traceutil/trace.go:171","msg":"trace[747672614] transaction","detail":"{read_only:false; response_revision:5452; number_of_response:1; }","duration":"121.843624ms","start":"2026-03-27T02:04:52.705687Z","end":"2026-03-27T02:04:52.82753Z","steps":["trace[747672614] 'process raft request' (duration: 121.581487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:04:52.827666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.610001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-27T02:04:52.827722Z","caller":"traceutil/trace.go:171","msg":"trace[237369344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5452; }","duration":"111.729495ms","start":"2026-03-27T02:04:52.715976Z","end":"2026-03-27T02:04:52.827705Z","steps":["trace[237369344] 'agreement among raft nodes before linearized reading' (duration: 111.623402ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:04:53.030014Z","caller":"traceutil/trace.go:171","msg":"trace[1956897205] transaction","detail":"{read_only:false; response_revision:5453; number_of_response:1; }","duration":"149.343643ms","start":"2026-03-27T02:04:52.880609Z","end":"2026-03-27T02:04:53.029952Z","steps":["trace[1956897205] 'process raft request' (duration: 145.98428ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:06:11.770291Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-03-27T02:06:11.891219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"119.396104ms","hash":899839044,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":33816576,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-03-27T02:06:11.891304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":899839044,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-03-27T02:10:36.674735Z","caller":"traceutil/trace.go:171","msg":"trace[2081725529] transaction","detail":"{read_only:false; response_revision:8072; number_of_response:1; }","duration":"179.199167ms","start":"2026-03-27T02:10:36.495497Z","end":"2026-03-27T02:10:36.674696Z","steps":["trace[2081725529] 'process raft request' (duration: 178.950359ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:10:49.044418Z","caller":"traceutil/trace.go:171","msg":"trace[1054477149] linearizableReadLoop","detail":"{readStateIndex:8414; appliedIndex:8413; }","duration":"192.382394ms","start":"2026-03-27T02:10:48.852012Z","end":"2026-03-27T02:10:49.044394Z","steps":["trace[1054477149] 'read index received' (duration: 192.122737ms)","trace[1054477149] 'applied index is now lower than readState.Index' (duration: 258.597µs)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:10:49.044559Z","caller":"traceutil/trace.go:171","msg":"trace[1094911369] transaction","detail":"{read_only:false; response_revision:8134; number_of_response:1; }","duration":"315.872089ms","start":"2026-03-27T02:10:48.728664Z","end":"2026-03-27T02:10:49.044536Z","steps":["trace[1094911369] 'process raft request' (duration: 315.527899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:10:49.04472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-27T02:10:48.728646Z","time spent":"315.981931ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-27T02:10:49.045094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.064342ms","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-03-27T02:10:49.045172Z","caller":"traceutil/trace.go:171","msg":"trace[1905616216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8134; }","duration":"193.200875ms","start":"2026-03-27T02:10:48.851953Z","end":"2026-03-27T02:10:49.045154Z","steps":["trace[1905616216] 'agreement among raft nodes before linearized reading' (duration: 192.939078ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:11:11.77734Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-03-27T02:11:11.880765Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"101.204524ms","hash":4270762825,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":22933504,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-27T02:11:11.880874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4270762825,"revision":6091,"compact-revision":2983} {"level":"info","ts":"2026-03-27T02:11:29.745477Z","caller":"traceutil/trace.go:171","msg":"trace[1409748912] transaction","detail":"{read_only:false; response_revision:8452; number_of_response:1; }","duration":"205.950125ms","start":"2026-03-27T02:11:29.539499Z","end":"2026-03-27T02:11:29.745449Z","steps":["trace[1409748912] 'process raft request' (duration: 205.566685ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:11:30.326593Z","caller":"traceutil/trace.go:171","msg":"trace[720604467] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"102.736766ms","start":"2026-03-27T02:11:30.223832Z","end":"2026-03-27T02:11:30.326568Z","steps":["trace[720604467] 'process raft request' (duration: 102.469098ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:11:30.695164Z","caller":"traceutil/trace.go:171","msg":"trace[2077502143] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"234.011155ms","start":"2026-03-27T02:11:30.461113Z","end":"2026-03-27T02:11:30.695124Z","steps":["trace[2077502143] 'process raft request' (duration: 233.758388ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:11:30.760459Z","caller":"traceutil/trace.go:171","msg":"trace[1437055437] linearizableReadLoop","detail":"{readStateIndex:8754; appliedIndex:8752; }","duration":"204.005372ms","start":"2026-03-27T02:11:30.556426Z","end":"2026-03-27T02:11:30.760432Z","steps":["trace[1437055437] 'read index received' (duration: 138.440665ms)","trace[1437055437] 'applied index is now lower than readState.Index' (duration: 65.563517ms)"],"step_count":2} {"level":"info","ts":"2026-03-27T02:11:30.760578Z","caller":"traceutil/trace.go:171","msg":"trace[821699176] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"263.624646ms","start":"2026-03-27T02:11:30.49689Z","end":"2026-03-27T02:11:30.760515Z","steps":["trace[821699176] 'process raft request' (duration: 263.376749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:11:30.760734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.245063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-27T02:11:30.760854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.066953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-03-27T02:11:30.760854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.19492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-27T02:11:30.760896Z","caller":"traceutil/trace.go:171","msg":"trace[1953125357] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8459; }","duration":"175.455238ms","start":"2026-03-27T02:11:30.5854Z","end":"2026-03-27T02:11:30.760855Z","steps":["trace[1953125357] 'agreement among raft nodes before linearized reading' (duration: 175.199011ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:11:30.760947Z","caller":"traceutil/trace.go:171","msg":"trace[1995865155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8459; }","duration":"164.335003ms","start":"2026-03-27T02:11:30.596596Z","end":"2026-03-27T02:11:30.760931Z","steps":["trace[1995865155] 'agreement among raft nodes before linearized reading' (duration: 164.018005ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:11:30.760947Z","caller":"traceutil/trace.go:171","msg":"trace[458093932] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8459; }","duration":"169.171807ms","start":"2026-03-27T02:11:30.591752Z","end":"2026-03-27T02:11:30.760924Z","steps":["trace[458093932] 'agreement among raft nodes before linearized reading' (duration: 168.926661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-27T02:11:30.760751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.30722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-27T02:11:30.761078Z","caller":"traceutil/trace.go:171","msg":"trace[143135379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8459; }","duration":"204.679521ms","start":"2026-03-27T02:11:30.556346Z","end":"2026-03-27T02:11:30.761026Z","steps":["trace[143135379] 'agreement among raft nodes before linearized reading' (duration: 204.214887ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:12:05.905809Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023ef.wal"} {"level":"info","ts":"2026-03-27T02:13:49.249858Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-27T02:13:49.255704Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-27T02:13:49.255847Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-03-27T02:14:42.507272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.801284ms","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-03-27T02:14:42.507393Z","caller":"traceutil/trace.go:171","msg":"trace[1493143832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10129; }","duration":"107.968898ms","start":"2026-03-27T02:14:42.399395Z","end":"2026-03-27T02:14:42.507364Z","steps":["trace[1493143832] 'range keys from in-memory index tree' (duration: 107.599059ms)"],"step_count":1} {"level":"info","ts":"2026-03-27T02:16:11.785013Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8266} {"level":"info","ts":"2026-03-27T02:16:11.859685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8266,"took":"73.033108ms","hash":899636441,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":24870912,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-03-27T02:16:11.859761Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":899636441,"revision":8266,"compact-revision":6091} {"level":"info","ts":"2026-03-27T02:21:11.791575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10658} {"level":"info","ts":"2026-03-27T02:21:11.869563Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10658,"took":"75.753695ms","hash":74687056,"current-db-size-bytes":58535936,"current-db-size":"58 MB","current-db-size-in-use-bytes":20262912,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-03-27T02:21:11.869636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":74687056,"revision":10658,"compact-revision":8266}