{"level":"warn","ts":"2026-07-15T09:41:11.132579Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T09:41:11.132698Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156: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.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-15T09:41:11.132774Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-15T09:41:11.132788Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-15T09:41:11.132814Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T09:41:11.13376Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-07-15T09:41:11.133856Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-15T09:41:11.137757Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.686203ms"} {"level":"info","ts":"2026-07-15T09:41:11.143141Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-07-15T09:41:11.143247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-07-15T09:41:11.143296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-07-15T09:41:11.143313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-15T09:41:11.143328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-07-15T09:41:11.143404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-07-15T09:41:11.1499Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-15T09:41:11.151189Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-15T09:41:11.152232Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-15T09:41:11.154225Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-15T09:41:11.154412Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-15T09:41:11.154446Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T09:41:11.154591Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T09:41:11.154617Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-15T09:41:11.158631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-07-15T09:41:11.158902Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-07-15T09:41:11.159487Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-15T09:41:11.159738Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-15T09:41:11.159772Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-15T09:41:11.159866Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-15T09:41:11.159888Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-07-15T09:41:11.44492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-07-15T09:41:11.445019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-15T09:41:11.445042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-07-15T09:41:11.445061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-07-15T09:41:11.445068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-15T09:41:11.44508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-07-15T09:41:11.445093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-07-15T09:41:11.447275Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-15T09:41:11.447388Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T09:41:11.447528Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-15T09:41:11.447604Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T09:41:11.447908Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-15T09:41:11.448022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-15T09:41:11.448388Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T09:41:11.448496Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T09:41:11.448554Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-15T09:41:11.450782Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-15T09:41:11.453011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"warn","ts":"2026-07-15T09:41:39.67416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.039878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T09:41:39.674302Z","caller":"traceutil/trace.go:171","msg":"trace[2075205314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:443; }","duration":"125.214033ms","start":"2026-07-15T09:41:39.549069Z","end":"2026-07-15T09:41:39.674283Z","steps":["trace[2075205314] 'range keys from in-memory index tree' (duration: 124.973686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:41:57.845978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.470412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:41:57.846149Z","caller":"traceutil/trace.go:171","msg":"trace[908452805] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"241.000609ms","start":"2026-07-15T09:41:57.605124Z","end":"2026-07-15T09:41:57.846125Z","steps":["trace[908452805] 'process raft request' (duration: 122.562723ms)","trace[908452805] 'compare' (duration: 117.257187ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T09:41:57.847589Z","caller":"traceutil/trace.go:171","msg":"trace[1120046256] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"239.964623ms","start":"2026-07-15T09:41:57.607607Z","end":"2026-07-15T09:41:57.847572Z","steps":["trace[1120046256] 'process raft request' (duration: 239.502622ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:41:57.847852Z","caller":"traceutil/trace.go:171","msg":"trace[358648853] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"240.129557ms","start":"2026-07-15T09:41:57.607696Z","end":"2026-07-15T09:41:57.847825Z","steps":["trace[358648853] 'process raft request' (duration: 239.531812ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:41:57.847879Z","caller":"traceutil/trace.go:171","msg":"trace[1328009885] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"225.838188ms","start":"2026-07-15T09:41:57.621984Z","end":"2026-07-15T09:41:57.847822Z","steps":["trace[1328009885] 'process raft request' (duration: 225.331925ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:42:02.93816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.510166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T09:42:02.938298Z","caller":"traceutil/trace.go:171","msg":"trace[677981476] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:647; }","duration":"150.70691ms","start":"2026-07-15T09:42:02.787563Z","end":"2026-07-15T09:42:02.93827Z","steps":["trace[677981476] 'range keys from in-memory index tree' (duration: 150.433174ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:42:03.479803Z","caller":"traceutil/trace.go:171","msg":"trace[620743670] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"115.878812ms","start":"2026-07-15T09:42:03.363909Z","end":"2026-07-15T09:42:03.479787Z","steps":["trace[620743670] 'process raft request' (duration: 115.236036ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:42:03.712995Z","caller":"traceutil/trace.go:171","msg":"trace[1483236146] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"165.531841ms","start":"2026-07-15T09:42:03.54743Z","end":"2026-07-15T09:42:03.712962Z","steps":["trace[1483236146] 'process raft request' (duration: 165.327005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:42:04.285898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.954807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T09:42:04.286029Z","caller":"traceutil/trace.go:171","msg":"trace[376390594] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"406.120721ms","start":"2026-07-15T09:42:03.879884Z","end":"2026-07-15T09:42:04.286004Z","steps":["trace[376390594] 'range keys from in-memory index tree' (duration: 405.761191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:42:04.286077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:42:03.879874Z","time spent":"406.192793ms","remote":"127.0.0.1:50796","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":"warn","ts":"2026-07-15T09:42:04.286176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.012728ms","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-07-15T09:42:04.286186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.805916ms","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-07-15T09:42:04.286284Z","caller":"traceutil/trace.go:171","msg":"trace[1702254651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"406.171182ms","start":"2026-07-15T09:42:03.880087Z","end":"2026-07-15T09:42:04.286258Z","steps":["trace[1702254651] 'range keys from in-memory index tree' (duration: 405.819672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:42:04.28634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:42:03.880081Z","time spent":"406.249154ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-15T09:42:04.286438Z","caller":"traceutil/trace.go:171","msg":"trace[1268775954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"409.96853ms","start":"2026-07-15T09:42:03.876309Z","end":"2026-07-15T09:42:04.286277Z","steps":["trace[1268775954] 'range keys from in-memory index tree' (duration: 409.484287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:42:04.286524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:42:03.876226Z","time spent":"410.282957ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-15T09:42:04.293943Z","caller":"traceutil/trace.go:171","msg":"trace[834789360] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"161.105324ms","start":"2026-07-15T09:42:04.132818Z","end":"2026-07-15T09:42:04.293923Z","steps":["trace[834789360] 'process raft request' (duration: 160.591991ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:42:18.895571Z","caller":"traceutil/trace.go:171","msg":"trace[286132003] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"162.376407ms","start":"2026-07-15T09:42:18.733164Z","end":"2026-07-15T09:42:18.89554Z","steps":["trace[286132003] 'process raft request' (duration: 162.197961ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:43:40.151464Z","caller":"traceutil/trace.go:171","msg":"trace[265685497] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1993; }","duration":"258.987398ms","start":"2026-07-15T09:43:39.892448Z","end":"2026-07-15T09:43:40.151435Z","steps":["trace[265685497] 'read index received' (duration: 160.774953ms)","trace[265685497] 'applied index is now lower than readState.Index' (duration: 98.210815ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:43:40.151589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.122372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T09:43:40.151675Z","caller":"traceutil/trace.go:171","msg":"trace[1949781436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1920; }","duration":"259.220954ms","start":"2026-07-15T09:43:39.892437Z","end":"2026-07-15T09:43:40.151657Z","steps":["trace[1949781436] 'agreement among raft nodes before linearized reading' (duration: 259.07135ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:43:40.232964Z","caller":"traceutil/trace.go:171","msg":"trace[1319267695] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"104.968016ms","start":"2026-07-15T09:43:40.127952Z","end":"2026-07-15T09:43:40.23292Z","steps":["trace[1319267695] 'process raft request' (duration: 104.666069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:43:40.233043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.285777ms","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-07-15T09:43:40.233155Z","caller":"traceutil/trace.go:171","msg":"trace[1090003666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1922; }","duration":"238.442641ms","start":"2026-07-15T09:43:39.994692Z","end":"2026-07-15T09:43:40.233134Z","steps":["trace[1090003666] 'agreement among raft nodes before linearized reading' (duration: 238.183144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:43:40.525185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.808924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:43:40.525302Z","caller":"traceutil/trace.go:171","msg":"trace[1327875197] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"286.271296ms","start":"2026-07-15T09:43:40.239011Z","end":"2026-07-15T09:43:40.525283Z","steps":["trace[1327875197] 'process raft request' (duration: 21.272217ms)","trace[1327875197] 'compare' (duration: 264.701092ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T09:43:40.530985Z","caller":"traceutil/trace.go:171","msg":"trace[1035929983] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"291.22919ms","start":"2026-07-15T09:43:40.239734Z","end":"2026-07-15T09:43:40.530963Z","steps":["trace[1035929983] 'process raft request' (duration: 291.036885ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:43:40.531238Z","caller":"traceutil/trace.go:171","msg":"trace[260327313] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"289.323952ms","start":"2026-07-15T09:43:40.241872Z","end":"2026-07-15T09:43:40.531196Z","steps":["trace[260327313] 'process raft request' (duration: 289.027964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:43:53.09283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.903311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T09:43:53.092915Z","caller":"traceutil/trace.go:171","msg":"trace[1225824768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2027; }","duration":"134.064096ms","start":"2026-07-15T09:43:52.958834Z","end":"2026-07-15T09:43:53.092898Z","steps":["trace[1225824768] 'range keys from in-memory index tree' (duration: 133.734936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:43:53.093669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.259066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-15T09:43:53.09366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.597947ms","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-07-15T09:43:53.093711Z","caller":"traceutil/trace.go:171","msg":"trace[1618320522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2027; }","duration":"114.359909ms","start":"2026-07-15T09:43:52.979342Z","end":"2026-07-15T09:43:53.093702Z","steps":["trace[1618320522] 'range keys from in-memory index tree' (duration: 114.094253ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:43:53.093731Z","caller":"traceutil/trace.go:171","msg":"trace[798735229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2027; }","duration":"122.715089ms","start":"2026-07-15T09:43:52.970999Z","end":"2026-07-15T09:43:53.093714Z","steps":["trace[798735229] 'range keys from in-memory index tree' (duration: 122.368651ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:43:56.41732Z","caller":"traceutil/trace.go:171","msg":"trace[1030659582] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"150.399905ms","start":"2026-07-15T09:43:56.266893Z","end":"2026-07-15T09:43:56.417293Z","steps":["trace[1030659582] 'process raft request' (duration: 150.22221ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:44:19.421012Z","caller":"traceutil/trace.go:171","msg":"trace[1023451180] linearizableReadLoop","detail":"{readStateIndex:2271; appliedIndex:2270; }","duration":"101.927182ms","start":"2026-07-15T09:44:19.319061Z","end":"2026-07-15T09:44:19.420988Z","steps":["trace[1023451180] 'read index received' (duration: 101.723547ms)","trace[1023451180] 'applied index is now lower than readState.Index' (duration: 202.625µs)"],"step_count":2} {"level":"info","ts":"2026-07-15T09:44:19.421071Z","caller":"traceutil/trace.go:171","msg":"trace[1073125356] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"104.389734ms","start":"2026-07-15T09:44:19.316648Z","end":"2026-07-15T09:44:19.421038Z","steps":["trace[1073125356] 'process raft request' (duration: 104.182439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:44:19.421204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.120356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-15T09:44:19.421248Z","caller":"traceutil/trace.go:171","msg":"trace[508546520] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2188; }","duration":"102.213829ms","start":"2026-07-15T09:44:19.319023Z","end":"2026-07-15T09:44:19.421237Z","steps":["trace[508546520] 'agreement among raft nodes before linearized reading' (duration: 102.062325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:44:20.081835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.163919ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-15T09:44:20.081998Z","caller":"traceutil/trace.go:171","msg":"trace[1024245827] linearizableReadLoop","detail":"{readStateIndex:2273; appliedIndex:2272; }","duration":"187.618635ms","start":"2026-07-15T09:44:19.894352Z","end":"2026-07-15T09:44:20.081971Z","steps":["trace[1024245827] 'read index received' (duration: 58.261µs)","trace[1024245827] 'applied index is now lower than readState.Index' (duration: 187.557564ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:44:20.08216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.795831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-15T09:44:20.082207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.418954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T09:44:20.082228Z","caller":"traceutil/trace.go:171","msg":"trace[1998910065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2189; }","duration":"187.915154ms","start":"2026-07-15T09:44:19.89429Z","end":"2026-07-15T09:44:20.082205Z","steps":["trace[1998910065] 'agreement among raft nodes before linearized reading' (duration: 187.78275ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:44:20.082251Z","caller":"traceutil/trace.go:171","msg":"trace[856371230] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2189; }","duration":"135.504356ms","start":"2026-07-15T09:44:19.946732Z","end":"2026-07-15T09:44:20.082237Z","steps":["trace[856371230] 'agreement among raft nodes before linearized reading' (duration: 135.407974ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:44:20.474095Z","caller":"traceutil/trace.go:171","msg":"trace[932793913] transaction","detail":"{read_only:false; response_revision:2190; number_of_response:1; }","duration":"165.806418ms","start":"2026-07-15T09:44:20.308258Z","end":"2026-07-15T09:44:20.474064Z","steps":["trace[932793913] 'process raft request' (duration: 165.48752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:44:20.650164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.739938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:44:20.650265Z","caller":"traceutil/trace.go:171","msg":"trace[416259593] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"150.051631ms","start":"2026-07-15T09:44:20.500197Z","end":"2026-07-15T09:44:20.650248Z","steps":["trace[416259593] 'compare' (duration: 146.45235ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:45:13.19569Z","caller":"traceutil/trace.go:171","msg":"trace[1635680927] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"186.94336ms","start":"2026-07-15T09:45:13.008722Z","end":"2026-07-15T09:45:13.195665Z","steps":["trace[1635680927] 'process raft request' (duration: 186.774776ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:45:13.209051Z","caller":"traceutil/trace.go:171","msg":"trace[569623093] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"197.068724ms","start":"2026-07-15T09:45:13.011958Z","end":"2026-07-15T09:45:13.209027Z","steps":["trace[569623093] 'process raft request' (duration: 196.010548ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:45:43.683549Z","caller":"traceutil/trace.go:171","msg":"trace[904625404] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"111.170759ms","start":"2026-07-15T09:45:43.572352Z","end":"2026-07-15T09:45:43.683523Z","steps":["trace[904625404] 'process raft request' (duration: 106.384019ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:45:49.291719Z","caller":"traceutil/trace.go:171","msg":"trace[371814851] transaction","detail":"{read_only:false; response_revision:2778; number_of_response:1; }","duration":"183.082ms","start":"2026-07-15T09:45:49.108604Z","end":"2026-07-15T09:45:49.291686Z","steps":["trace[371814851] 'process raft request' (duration: 182.882196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:49:40.950268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.8459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:49:40.950413Z","caller":"traceutil/trace.go:171","msg":"trace[1478044183] linearizableReadLoop","detail":"{readStateIndex:4956; appliedIndex:4955; }","duration":"282.405905ms","start":"2026-07-15T09:49:40.667988Z","end":"2026-07-15T09:49:40.950394Z","steps":["trace[1478044183] 'read index received' (duration: 125.247398ms)","trace[1478044183] 'applied index is now lower than readState.Index' (duration: 157.156577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:49:40.950737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.814075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T09:49:40.950822Z","caller":"traceutil/trace.go:171","msg":"trace[2057048544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4773; }","duration":"246.959818ms","start":"2026-07-15T09:49:40.703845Z","end":"2026-07-15T09:49:40.950805Z","steps":["trace[2057048544] 'agreement among raft nodes before linearized reading' (duration: 246.804265ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:49:40.950833Z","caller":"traceutil/trace.go:171","msg":"trace[940945505] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"313.015414ms","start":"2026-07-15T09:49:40.637765Z","end":"2026-07-15T09:49:40.95078Z","steps":["trace[940945505] 'process raft request' (duration: 155.530207ms)","trace[940945505] 'compare' (duration: 156.536933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:49:40.951015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.00162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6a5cc705-103e-472d-bcee-2a1a6db029b1\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-07-15T09:49:40.951088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:49:40.637728Z","time spent":"313.224738ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T09:49:40.951105Z","caller":"traceutil/trace.go:171","msg":"trace[1803384086] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6a5cc705-103e-472d-bcee-2a1a6db029b1; range_end:; response_count:1; response_revision:4773; }","duration":"283.144884ms","start":"2026-07-15T09:49:40.667939Z","end":"2026-07-15T09:49:40.951084Z","steps":["trace[1803384086] 'agreement among raft nodes before linearized reading' (duration: 282.891658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:49:41.278856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.831701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:49:41.279349Z","caller":"traceutil/trace.go:171","msg":"trace[577547955] transaction","detail":"{read_only:false; response_revision:4786; number_of_response:1; }","duration":"145.610679ms","start":"2026-07-15T09:49:41.13371Z","end":"2026-07-15T09:49:41.279321Z","steps":["trace[577547955] 'process raft request' (duration: 145.478536ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:49:41.27937Z","caller":"traceutil/trace.go:171","msg":"trace[544941818] transaction","detail":"{read_only:false; response_revision:4785; number_of_response:1; }","duration":"146.785778ms","start":"2026-07-15T09:49:41.132493Z","end":"2026-07-15T09:49:41.279279Z","steps":["trace[544941818] 'process raft request' (duration: 146.591722ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:49:41.280441Z","caller":"traceutil/trace.go:171","msg":"trace[68105487] transaction","detail":"{read_only:false; response_revision:4787; number_of_response:1; }","duration":"146.09574ms","start":"2026-07-15T09:49:41.134319Z","end":"2026-07-15T09:49:41.280415Z","steps":["trace[68105487] 'process raft request' (duration: 144.922422ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:49:41.281002Z","caller":"traceutil/trace.go:171","msg":"trace[393352755] transaction","detail":"{read_only:false; response_revision:4784; number_of_response:1; }","duration":"148.971552ms","start":"2026-07-15T09:49:41.131992Z","end":"2026-07-15T09:49:41.280963Z","steps":["trace[393352755] 'process raft request' (duration: 44.895315ms)","trace[393352755] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/endpointslices/monitoring/kube-prometheus-stack-alertmanager-x6c4z; req_size:1865; } (duration: 101.565675ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T09:49:55.508506Z","caller":"traceutil/trace.go:171","msg":"trace[1146880856] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"143.150627ms","start":"2026-07-15T09:49:55.365313Z","end":"2026-07-15T09:49:55.508464Z","steps":["trace[1146880856] 'process raft request' (duration: 142.891701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:49:55.782947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.584268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:49:55.78319Z","caller":"traceutil/trace.go:171","msg":"trace[1805340631] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"417.609475ms","start":"2026-07-15T09:49:55.365462Z","end":"2026-07-15T09:49:55.783071Z","steps":["trace[1805340631] 'process raft request' (duration: 261.840331ms)","trace[1805340631] 'compare' (duration: 155.485926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:49:55.783418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:49:55.365439Z","time spent":"417.856491ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T09:51:11.690121Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2899} {"level":"info","ts":"2026-07-15T09:51:11.769028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2899,"took":"77.666767ms","hash":4066837251,"current-db-size-bytes":53436416,"current-db-size":"53 MB","current-db-size-in-use-bytes":29437952,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-15T09:51:11.769094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4066837251,"revision":2899,"compact-revision":-1} {"level":"info","ts":"2026-07-15T09:53:27.657245Z","caller":"traceutil/trace.go:171","msg":"trace[1385045771] transaction","detail":"{read_only:false; response_revision:6484; number_of_response:1; }","duration":"448.998ms","start":"2026-07-15T09:53:27.208202Z","end":"2026-07-15T09:53:27.6572Z","steps":["trace[1385045771] 'process raft request' (duration: 448.688571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:27.657612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:27.208158Z","time spent":"449.229695ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T09:53:27.658225Z","caller":"traceutil/trace.go:171","msg":"trace[704323280] transaction","detail":"{read_only:false; response_revision:6485; number_of_response:1; }","duration":"449.553323ms","start":"2026-07-15T09:53:27.208644Z","end":"2026-07-15T09:53:27.658197Z","steps":["trace[704323280] 'process raft request' (duration: 449.049691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:27.658483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:27.208611Z","time spent":"449.696578ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T09:53:27.658915Z","caller":"traceutil/trace.go:171","msg":"trace[101940942] transaction","detail":"{read_only:false; response_revision:6486; number_of_response:1; }","duration":"286.407066ms","start":"2026-07-15T09:53:27.372465Z","end":"2026-07-15T09:53:27.658872Z","steps":["trace[101940942] 'process raft request' (duration: 285.554725ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:53:28.288663Z","caller":"traceutil/trace.go:171","msg":"trace[403239429] linearizableReadLoop","detail":"{readStateIndex:6733; appliedIndex:6732; }","duration":"138.238273ms","start":"2026-07-15T09:53:28.15039Z","end":"2026-07-15T09:53:28.288629Z","steps":["trace[403239429] 'read index received' (duration: 137.937376ms)","trace[403239429] 'applied index is now lower than readState.Index' (duration: 299.707µs)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:53:28.288879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.46745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-15T09:53:28.288918Z","caller":"traceutil/trace.go:171","msg":"trace[2080876805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6493; }","duration":"138.598103ms","start":"2026-07-15T09:53:28.150308Z","end":"2026-07-15T09:53:28.288906Z","steps":["trace[2080876805] 'agreement among raft nodes before linearized reading' (duration: 138.446209ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:53:28.288885Z","caller":"traceutil/trace.go:171","msg":"trace[872923840] transaction","detail":"{read_only:false; response_revision:6493; number_of_response:1; }","duration":"261.470302ms","start":"2026-07-15T09:53:28.027366Z","end":"2026-07-15T09:53:28.288836Z","steps":["trace[872923840] 'process raft request' (duration: 261.044011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:28.854272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.027024ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-15T09:53:28.854497Z","caller":"traceutil/trace.go:171","msg":"trace[967873449] transaction","detail":"{read_only:false; response_revision:6494; number_of_response:1; }","duration":"559.412626ms","start":"2026-07-15T09:53:28.295046Z","end":"2026-07-15T09:53:28.854458Z","steps":["trace[967873449] 'process raft request' (duration: 149.082035ms)","trace[967873449] 'compare' (duration: 409.346697ms)"],"step_count":2} {"level":"info","ts":"2026-07-15T09:53:28.854639Z","caller":"traceutil/trace.go:171","msg":"trace[954576223] transaction","detail":"{read_only:false; response_revision:6495; number_of_response:1; }","duration":"234.666949ms","start":"2026-07-15T09:53:28.619948Z","end":"2026-07-15T09:53:28.854615Z","steps":["trace[954576223] 'process raft request' (duration: 234.458844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:28.854696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:28.295015Z","time spent":"559.58585ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-15T09:53:28.854839Z","caller":"traceutil/trace.go:171","msg":"trace[300614042] linearizableReadLoop","detail":"{readStateIndex:6734; appliedIndex:6733; }","duration":"499.337582ms","start":"2026-07-15T09:53:28.355472Z","end":"2026-07-15T09:53:28.85481Z","steps":["trace[300614042] 'read index received' (duration: 88.556319ms)","trace[300614042] 'applied index is now lower than readState.Index' (duration: 410.778913ms)"],"step_count":2} {"level":"warn","ts":"2026-07-15T09:53:28.855069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.591928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T09:53:28.855103Z","caller":"traceutil/trace.go:171","msg":"trace[1959529325] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6495; }","duration":"499.68753ms","start":"2026-07-15T09:53:28.355406Z","end":"2026-07-15T09:53:28.855094Z","steps":["trace[1959529325] 'agreement among raft nodes before linearized reading' (duration: 499.557437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:28.855136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:28.355381Z","time spent":"499.747511ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-07-15T09:53:28.855371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.84269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T09:53:28.855417Z","caller":"traceutil/trace.go:171","msg":"trace[915566841] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6495; }","duration":"419.940683ms","start":"2026-07-15T09:53:28.435463Z","end":"2026-07-15T09:53:28.855404Z","steps":["trace[915566841] 'agreement among raft nodes before linearized reading' (duration: 419.872431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:28.85545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:28.435435Z","time spent":"420.006894ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-07-15T09:53:28.855527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.352604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-init-g4dmj\" ","response":"range_response_count:1 size:6319"} {"level":"warn","ts":"2026-07-15T09:53:28.855576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.639648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-15T09:53:28.85563Z","caller":"traceutil/trace.go:171","msg":"trace[1186840815] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6495; }","duration":"301.738091ms","start":"2026-07-15T09:53:28.553878Z","end":"2026-07-15T09:53:28.855616Z","steps":["trace[1186840815] 'agreement among raft nodes before linearized reading' (duration: 301.649889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:28.855647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.172886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-15T09:53:28.855663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:28.553855Z","time spent":"301.800282ms","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/horizon-db-sync\" "} {"level":"warn","ts":"2026-07-15T09:53:28.855662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.706843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-15T09:53:28.855677Z","caller":"traceutil/trace.go:171","msg":"trace[1355130147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6495; }","duration":"309.249418ms","start":"2026-07-15T09:53:28.546418Z","end":"2026-07-15T09:53:28.855668Z","steps":["trace[1355130147] 'agreement among raft nodes before linearized reading' (duration: 309.163496ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:53:28.855584Z","caller":"traceutil/trace.go:171","msg":"trace[1096281522] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-init-g4dmj; range_end:; response_count:1; response_revision:6495; }","duration":"260.434016ms","start":"2026-07-15T09:53:28.595133Z","end":"2026-07-15T09:53:28.855567Z","steps":["trace[1096281522] 'agreement among raft nodes before linearized reading' (duration: 260.230411ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:53:28.855703Z","caller":"traceutil/trace.go:171","msg":"trace[1638231158] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:6495; }","duration":"104.780535ms","start":"2026-07-15T09:53:28.750911Z","end":"2026-07-15T09:53:28.855692Z","steps":["trace[1638231158] 'agreement among raft nodes before linearized reading' (duration: 104.701963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-15T09:53:28.855705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-15T09:53:28.54639Z","time spent":"309.30919ms","remote":"127.0.0.1:50796","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-07-15T09:53:47.184248Z","caller":"traceutil/trace.go:171","msg":"trace[31386114] transaction","detail":"{read_only:false; response_revision:6617; number_of_response:1; }","duration":"128.478049ms","start":"2026-07-15T09:53:47.055727Z","end":"2026-07-15T09:53:47.184205Z","steps":["trace[31386114] 'process raft request' (duration: 128.351445ms)"],"step_count":1} {"level":"info","ts":"2026-07-15T09:56:11.697648Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5574} {"level":"info","ts":"2026-07-15T09:56:11.797447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5574,"took":"97.383764ms","hash":249561969,"current-db-size-bytes":53436416,"current-db-size":"53 MB","current-db-size-in-use-bytes":18558976,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-15T09:56:11.797518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":249561969,"revision":5574,"compact-revision":2899}