{"level":"warn","ts":"2026-04-07T12:55:43.271497Z","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-07T12:55:43.271627Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-07T12:55:43.271714Z","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-07T12:55:43.271729Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-07T12:55:43.271753Z","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-07T12:55:43.272538Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-04-07T12:55:43.272634Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-07T12:55:43.277481Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.550961ms"} {"level":"info","ts":"2026-04-07T12:55:43.286631Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-04-07T12:55:43.286737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T12:55:43.286772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-04-07T12:55:43.286784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T12:55:43.286803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-04-07T12:55:43.286852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-04-07T12:55:43.292873Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T12:55:43.294305Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T12:55:43.297002Z","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-07T12:55:43.300258Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T12:55:43.300522Z","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-07T12:55:43.300674Z","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-07T12:55:43.300692Z","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-07T12:55:43.301049Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T12:55:43.304235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-04-07T12:55:43.304993Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-04-07T12:55:43.307398Z","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-07T12:55:43.307872Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T12:55:43.307947Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T12:55:43.30807Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-07T12:55:43.308155Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-04-07T12:55:43.787806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T12:55:43.787884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T12:55:43.787945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-04-07T12:55:43.78797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-04-07T12:55:43.787982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-07T12:55:43.788007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-04-07T12:55:43.788028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-04-07T12:55:43.790088Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T12:55:43.790123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:55:43.790158Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T12:55:43.79044Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:43.790518Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T12:55:43.790623Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T12:55:43.791612Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:43.791777Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:43.791816Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T12:55:43.793288Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T12:55:43.793306Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-04-07T12:56:03.395192Z","caller":"traceutil/trace.go:171","msg":"trace[2120435553] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"261.350892ms","start":"2026-04-07T12:56:03.133577Z","end":"2026-04-07T12:56:03.394928Z","steps":["trace[2120435553] 'process raft request' (duration: 261.142286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:14.990652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.42221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:56:14.990861Z","caller":"traceutil/trace.go:171","msg":"trace[1688057961] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"351.051953ms","start":"2026-04-07T12:56:14.639783Z","end":"2026-04-07T12:56:14.990835Z","steps":["trace[1688057961] 'process raft request' (duration: 177.879ms)","trace[1688057961] 'compare' (duration: 172.191464ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:14.990936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:14.639741Z","time spent":"351.163186ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T12:56:14.993884Z","caller":"traceutil/trace.go:171","msg":"trace[1918749488] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"315.53082ms","start":"2026-04-07T12:56:14.678329Z","end":"2026-04-07T12:56:14.99386Z","steps":["trace[1918749488] 'process raft request' (duration: 315.449967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:14.993939Z","caller":"traceutil/trace.go:171","msg":"trace[178469855] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"324.761408ms","start":"2026-04-07T12:56:14.669142Z","end":"2026-04-07T12:56:14.993904Z","steps":["trace[178469855] 'process raft request' (duration: 324.47635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:14.994269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:14.678261Z","time spent":"315.711355ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:56:14.994441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:14.669102Z","time spent":"324.909802ms","remote":"127.0.0.1:34966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:56:21.667369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.107376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:56:21.667491Z","caller":"traceutil/trace.go:171","msg":"trace[292412583] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"177.745275ms","start":"2026-04-07T12:56:21.489729Z","end":"2026-04-07T12:56:21.667475Z","steps":["trace[292412583] 'read index received' (duration: 52.414203ms)","trace[292412583] 'applied index is now lower than readState.Index' (duration: 125.329852ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:56:21.667576Z","caller":"traceutil/trace.go:171","msg":"trace[632724665] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"185.667165ms","start":"2026-04-07T12:56:21.481898Z","end":"2026-04-07T12:56:21.667566Z","steps":["trace[632724665] 'process raft request' (duration: 60.290091ms)","trace[632724665] 'compare' (duration: 124.999673ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:56:21.667811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.790376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-07T12:56:21.667888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.272218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-07T12:56:21.667972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.244439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T12:56:21.667966Z","caller":"traceutil/trace.go:171","msg":"trace[120873205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:617; }","duration":"158.401623ms","start":"2026-04-07T12:56:21.509548Z","end":"2026-04-07T12:56:21.66795Z","steps":["trace[120873205] 'agreement among raft nodes before linearized reading' (duration: 158.214327ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:21.668012Z","caller":"traceutil/trace.go:171","msg":"trace[1499487067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:617; }","duration":"178.343772ms","start":"2026-04-07T12:56:21.489655Z","end":"2026-04-07T12:56:21.667999Z","steps":["trace[1499487067] 'agreement among raft nodes before linearized reading' (duration: 178.237679ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:21.667899Z","caller":"traceutil/trace.go:171","msg":"trace[75500218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:617; }","duration":"167.9206ms","start":"2026-04-07T12:56:21.499956Z","end":"2026-04-07T12:56:21.667877Z","steps":["trace[75500218] 'agreement among raft nodes before linearized reading' (duration: 167.665522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:56:22.293046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:56:21.945499Z","time spent":"347.538129ms","remote":"127.0.0.1:34474","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-07T12:56:22.294629Z","caller":"traceutil/trace.go:171","msg":"trace[276135040] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"203.815183ms","start":"2026-04-07T12:56:22.090785Z","end":"2026-04-07T12:56:22.2946Z","steps":["trace[276135040] 'process raft request' (duration: 198.994933ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:56:40.180097Z","caller":"traceutil/trace.go:171","msg":"trace[466558135] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"159.794593ms","start":"2026-04-07T12:56:40.020266Z","end":"2026-04-07T12:56:40.180061Z","steps":["trace[466558135] 'process raft request' (duration: 159.575967ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:09.661924Z","caller":"traceutil/trace.go:171","msg":"trace[1652026386] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"145.428927ms","start":"2026-04-07T12:57:09.516459Z","end":"2026-04-07T12:57:09.661888Z","steps":["trace[1652026386] 'process raft request' (duration: 145.219661ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:53.507805Z","caller":"traceutil/trace.go:171","msg":"trace[1119273265] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"397.124038ms","start":"2026-04-07T12:57:53.110652Z","end":"2026-04-07T12:57:53.507776Z","steps":["trace[1119273265] 'process raft request' (duration: 396.958713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:53.507991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:53.110637Z","time spent":"397.260651ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:57:53.519072Z","caller":"traceutil/trace.go:171","msg":"trace[1332118658] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1912; }","duration":"267.823247ms","start":"2026-04-07T12:57:53.251187Z","end":"2026-04-07T12:57:53.51901Z","steps":["trace[1332118658] 'read index received' (duration: 256.463069ms)","trace[1332118658] 'applied index is now lower than readState.Index' (duration: 11.358348ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:53.519167Z","caller":"traceutil/trace.go:171","msg":"trace[1941546859] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"404.987885ms","start":"2026-04-07T12:57:53.114126Z","end":"2026-04-07T12:57:53.519114Z","steps":["trace[1941546859] 'process raft request' (duration: 404.613144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:53.519356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.168327ms","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-07T12:57:53.519479Z","caller":"traceutil/trace.go:171","msg":"trace[2014267419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1844; }","duration":"268.344952ms","start":"2026-04-07T12:57:53.251121Z","end":"2026-04-07T12:57:53.519466Z","steps":["trace[2014267419] 'agreement among raft nodes before linearized reading' (duration: 268.093325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:53.519472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:57:53.114103Z","time spent":"405.240181ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T12:57:53.519527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.809855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-04-07T12:57:53.519635Z","caller":"traceutil/trace.go:171","msg":"trace[1555989402] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1844; }","duration":"172.972851ms","start":"2026-04-07T12:57:53.346636Z","end":"2026-04-07T12:57:53.519609Z","steps":["trace[1555989402] 'agreement among raft nodes before linearized reading' (duration: 172.722054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:57:53.725915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.559124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T12:57:53.72631Z","caller":"traceutil/trace.go:171","msg":"trace[1635119511] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"136.278742ms","start":"2026-04-07T12:57:53.58996Z","end":"2026-04-07T12:57:53.726239Z","steps":["trace[1635119511] 'process raft request' (duration: 11.146201ms)","trace[1635119511] 'compare' (duration: 124.448831ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T12:57:53.726419Z","caller":"traceutil/trace.go:171","msg":"trace[539848111] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"136.259072ms","start":"2026-04-07T12:57:53.590123Z","end":"2026-04-07T12:57:53.726382Z","steps":["trace[539848111] 'process raft request' (duration: 135.984854ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:53.726414Z","caller":"traceutil/trace.go:171","msg":"trace[1969033082] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1916; }","duration":"100.17194ms","start":"2026-04-07T12:57:53.626219Z","end":"2026-04-07T12:57:53.72639Z","steps":["trace[1969033082] 'read index received' (duration: 99.431009ms)","trace[1969033082] 'applied index is now lower than readState.Index' (duration: 739.331µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:57:53.726708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.49231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a4147ad6975df5\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-07T12:57:53.726682Z","caller":"traceutil/trace.go:171","msg":"trace[302460636] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"136.156619ms","start":"2026-04-07T12:57:53.590468Z","end":"2026-04-07T12:57:53.726625Z","steps":["trace[302460636] 'process raft request' (duration: 135.85756ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:53.726759Z","caller":"traceutil/trace.go:171","msg":"trace[982009945] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a4147ad6975df5; range_end:; response_count:1; response_revision:1850; }","duration":"100.577402ms","start":"2026-04-07T12:57:53.626168Z","end":"2026-04-07T12:57:53.726746Z","steps":["trace[982009945] 'agreement among raft nodes before linearized reading' (duration: 100.394077ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:57:53.726621Z","caller":"traceutil/trace.go:171","msg":"trace[1259475099] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"136.415306ms","start":"2026-04-07T12:57:53.590181Z","end":"2026-04-07T12:57:53.726597Z","steps":["trace[1259475099] 'process raft request' (duration: 136.091167ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T12:58:42.770059Z","caller":"traceutil/trace.go:171","msg":"trace[1523820023] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"323.021548ms","start":"2026-04-07T12:58:42.447004Z","end":"2026-04-07T12:58:42.770026Z","steps":["trace[1523820023] 'process raft request' (duration: 322.783751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:42.770304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:58:42.446971Z","time spent":"323.232364ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T12:58:42.769969Z","caller":"traceutil/trace.go:171","msg":"trace[2022566792] linearizableReadLoop","detail":"{readStateIndex:2244; appliedIndex:2242; }","duration":"299.566914ms","start":"2026-04-07T12:58:42.47038Z","end":"2026-04-07T12:58:42.769947Z","steps":["trace[2022566792] 'read index received' (duration: 2.397878ms)","trace[2022566792] 'applied index is now lower than readState.Index' (duration: 297.167866ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T12:58:42.770955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.520782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-07T12:58:42.77109Z","caller":"traceutil/trace.go:171","msg":"trace[1110908668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2163; }","duration":"300.734968ms","start":"2026-04-07T12:58:42.470327Z","end":"2026-04-07T12:58:42.771062Z","steps":["trace[1110908668] 'agreement among raft nodes before linearized reading' (duration: 299.821122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:42.771247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:58:42.470307Z","time spent":"300.925103ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-07T12:58:42.784363Z","caller":"traceutil/trace.go:171","msg":"trace[2110654205] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"218.573001ms","start":"2026-04-07T12:58:42.565771Z","end":"2026-04-07T12:58:42.784344Z","steps":["trace[2110654205] 'process raft request' (duration: 218.478428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:42.784942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.215685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T12:58:42.785048Z","caller":"traceutil/trace.go:171","msg":"trace[1166172370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2164; }","duration":"314.398389ms","start":"2026-04-07T12:58:42.470627Z","end":"2026-04-07T12:58:42.785026Z","steps":["trace[1166172370] 'agreement among raft nodes before linearized reading' (duration: 313.777392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T12:58:42.785097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T12:58:42.470596Z","time spent":"314.492462ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T13:04:09.698004Z","caller":"traceutil/trace.go:171","msg":"trace[784971062] transaction","detail":"{read_only:false; response_revision:4846; number_of_response:1; }","duration":"109.435129ms","start":"2026-04-07T13:04:09.588533Z","end":"2026-04-07T13:04:09.697968Z","steps":["trace[784971062] 'process raft request' (duration: 109.171291ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:12.155208Z","caller":"traceutil/trace.go:171","msg":"trace[1691578952] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4886; }","duration":"144.790637ms","start":"2026-04-07T13:04:12.010348Z","end":"2026-04-07T13:04:12.155139Z","steps":["trace[1691578952] 'process raft request' (duration: 126.137564ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:28.511912Z","caller":"traceutil/trace.go:171","msg":"trace[1962905914] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"132.354936ms","start":"2026-04-07T13:04:28.379533Z","end":"2026-04-07T13:04:28.511887Z","steps":["trace[1962905914] 'process raft request' (duration: 132.15033ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:28.737454Z","caller":"traceutil/trace.go:171","msg":"trace[1156773047] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"220.723462ms","start":"2026-04-07T13:04:28.516703Z","end":"2026-04-07T13:04:28.737426Z","steps":["trace[1156773047] 'process raft request' (duration: 220.524056ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:28.737808Z","caller":"traceutil/trace.go:171","msg":"trace[451061885] linearizableReadLoop","detail":"{readStateIndex:5396; appliedIndex:5392; }","duration":"123.815736ms","start":"2026-04-07T13:04:28.613969Z","end":"2026-04-07T13:04:28.737784Z","steps":["trace[451061885] 'read index received' (duration: 123.202179ms)","trace[451061885] 'applied index is now lower than readState.Index' (duration: 612.577µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:28.73786Z","caller":"traceutil/trace.go:171","msg":"trace[855696500] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"221.073452ms","start":"2026-04-07T13:04:28.516703Z","end":"2026-04-07T13:04:28.737777Z","steps":["trace[855696500] 'process raft request' (duration: 220.682611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:28.73803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.041694ms","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-07T13:04:28.73803Z","caller":"traceutil/trace.go:171","msg":"trace[1549613338] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"196.702923ms","start":"2026-04-07T13:04:28.541294Z","end":"2026-04-07T13:04:28.737997Z","steps":["trace[1549613338] 'process raft request' (duration: 196.430096ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:28.738074Z","caller":"traceutil/trace.go:171","msg":"trace[1531174511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5203; }","duration":"124.137126ms","start":"2026-04-07T13:04:28.613926Z","end":"2026-04-07T13:04:28.738063Z","steps":["trace[1531174511] 'agreement among raft nodes before linearized reading' (duration: 123.954991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:28.737923Z","caller":"traceutil/trace.go:171","msg":"trace[1563677369] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"201.391181ms","start":"2026-04-07T13:04:28.536511Z","end":"2026-04-07T13:04:28.737903Z","steps":["trace[1563677369] 'process raft request' (duration: 201.111393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:29.040962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.853205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T13:04:29.0411Z","caller":"traceutil/trace.go:171","msg":"trace[1194619023] linearizableReadLoop","detail":"{readStateIndex:5397; appliedIndex:5396; }","duration":"276.946566ms","start":"2026-04-07T13:04:28.764132Z","end":"2026-04-07T13:04:29.041078Z","steps":["trace[1194619023] 'read index received' (duration: 44.775041ms)","trace[1194619023] 'applied index is now lower than readState.Index' (duration: 232.169155ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:29.041115Z","caller":"traceutil/trace.go:171","msg":"trace[1595446432] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"298.229404ms","start":"2026-04-07T13:04:28.742865Z","end":"2026-04-07T13:04:29.041094Z","steps":["trace[1595446432] 'process raft request' (duration: 66.121331ms)","trace[1595446432] 'compare' (duration: 231.594537ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:04:29.041201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.057989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:04:29.041249Z","caller":"traceutil/trace.go:171","msg":"trace[64446795] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:5204; }","duration":"277.151741ms","start":"2026-04-07T13:04:28.764083Z","end":"2026-04-07T13:04:29.041235Z","steps":["trace[64446795] 'agreement among raft nodes before linearized reading' (duration: 277.058928ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:41.421267Z","caller":"traceutil/trace.go:171","msg":"trace[1419246992] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"145.578319ms","start":"2026-04-07T13:04:41.275664Z","end":"2026-04-07T13:04:41.421242Z","steps":["trace[1419246992] 'process raft request' (duration: 145.435415ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:04:41.434538Z","caller":"traceutil/trace.go:171","msg":"trace[1800938835] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5602; }","duration":"145.179728ms","start":"2026-04-07T13:04:41.289336Z","end":"2026-04-07T13:04:41.434516Z","steps":["trace[1800938835] 'read index received' (duration: 131.770698ms)","trace[1800938835] 'applied index is now lower than readState.Index' (duration: 13.40795ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:04:41.434637Z","caller":"traceutil/trace.go:171","msg":"trace[375707926] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"155.896179ms","start":"2026-04-07T13:04:41.278714Z","end":"2026-04-07T13:04:41.43461Z","steps":["trace[375707926] 'process raft request' (duration: 155.653422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:41.434761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.398414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-07T13:04:41.434805Z","caller":"traceutil/trace.go:171","msg":"trace[1355439676] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5405; }","duration":"145.495957ms","start":"2026-04-07T13:04:41.289292Z","end":"2026-04-07T13:04:41.434788Z","steps":["trace[1355439676] 'agreement among raft nodes before linearized reading' (duration: 145.334062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:04:41.483458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.118073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tp9v\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T13:04:41.483535Z","caller":"traceutil/trace.go:171","msg":"trace[1041780330] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tp9v; range_end:; response_count:1; response_revision:5409; }","duration":"168.225987ms","start":"2026-04-07T13:04:41.315292Z","end":"2026-04-07T13:04:41.483518Z","steps":["trace[1041780330] 'agreement among raft nodes before linearized reading' (duration: 168.036561ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:05:43.820034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2863} {"level":"info","ts":"2026-04-07T13:05:43.884155Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2863,"took":"62.810737ms","hash":2146084219,"current-db-size-bytes":57143296,"current-db-size":"57 MB","current-db-size-in-use-bytes":32681984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T13:05:43.884345Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2146084219,"revision":2863,"compact-revision":-1} {"level":"info","ts":"2026-04-07T13:10:43.833071Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5908} {"level":"info","ts":"2026-04-07T13:10:43.942193Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5908,"took":"106.516423ms","hash":1699779213,"current-db-size-bytes":57143296,"current-db-size":"57 MB","current-db-size-in-use-bytes":25169920,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-07T13:10:43.942293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1699779213,"revision":5908,"compact-revision":2863} {"level":"info","ts":"2026-04-07T13:10:50.815945Z","caller":"traceutil/trace.go:171","msg":"trace[40309809] linearizableReadLoop","detail":"{readStateIndex:8446; appliedIndex:8445; }","duration":"255.712926ms","start":"2026-04-07T13:10:50.560203Z","end":"2026-04-07T13:10:50.815916Z","steps":["trace[40309809] 'read index received' (duration: 255.541801ms)","trace[40309809] 'applied index is now lower than readState.Index' (duration: 169.645µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:10:50.816094Z","caller":"traceutil/trace.go:171","msg":"trace[1643269699] transaction","detail":"{read_only:false; response_revision:8157; number_of_response:1; }","duration":"258.285751ms","start":"2026-04-07T13:10:50.557754Z","end":"2026-04-07T13:10:50.81604Z","steps":["trace[1643269699] 'process raft request' (duration: 257.985692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:10:50.816241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.065241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T13:10:50.816292Z","caller":"traceutil/trace.go:171","msg":"trace[243205678] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8157; }","duration":"175.176384ms","start":"2026-04-07T13:10:50.641096Z","end":"2026-04-07T13:10:50.816272Z","steps":["trace[243205678] 'agreement among raft nodes before linearized reading' (duration: 174.986388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:10:50.81629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.639167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T13:10:50.816241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.966294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:10:50.81638Z","caller":"traceutil/trace.go:171","msg":"trace[614861625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8157; }","duration":"157.769781ms","start":"2026-04-07T13:10:50.658595Z","end":"2026-04-07T13:10:50.816365Z","steps":["trace[614861625] 'agreement among raft nodes before linearized reading' (duration: 157.557445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:10:50.816391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.498899ms","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-07T13:10:50.816438Z","caller":"traceutil/trace.go:171","msg":"trace[542797465] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:8157; }","duration":"256.241802ms","start":"2026-04-07T13:10:50.560149Z","end":"2026-04-07T13:10:50.816391Z","steps":["trace[542797465] 'agreement among raft nodes before linearized reading' (duration: 255.915113ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:10:50.816492Z","caller":"traceutil/trace.go:171","msg":"trace[673329323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8157; }","duration":"152.629843ms","start":"2026-04-07T13:10:50.663839Z","end":"2026-04-07T13:10:50.816469Z","steps":["trace[673329323] 'agreement among raft nodes before linearized reading' (duration: 152.276603ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:03.423571Z","caller":"traceutil/trace.go:171","msg":"trace[1121948948] linearizableReadLoop","detail":"{readStateIndex:8511; appliedIndex:8510; }","duration":"466.486749ms","start":"2026-04-07T13:11:02.957055Z","end":"2026-04-07T13:11:03.423542Z","steps":["trace[1121948948] 'read index received' (duration: 466.289512ms)","trace[1121948948] 'applied index is now lower than readState.Index' (duration: 194.996µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:03.423613Z","caller":"traceutil/trace.go:171","msg":"trace[1937894686] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"537.938569ms","start":"2026-04-07T13:11:02.885635Z","end":"2026-04-07T13:11:03.423573Z","steps":["trace[1937894686] 'process raft request' (duration: 537.64623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.423815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:02.885618Z","time spent":"538.082983ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:11:03.423872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.790277ms","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-07T13:11:03.423931Z","caller":"traceutil/trace.go:171","msg":"trace[1681145455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8219; }","duration":"466.930721ms","start":"2026-04-07T13:11:02.956985Z","end":"2026-04-07T13:11:03.423915Z","steps":["trace[1681145455] 'agreement among raft nodes before linearized reading' (duration: 466.717465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.423982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:02.956949Z","time spent":"467.021324ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-07T13:11:03.552242Z","caller":"traceutil/trace.go:171","msg":"trace[938841134] linearizableReadLoop","detail":"{readStateIndex:8513; appliedIndex:8511; }","duration":"128.540038ms","start":"2026-04-07T13:11:03.423665Z","end":"2026-04-07T13:11:03.552205Z","steps":["trace[938841134] 'read index received' (duration: 91.879ms)","trace[938841134] 'applied index is now lower than readState.Index' (duration: 36.659748ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:03.552383Z","caller":"traceutil/trace.go:171","msg":"trace[1986183402] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"540.947097ms","start":"2026-04-07T13:11:03.011324Z","end":"2026-04-07T13:11:03.552271Z","steps":["trace[1986183402] 'process raft request' (duration: 540.819973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.552578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:03.011295Z","time spent":"541.180123ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T13:11:03.552646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.346159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T13:11:03.552726Z","caller":"traceutil/trace.go:171","msg":"trace[289785516] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8221; }","duration":"566.499354ms","start":"2026-04-07T13:11:02.986203Z","end":"2026-04-07T13:11:03.552702Z","steps":["trace[289785516] 'agreement among raft nodes before linearized reading' (duration: 566.178825ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.552752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.136275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:11:03.552783Z","caller":"traceutil/trace.go:171","msg":"trace[1479355043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8221; }","duration":"452.169806ms","start":"2026-04-07T13:11:03.100605Z","end":"2026-04-07T13:11:03.552775Z","steps":["trace[1479355043] 'agreement among raft nodes before linearized reading' (duration: 452.093214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.552779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:02.986183Z","time spent":"566.580957ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":595,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-07T13:11:03.552807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:03.100545Z","time spent":"452.256959ms","remote":"127.0.0.1:34906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T13:11:03.552655Z","caller":"traceutil/trace.go:171","msg":"trace[37607372] transaction","detail":"{read_only:false; response_revision:8220; number_of_response:1; }","duration":"593.520573ms","start":"2026-04-07T13:11:02.959109Z","end":"2026-04-07T13:11:03.55263Z","steps":["trace[37607372] 'process raft request' (duration: 556.419862ms)","trace[37607372] 'compare' (duration: 36.451521ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:11:03.552857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.580831ms","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-07T13:11:03.552874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.965279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T13:11:03.552895Z","caller":"traceutil/trace.go:171","msg":"trace[344445890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8221; }","duration":"388.656563ms","start":"2026-04-07T13:11:03.164225Z","end":"2026-04-07T13:11:03.552881Z","steps":["trace[344445890] 'agreement among raft nodes before linearized reading' (duration: 388.515629ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:03.552923Z","caller":"traceutil/trace.go:171","msg":"trace[1721124930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8221; }","duration":"565.072863ms","start":"2026-04-07T13:11:02.987835Z","end":"2026-04-07T13:11:03.552908Z","steps":["trace[1721124930] 'agreement among raft nodes before linearized reading' (duration: 564.893748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:03.552929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:03.164195Z","time spent":"388.726805ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-07T13:11:03.552965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:02.987803Z","time spent":"565.154155ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T13:11:03.553094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:02.959081Z","time spent":"593.833532ms","remote":"127.0.0.1:35164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:11:03.567206Z","caller":"traceutil/trace.go:171","msg":"trace[1366060109] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"138.138276ms","start":"2026-04-07T13:11:03.429036Z","end":"2026-04-07T13:11:03.567175Z","steps":["trace[1366060109] 'process raft request' (duration: 137.959631ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:32.150343Z","caller":"traceutil/trace.go:171","msg":"trace[1442489088] linearizableReadLoop","detail":"{readStateIndex:8728; appliedIndex:8727; }","duration":"203.394258ms","start":"2026-04-07T13:11:31.946917Z","end":"2026-04-07T13:11:32.150311Z","steps":["trace[1442489088] 'read index received' (duration: 203.188843ms)","trace[1442489088] 'applied index is now lower than readState.Index' (duration: 204.105µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:32.150497Z","caller":"traceutil/trace.go:171","msg":"trace[1316809035] transaction","detail":"{read_only:false; response_revision:8429; number_of_response:1; }","duration":"276.336022ms","start":"2026-04-07T13:11:31.87415Z","end":"2026-04-07T13:11:32.150486Z","steps":["trace[1316809035] 'process raft request' (duration: 275.938541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:32.150755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.81712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8tp9v\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-07T13:11:32.150804Z","caller":"traceutil/trace.go:171","msg":"trace[93628602] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8tp9v; range_end:; response_count:1; response_revision:8429; }","duration":"203.919573ms","start":"2026-04-07T13:11:31.946869Z","end":"2026-04-07T13:11:32.150789Z","steps":["trace[93628602] 'agreement among raft nodes before linearized reading' (duration: 203.757828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:32.1511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.946885ms","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-04-07T13:11:32.151215Z","caller":"traceutil/trace.go:171","msg":"trace[1977915658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8429; }","duration":"107.110449ms","start":"2026-04-07T13:11:32.044076Z","end":"2026-04-07T13:11:32.151186Z","steps":["trace[1977915658] 'agreement among raft nodes before linearized reading' (duration: 106.841832ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:32.37929Z","caller":"traceutil/trace.go:171","msg":"trace[899498028] linearizableReadLoop","detail":"{readStateIndex:8730; appliedIndex:8729; }","duration":"124.525933ms","start":"2026-04-07T13:11:32.254735Z","end":"2026-04-07T13:11:32.379261Z","steps":["trace[899498028] 'read index received' (duration: 67.92976ms)","trace[899498028] 'applied index is now lower than readState.Index' (duration: 56.594873ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:32.379481Z","caller":"traceutil/trace.go:171","msg":"trace[353559577] transaction","detail":"{read_only:false; response_revision:8431; number_of_response:1; }","duration":"171.526619ms","start":"2026-04-07T13:11:32.207927Z","end":"2026-04-07T13:11:32.379454Z","steps":["trace[353559577] 'process raft request' (duration: 114.801322ms)","trace[353559577] 'compare' (duration: 56.419588ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T13:11:32.379751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.993866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:11:32.379837Z","caller":"traceutil/trace.go:171","msg":"trace[1302240286] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:8431; }","duration":"125.13052ms","start":"2026-04-07T13:11:32.254687Z","end":"2026-04-07T13:11:32.379818Z","steps":["trace[1302240286] 'agreement among raft nodes before linearized reading' (duration: 125.014137ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:46.001917Z","caller":"traceutil/trace.go:171","msg":"trace[1201776386] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"311.406714ms","start":"2026-04-07T13:11:45.690469Z","end":"2026-04-07T13:11:46.001876Z","steps":["trace[1201776386] 'process raft request' (duration: 311.159486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.002122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:45.690449Z","time spent":"311.574239ms","remote":"127.0.0.1:34990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T13:11:46.025058Z","caller":"traceutil/trace.go:171","msg":"trace[487903650] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"325.653025ms","start":"2026-04-07T13:11:45.69936Z","end":"2026-04-07T13:11:46.025013Z","steps":["trace[487903650] 'process raft request' (duration: 325.432438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.025272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T13:11:45.699304Z","time spent":"325.864121ms","remote":"127.0.0.1:35084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4638,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T13:11:46.025423Z","caller":"traceutil/trace.go:171","msg":"trace[1915869230] linearizableReadLoop","detail":"{readStateIndex:8844; appliedIndex:8842; }","duration":"297.522434ms","start":"2026-04-07T13:11:45.727868Z","end":"2026-04-07T13:11:46.02539Z","steps":["trace[1915869230] 'read index received' (duration: 273.789488ms)","trace[1915869230] 'applied index is now lower than readState.Index' (duration: 23.731365ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:46.025538Z","caller":"traceutil/trace.go:171","msg":"trace[1044460850] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"291.535451ms","start":"2026-04-07T13:11:45.733977Z","end":"2026-04-07T13:11:46.025513Z","steps":["trace[1044460850] 'process raft request' (duration: 290.958545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.025678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.75221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T13:11:46.025731Z","caller":"traceutil/trace.go:171","msg":"trace[1920199045] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:8541; }","duration":"297.900444ms","start":"2026-04-07T13:11:45.727815Z","end":"2026-04-07T13:11:46.025716Z","steps":["trace[1920199045] 'agreement among raft nodes before linearized reading' (duration: 297.748509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.025795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.07173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-04-07T13:11:46.025858Z","caller":"traceutil/trace.go:171","msg":"trace[190083266] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8541; }","duration":"211.151601ms","start":"2026-04-07T13:11:45.814691Z","end":"2026-04-07T13:11:46.025843Z","steps":["trace[190083266] 'agreement among raft nodes before linearized reading' (duration: 210.971956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.025878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.177013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-07T13:11:46.025884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.457251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-07T13:11:46.02596Z","caller":"traceutil/trace.go:171","msg":"trace[1633183806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8541; }","duration":"228.288706ms","start":"2026-04-07T13:11:45.797653Z","end":"2026-04-07T13:11:46.025941Z","steps":["trace[1633183806] 'agreement among raft nodes before linearized reading' (duration: 228.111651ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:46.025974Z","caller":"traceutil/trace.go:171","msg":"trace[1935701192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8541; }","duration":"271.597736ms","start":"2026-04-07T13:11:45.754356Z","end":"2026-04-07T13:11:46.025954Z","steps":["trace[1935701192] 'agreement among raft nodes before linearized reading' (duration: 271.291197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.026007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.045922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T13:11:46.02583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.321084ms","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-07T13:11:46.026062Z","caller":"traceutil/trace.go:171","msg":"trace[1086962492] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8541; }","duration":"243.160045ms","start":"2026-04-07T13:11:45.782887Z","end":"2026-04-07T13:11:46.026047Z","steps":["trace[1086962492] 'agreement among raft nodes before linearized reading' (duration: 242.97639ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:46.026135Z","caller":"traceutil/trace.go:171","msg":"trace[740039411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8541; }","duration":"222.629093ms","start":"2026-04-07T13:11:45.803465Z","end":"2026-04-07T13:11:46.026094Z","steps":["trace[740039411] 'agreement among raft nodes before linearized reading' (duration: 222.18884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:46.831022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.522662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:11:46.831109Z","caller":"traceutil/trace.go:171","msg":"trace[2128993592] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:8550; }","duration":"202.624606ms","start":"2026-04-07T13:11:46.628465Z","end":"2026-04-07T13:11:46.831089Z","steps":["trace[2128993592] 'count revisions from in-memory index tree' (duration: 202.41453ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:11:47.400844Z","caller":"traceutil/trace.go:171","msg":"trace[820144242] linearizableReadLoop","detail":"{readStateIndex:8860; appliedIndex:8859; }","duration":"113.150934ms","start":"2026-04-07T13:11:47.287667Z","end":"2026-04-07T13:11:47.400818Z","steps":["trace[820144242] 'read index received' (duration: 112.964429ms)","trace[820144242] 'applied index is now lower than readState.Index' (duration: 185.295µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T13:11:47.401036Z","caller":"traceutil/trace.go:171","msg":"trace[313392576] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"127.338813ms","start":"2026-04-07T13:11:47.273619Z","end":"2026-04-07T13:11:47.400958Z","steps":["trace[313392576] 'process raft request' (duration: 127.024654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T13:11:47.401068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.3862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T13:11:47.401218Z","caller":"traceutil/trace.go:171","msg":"trace[1112765136] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:8554; }","duration":"113.552485ms","start":"2026-04-07T13:11:47.287645Z","end":"2026-04-07T13:11:47.401197Z","steps":["trace[1112765136] 'agreement among raft nodes before linearized reading' (duration: 113.325158ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T13:12:32.584567Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002466.wal"} {"level":"info","ts":"2026-04-07T13:13:48.834865Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d98d5f2c752cc52d","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T13:13:48.840606Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T13:13:48.840817Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T13:15:43.840458Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7991} {"level":"info","ts":"2026-04-07T13:15:43.901546Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7991,"took":"58.138086ms","hash":1263401222,"current-db-size-bytes":57143296,"current-db-size":"57 MB","current-db-size-in-use-bytes":28991488,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T13:15:43.901665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1263401222,"revision":7991,"compact-revision":5908} {"level":"info","ts":"2026-04-07T13:20:43.847645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10552} {"level":"info","ts":"2026-04-07T13:20:43.926393Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10552,"took":"76.591611ms","hash":2785306485,"current-db-size-bytes":57143296,"current-db-size":"57 MB","current-db-size-in-use-bytes":20254720,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-07T13:20:43.926475Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2785306485,"revision":10552,"compact-revision":7991}