{"level":"warn","ts":"2026-04-03T02:23:55.607825Z","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-03T02:23:55.607908Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246: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-03T02:23:55.607972Z","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-03T02:23:55.607986Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-03T02:23:55.608001Z","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-03T02:23:55.608611Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-03T02:23:55.608687Z","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://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246: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-03T02:23:55.612523Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.638336ms"} {"level":"info","ts":"2026-04-03T02:23:55.61995Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-03T02:23:55.620126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T02:23:55.620176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-03T02:23:55.620301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T02:23:55.620317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-03T02:23:55.620406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-03T02:23:55.626547Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T02:23:55.627971Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T02:23:55.629602Z","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-03T02:23:55.632536Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T02:23:55.632742Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T02:23:55.632827Z","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-03T02:23:55.632964Z","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-03T02:23:55.632979Z","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-03T02:23:55.634985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-03T02:23:55.635338Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-03T02:23:55.635355Z","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-03T02:23:55.635383Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-03T02:23:55.635592Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-03T02:23:55.635942Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T02:23:55.635995Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T02:23:55.921784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T02:23:55.921845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T02:23:55.921862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-03T02:23:55.921875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-03T02:23:55.92188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-03T02:23:55.921889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-03T02:23:55.921896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-03T02:23:55.92991Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:55.939199Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T02:23:55.939442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:23:55.939487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:23:55.93983Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T02:23:55.940011Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T02:23:55.953803Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:55.954258Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:55.95447Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:23:55.955392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-03T02:23:55.966148Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-03T02:24:12.063414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.384295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:24:12.063608Z","caller":"traceutil/trace.go:171","msg":"trace[889608111] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"225.684838ms","start":"2026-04-03T02:24:11.837904Z","end":"2026-04-03T02:24:12.063589Z","steps":["trace[889608111] 'process raft request' (duration: 92.55438ms)","trace[889608111] 'compare' (duration: 132.230032ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:24:32.740959Z","caller":"traceutil/trace.go:171","msg":"trace[455299652] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"182.853059ms","start":"2026-04-03T02:24:32.558078Z","end":"2026-04-03T02:24:32.740931Z","steps":["trace[455299652] 'process raft request' (duration: 182.722517ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:24:33.479645Z","caller":"traceutil/trace.go:171","msg":"trace[1075170211] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"360.008466ms","start":"2026-04-03T02:24:33.119604Z","end":"2026-04-03T02:24:33.479613Z","steps":["trace[1075170211] 'process raft request' (duration: 359.771102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:24:33.479843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:24:33.119575Z","time spent":"360.18475ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:24:33.66521Z","caller":"traceutil/trace.go:171","msg":"trace[632834709] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"521.334442ms","start":"2026-04-03T02:24:33.143845Z","end":"2026-04-03T02:24:33.66518Z","steps":["trace[632834709] 'process raft request' (duration: 515.822331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:24:33.665493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:24:33.143812Z","time spent":"521.527946ms","remote":"127.0.0.1:46676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:24:49.22444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.698723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T02:24:49.224539Z","caller":"traceutil/trace.go:171","msg":"trace[2110505393] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:786; }","duration":"142.886906ms","start":"2026-04-03T02:24:49.081629Z","end":"2026-04-03T02:24:49.224516Z","steps":["trace[2110505393] 'range keys from in-memory index tree' (duration: 142.436948ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:25:39.631907Z","caller":"traceutil/trace.go:171","msg":"trace[1942816945] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"127.836308ms","start":"2026-04-03T02:25:39.504043Z","end":"2026-04-03T02:25:39.63188Z","steps":["trace[1942816945] 'process raft request' (duration: 127.688855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:05.539672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.926185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T02:26:05.539831Z","caller":"traceutil/trace.go:171","msg":"trace[1297935925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1843; }","duration":"332.152779ms","start":"2026-04-03T02:26:05.207647Z","end":"2026-04-03T02:26:05.5398Z","steps":["trace[1297935925] 'range keys from in-memory index tree' (duration: 331.64191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:26:05.539702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.876123ms","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-04-03T02:26:05.539912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:26:05.207624Z","time spent":"332.255181ms","remote":"127.0.0.1:46876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T02:26:05.539943Z","caller":"traceutil/trace.go:171","msg":"trace[421811366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1843; }","duration":"223.173689ms","start":"2026-04-03T02:26:05.316748Z","end":"2026-04-03T02:26:05.539922Z","steps":["trace[421811366] 'range keys from in-memory index tree' (duration: 222.67836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:28:18.465901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.383823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-03T02:28:18.466012Z","caller":"traceutil/trace.go:171","msg":"trace[986954987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2812; }","duration":"133.563146ms","start":"2026-04-03T02:28:18.332429Z","end":"2026-04-03T02:28:18.465992Z","steps":["trace[986954987] 'range keys from in-memory index tree' (duration: 133.144849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:04.29229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.057895ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-03T02:32:04.292452Z","caller":"traceutil/trace.go:171","msg":"trace[1261671410] linearizableReadLoop","detail":"{readStateIndex:4930; appliedIndex:4929; }","duration":"100.673271ms","start":"2026-04-03T02:32:04.19174Z","end":"2026-04-03T02:32:04.292413Z","steps":["trace[1261671410] 'read index received' (duration: 47.381µs)","trace[1261671410] 'applied index is now lower than readState.Index' (duration: 100.624349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:32:04.292884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.130729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:9 size:71715"} {"level":"info","ts":"2026-04-03T02:32:04.292944Z","caller":"traceutil/trace.go:171","msg":"trace[1049622898] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:9; response_revision:4746; }","duration":"101.243752ms","start":"2026-04-03T02:32:04.19169Z","end":"2026-04-03T02:32:04.292934Z","steps":["trace[1049622898] 'agreement among raft nodes before linearized reading' (duration: 100.862945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:32:04.324267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.001147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-04-03T02:32:04.324366Z","caller":"traceutil/trace.go:171","msg":"trace[1555790464] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4748; }","duration":"132.166649ms","start":"2026-04-03T02:32:04.192179Z","end":"2026-04-03T02:32:04.324345Z","steps":["trace[1555790464] 'agreement among raft nodes before linearized reading' (duration: 131.874904ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:18.384032Z","caller":"traceutil/trace.go:171","msg":"trace[174063038] transaction","detail":"{read_only:false; response_revision:5098; number_of_response:1; }","duration":"157.328702ms","start":"2026-04-03T02:32:18.226684Z","end":"2026-04-03T02:32:18.384012Z","steps":["trace[174063038] 'process raft request' (duration: 157.18966ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:32:19.323267Z","caller":"traceutil/trace.go:171","msg":"trace[1761320204] transaction","detail":"{read_only:false; response_revision:5108; number_of_response:1; }","duration":"160.160295ms","start":"2026-04-03T02:32:19.16307Z","end":"2026-04-03T02:32:19.32323Z","steps":["trace[1761320204] 'process raft request' (duration: 149.977265ms)","trace[1761320204] 'compare' (duration: 10.028707ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:32:29.355264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.82118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:32:29.355379Z","caller":"traceutil/trace.go:171","msg":"trace[1477451183] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"193.764641ms","start":"2026-04-03T02:32:29.161587Z","end":"2026-04-03T02:32:29.355352Z","steps":["trace[1477451183] 'process raft request' (duration: 71.776758ms)","trace[1477451183] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/masterleases/199.204.45.246; req_size:118; } (duration: 121.666617ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:33:14.157123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.966281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:33:14.157137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.001652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:33:14.157232Z","caller":"traceutil/trace.go:171","msg":"trace[819101910] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5825; }","duration":"105.143385ms","start":"2026-04-03T02:33:14.052067Z","end":"2026-04-03T02:33:14.157211Z","steps":["trace[819101910] 'range keys from in-memory index tree' (duration: 104.843879ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:33:14.157253Z","caller":"traceutil/trace.go:171","msg":"trace[1604456820] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5825; }","duration":"105.158075ms","start":"2026-04-03T02:33:14.052067Z","end":"2026-04-03T02:33:14.157225Z","steps":["trace[1604456820] 'range keys from in-memory index tree' (duration: 104.876079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:33:14.157269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.270268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6159"} {"level":"info","ts":"2026-04-03T02:33:14.157374Z","caller":"traceutil/trace.go:171","msg":"trace[81424476] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5825; }","duration":"104.404691ms","start":"2026-04-03T02:33:14.052945Z","end":"2026-04-03T02:33:14.15735Z","steps":["trace[81424476] 'range keys from in-memory index tree' (duration: 104.072554ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:33:56.065515Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3020} {"level":"info","ts":"2026-04-03T02:33:56.146957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3020,"took":"80.023165ms","hash":878741475,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-03T02:33:56.147039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":878741475,"revision":3020,"compact-revision":-1} {"level":"info","ts":"2026-04-03T02:38:26.510954Z","caller":"traceutil/trace.go:171","msg":"trace[414367973] transaction","detail":"{read_only:false; response_revision:8146; number_of_response:1; }","duration":"253.303274ms","start":"2026-04-03T02:38:26.25762Z","end":"2026-04-03T02:38:26.510924Z","steps":["trace[414367973] 'process raft request' (duration: 253.154811ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:26.666921Z","caller":"traceutil/trace.go:171","msg":"trace[1331230372] transaction","detail":"{read_only:false; response_revision:8147; number_of_response:1; }","duration":"151.096299ms","start":"2026-04-03T02:38:26.5158Z","end":"2026-04-03T02:38:26.666896Z","steps":["trace[1331230372] 'process raft request' (duration: 150.937986ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:26.686101Z","caller":"traceutil/trace.go:171","msg":"trace[639722291] transaction","detail":"{read_only:false; response_revision:8148; number_of_response:1; }","duration":"169.859561ms","start":"2026-04-03T02:38:26.516221Z","end":"2026-04-03T02:38:26.68608Z","steps":["trace[639722291] 'process raft request' (duration: 169.705358ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:26.68635Z","caller":"traceutil/trace.go:171","msg":"trace[2092169531] transaction","detail":"{read_only:false; response_revision:8149; number_of_response:1; }","duration":"169.522054ms","start":"2026-04-03T02:38:26.516801Z","end":"2026-04-03T02:38:26.686323Z","steps":["trace[2092169531] 'process raft request' (duration: 169.239819ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:27.531657Z","caller":"traceutil/trace.go:171","msg":"trace[869411336] transaction","detail":"{read_only:false; response_revision:8151; number_of_response:1; }","duration":"109.97941ms","start":"2026-04-03T02:38:27.421636Z","end":"2026-04-03T02:38:27.531615Z","steps":["trace[869411336] 'process raft request' (duration: 63.899517ms)","trace[869411336] 'compare' (duration: 45.766917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:38:54.908386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.60704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:38:54.908497Z","caller":"traceutil/trace.go:171","msg":"trace[2143980534] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:8350; }","duration":"111.770623ms","start":"2026-04-03T02:38:54.796707Z","end":"2026-04-03T02:38:54.908478Z","steps":["trace[2143980534] 'count revisions from in-memory index tree' (duration: 111.500279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:38:54.908792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.686681ms","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-04-03T02:38:54.9091Z","caller":"traceutil/trace.go:171","msg":"trace[1670836255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8350; }","duration":"112.368124ms","start":"2026-04-03T02:38:54.796707Z","end":"2026-04-03T02:38:54.909075Z","steps":["trace[1670836255] 'range keys from in-memory index tree' (duration: 111.480688ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:56.068354Z","caller":"traceutil/trace.go:171","msg":"trace[926734045] transaction","detail":"{read_only:false; response_revision:8359; number_of_response:1; }","duration":"123.662916ms","start":"2026-04-03T02:38:55.944658Z","end":"2026-04-03T02:38:56.06832Z","steps":["trace[926734045] 'process raft request' (duration: 123.418741ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:38:56.105233Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6160} {"level":"info","ts":"2026-04-03T02:38:56.231019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6160,"took":"123.803829ms","hash":205361497,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":23015424,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-03T02:38:56.2311Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":205361497,"revision":6160,"compact-revision":3020} {"level":"warn","ts":"2026-04-03T02:39:07.051635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.604178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-03T02:39:07.051758Z","caller":"traceutil/trace.go:171","msg":"trace[1252182213] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8452; }","duration":"126.303386ms","start":"2026-04-03T02:39:06.925439Z","end":"2026-04-03T02:39:07.051742Z","steps":["trace[1252182213] 'compare' (duration: 121.474694ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:07.071895Z","caller":"traceutil/trace.go:171","msg":"trace[871330297] linearizableReadLoop","detail":"{readStateIndex:8758; appliedIndex:8757; }","duration":"106.497964ms","start":"2026-04-03T02:39:06.965374Z","end":"2026-04-03T02:39:07.071872Z","steps":["trace[871330297] 'read index received' (duration: 106.330781ms)","trace[871330297] 'applied index is now lower than readState.Index' (duration: 166.483µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:07.072122Z","caller":"traceutil/trace.go:171","msg":"trace[331656070] transaction","detail":"{read_only:false; response_revision:8453; number_of_response:1; }","duration":"146.382382ms","start":"2026-04-03T02:39:06.925729Z","end":"2026-04-03T02:39:07.072112Z","steps":["trace[331656070] 'process raft request' (duration: 146.033495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:07.072441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.052295ms","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-03T02:39:07.072532Z","caller":"traceutil/trace.go:171","msg":"trace[1353694451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8453; }","duration":"107.195838ms","start":"2026-04-03T02:39:06.965311Z","end":"2026-04-03T02:39:07.072506Z","steps":["trace[1353694451] 'agreement among raft nodes before linearized reading' (duration: 106.908762ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:07.197716Z","caller":"traceutil/trace.go:171","msg":"trace[62719108] transaction","detail":"{read_only:false; response_revision:8454; number_of_response:1; }","duration":"120.278483ms","start":"2026-04-03T02:39:07.077411Z","end":"2026-04-03T02:39:07.197689Z","steps":["trace[62719108] 'process raft request' (duration: 109.191275ms)","trace[62719108] 'compare' (duration: 10.858244ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:07.197963Z","caller":"traceutil/trace.go:171","msg":"trace[670038912] transaction","detail":"{read_only:false; response_revision:8455; number_of_response:1; }","duration":"120.379554ms","start":"2026-04-03T02:39:07.077558Z","end":"2026-04-03T02:39:07.197937Z","steps":["trace[670038912] 'process raft request' (duration: 120.066809ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:07.197966Z","caller":"traceutil/trace.go:171","msg":"trace[1551308563] linearizableReadLoop","detail":"{readStateIndex:8761; appliedIndex:8758; }","duration":"118.806385ms","start":"2026-04-03T02:39:07.079134Z","end":"2026-04-03T02:39:07.197941Z","steps":["trace[1551308563] 'read index received' (duration: 107.603116ms)","trace[1551308563] 'applied index is now lower than readState.Index' (duration: 11.202049ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:39:07.198347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.296935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4l2x4\" ","response":"range_response_count:1 size:4670"} {"level":"warn","ts":"2026-04-03T02:39:07.198262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.104361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4l2x4\" ","response":"range_response_count:1 size:4670"} {"level":"info","ts":"2026-04-03T02:39:07.198383Z","caller":"traceutil/trace.go:171","msg":"trace[452498934] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4l2x4; range_end:; response_count:1; response_revision:8457; }","duration":"118.360596ms","start":"2026-04-03T02:39:07.080012Z","end":"2026-04-03T02:39:07.198373Z","steps":["trace[452498934] 'agreement among raft nodes before linearized reading' (duration: 118.210814ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:07.198437Z","caller":"traceutil/trace.go:171","msg":"trace[1661860205] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4l2x4; range_end:; response_count:1; response_revision:8457; }","duration":"119.302855ms","start":"2026-04-03T02:39:07.079085Z","end":"2026-04-03T02:39:07.198387Z","steps":["trace[1661860205] 'agreement among raft nodes before linearized reading' (duration: 118.936178ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:07.198446Z","caller":"traceutil/trace.go:171","msg":"trace[1949761804] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"120.731431ms","start":"2026-04-03T02:39:07.077655Z","end":"2026-04-03T02:39:07.198386Z","steps":["trace[1949761804] 'process raft request' (duration: 120.14196ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:07.808086Z","caller":"traceutil/trace.go:171","msg":"trace[1231220680] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"117.080353ms","start":"2026-04-03T02:39:07.690976Z","end":"2026-04-03T02:39:07.808056Z","steps":["trace[1231220680] 'process raft request' (duration: 35.791431ms)","trace[1231220680] 'compare' (duration: 81.20732ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:39:08.074655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.181917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-4l2x4\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-04-03T02:39:08.074726Z","caller":"traceutil/trace.go:171","msg":"trace[134745354] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-4l2x4; range_end:; response_count:1; response_revision:8461; }","duration":"162.29489ms","start":"2026-04-03T02:39:07.912414Z","end":"2026-04-03T02:39:08.074709Z","steps":["trace[134745354] 'range keys from in-memory index tree' (duration: 161.867532ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:08.074753Z","caller":"traceutil/trace.go:171","msg":"trace[118732902] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"162.259399ms","start":"2026-04-03T02:39:07.912471Z","end":"2026-04-03T02:39:08.074731Z","steps":["trace[118732902] 'process raft request' (duration: 156.033213ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:08.086938Z","caller":"traceutil/trace.go:171","msg":"trace[1388977959] linearizableReadLoop","detail":"{readStateIndex:8768; appliedIndex:8766; }","duration":"148.645724ms","start":"2026-04-03T02:39:07.938268Z","end":"2026-04-03T02:39:08.086913Z","steps":["trace[1388977959] 'read index received' (duration: 130.25247ms)","trace[1388977959] 'applied index is now lower than readState.Index' (duration: 18.392544ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:08.087073Z","caller":"traceutil/trace.go:171","msg":"trace[1155455747] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"167.14658ms","start":"2026-04-03T02:39:07.919912Z","end":"2026-04-03T02:39:08.087058Z","steps":["trace[1155455747] 'process raft request' (duration: 166.919245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:08.087285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.00207ms","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-04-03T02:39:08.087365Z","caller":"traceutil/trace.go:171","msg":"trace[2060406135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8463; }","duration":"149.123903ms","start":"2026-04-03T02:39:07.938221Z","end":"2026-04-03T02:39:08.087345Z","steps":["trace[2060406135] 'agreement among raft nodes before linearized reading' (duration: 148.858228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:39:08.088743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.921545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-03T02:39:08.088782Z","caller":"traceutil/trace.go:171","msg":"trace[2000811354] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8463; }","duration":"113.017177ms","start":"2026-04-03T02:39:07.975757Z","end":"2026-04-03T02:39:08.088774Z","steps":["trace[2000811354] 'agreement among raft nodes before linearized reading' (duration: 111.629931ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:08.746459Z","caller":"traceutil/trace.go:171","msg":"trace[913078147] transaction","detail":"{read_only:false; response_revision:8470; number_of_response:1; }","duration":"223.104119ms","start":"2026-04-03T02:39:08.523326Z","end":"2026-04-03T02:39:08.74643Z","steps":["trace[913078147] 'process raft request' (duration: 222.928275ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:39:08.892237Z","caller":"traceutil/trace.go:171","msg":"trace[1699895690] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"108.958701ms","start":"2026-04-03T02:39:08.783249Z","end":"2026-04-03T02:39:08.892207Z","steps":["trace[1699895690] 'process raft request' (duration: 92.32833ms)","trace[1699895690] 'compare' (duration: 16.504679ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:39:58.485415Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002465.wal"} {"level":"info","ts":"2026-04-03T02:40:14.175141Z","caller":"traceutil/trace.go:171","msg":"trace[118493276] transaction","detail":"{read_only:false; response_revision:9095; number_of_response:1; }","duration":"134.218753ms","start":"2026-04-03T02:40:14.040895Z","end":"2026-04-03T02:40:14.175114Z","steps":["trace[118493276] 'process raft request' (duration: 134.0463ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:41:22.046189Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ab97322ebdd79664","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-03T02:41:22.050987Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-03T02:41:22.051078Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-03T02:43:56.111833Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8360} {"level":"info","ts":"2026-04-03T02:43:56.192896Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8360,"took":"78.796985ms","hash":1423433764,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":24756224,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-03T02:43:56.192976Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1423433764,"revision":8360,"compact-revision":6160} {"level":"info","ts":"2026-04-03T02:48:56.11764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10740} {"level":"info","ts":"2026-04-03T02:48:56.195661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10740,"took":"75.842488ms","hash":355352400,"current-db-size-bytes":57978880,"current-db-size":"58 MB","current-db-size-in-use-bytes":20357120,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-03T02:48:56.195712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":355352400,"revision":10740,"compact-revision":8360}