{"level":"warn","ts":"2026-07-27T20:05:18.416593Z","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-27T20:05:18.41671Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-27T20:05:18.416807Z","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-27T20:05:18.41682Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-27T20:05:18.416845Z","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-27T20:05:18.417688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-07-27T20:05:18.417789Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-27T20:05:18.420429Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.327128ms"} {"level":"info","ts":"2026-07-27T20:05:18.426938Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-07-27T20:05:18.427052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-07-27T20:05:18.427081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-07-27T20:05:18.427091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-27T20:05:18.427103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-07-27T20:05:18.427145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-07-27T20:05:18.431933Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-27T20:05:18.43344Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-27T20:05:18.435755Z","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-27T20:05:18.437686Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-27T20:05:18.439778Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-27T20:05:18.439824Z","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-27T20:05:18.43994Z","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-27T20:05:18.439985Z","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-27T20:05:18.440731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-07-27T20:05:18.440847Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-07-27T20:05:18.442125Z","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-27T20:05:18.442227Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-27T20:05:18.442294Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-07-27T20:05:18.442366Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-27T20:05:18.442419Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-27T20:05:19.327601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-07-27T20:05:19.327671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-27T20:05:19.327687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-07-27T20:05:19.327701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-07-27T20:05:19.327708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-27T20:05:19.327718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-07-27T20:05:19.327727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-07-27T20:05:19.337741Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:19.33817Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-07-27T20:05:19.338183Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:05:19.338198Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-27T20:05:19.338719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-27T20:05:19.338814Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-27T20:05:19.340622Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-27T20:05:19.344466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-07-27T20:05:19.344734Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:19.344923Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:19.344997Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-27T20:05:31.164237Z","caller":"traceutil/trace.go:171","msg":"trace[198731382] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"146.999919ms","start":"2026-07-27T20:05:31.017215Z","end":"2026-07-27T20:05:31.164214Z","steps":["trace[198731382] 'process raft request' (duration: 132.357975ms)","trace[198731382] 'compare' (duration: 14.530281ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:05:31.444261Z","caller":"traceutil/trace.go:171","msg":"trace[1269092718] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"274.249927ms","start":"2026-07-27T20:05:31.169986Z","end":"2026-07-27T20:05:31.444236Z","steps":["trace[1269092718] 'process raft request' (duration: 271.042234ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:05:31.641518Z","caller":"traceutil/trace.go:171","msg":"trace[1534421937] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"466.93096ms","start":"2026-07-27T20:05:31.174562Z","end":"2026-07-27T20:05:31.641493Z","steps":["trace[1534421937] 'process raft request' (duration: 427.067034ms)","trace[1534421937] 'compare' (duration: 39.708272ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:05:31.642024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:05:31.174542Z","time spent":"467.037124ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:06:01.910982Z","caller":"traceutil/trace.go:171","msg":"trace[1026455251] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"142.624813ms","start":"2026-07-27T20:06:01.768303Z","end":"2026-07-27T20:06:01.910928Z","steps":["trace[1026455251] 'process raft request' (duration: 142.427027ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:02.765679Z","caller":"traceutil/trace.go:171","msg":"trace[1893688019] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"408.4772ms","start":"2026-07-27T20:06:02.357171Z","end":"2026-07-27T20:06:02.765648Z","steps":["trace[1893688019] 'read index received' (duration: 407.671536ms)","trace[1893688019] 'applied index is now lower than readState.Index' (duration: 804.844µs)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:06:02.765886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.649414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-27T20:06:02.765967Z","caller":"traceutil/trace.go:171","msg":"trace[657331533] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:633; }","duration":"408.803619ms","start":"2026-07-27T20:06:02.357148Z","end":"2026-07-27T20:06:02.765952Z","steps":["trace[657331533] 'agreement among raft nodes before linearized reading' (duration: 408.634845ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:02.765868Z","caller":"traceutil/trace.go:171","msg":"trace[56634056] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"421.811826ms","start":"2026-07-27T20:06:02.344024Z","end":"2026-07-27T20:06:02.765836Z","steps":["trace[56634056] 'process raft request' (duration: 421.052204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:06:02.766012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:06:02.357134Z","time spent":"408.866421ms","remote":"127.0.0.1:56608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.222\" "} {"level":"warn","ts":"2026-07-27T20:06:02.766116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.238832ms","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-27T20:06:02.766207Z","caller":"traceutil/trace.go:171","msg":"trace[321577888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"195.388406ms","start":"2026-07-27T20:06:02.570797Z","end":"2026-07-27T20:06:02.766185Z","steps":["trace[321577888] 'agreement among raft nodes before linearized reading' (duration: 195.094128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:06:02.766147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:06:02.344Z","time spent":"422.066684ms","remote":"127.0.0.1:56660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:06:02.88505Z","caller":"traceutil/trace.go:171","msg":"trace[1927662882] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"114.968855ms","start":"2026-07-27T20:06:02.770057Z","end":"2026-07-27T20:06:02.885026Z","steps":["trace[1927662882] 'process raft request' (duration: 114.7792ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:06:02.885122Z","caller":"traceutil/trace.go:171","msg":"trace[2083148892] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"114.249444ms","start":"2026-07-27T20:06:02.770835Z","end":"2026-07-27T20:06:02.885085Z","steps":["trace[2083148892] 'process raft request' (duration: 114.11807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:07:27.905056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.390397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:07:27.905164Z","caller":"traceutil/trace.go:171","msg":"trace[1251542605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1837; }","duration":"114.556312ms","start":"2026-07-27T20:07:27.790584Z","end":"2026-07-27T20:07:27.905141Z","steps":["trace[1251542605] 'range keys from in-memory index tree' (duration: 114.303104ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:07:28.117276Z","caller":"traceutil/trace.go:171","msg":"trace[1173858515] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"164.0692ms","start":"2026-07-27T20:07:27.953178Z","end":"2026-07-27T20:07:28.117247Z","steps":["trace[1173858515] 'process raft request' (duration: 163.860134ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:07:28.454327Z","caller":"traceutil/trace.go:171","msg":"trace[1286071482] transaction","detail":"{read_only:false; response_revision:1839; number_of_response:1; }","duration":"111.474752ms","start":"2026-07-27T20:07:28.342641Z","end":"2026-07-27T20:07:28.454116Z","steps":["trace[1286071482] 'process raft request' (duration: 111.321567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:07.975619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.286488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-07-27T20:08:07.975707Z","caller":"traceutil/trace.go:171","msg":"trace[2107487003] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2113; }","duration":"122.424971ms","start":"2026-07-27T20:08:07.853268Z","end":"2026-07-27T20:08:07.975693Z","steps":["trace[2107487003] 'range keys from in-memory index tree' (duration: 122.105522ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:13.743088Z","caller":"traceutil/trace.go:171","msg":"trace[716661086] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"133.201857ms","start":"2026-07-27T20:08:13.609864Z","end":"2026-07-27T20:08:13.743066Z","steps":["trace[716661086] 'read index received' (duration: 133.006831ms)","trace[716661086] 'applied index is now lower than readState.Index' (duration: 194.176µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:08:13.743171Z","caller":"traceutil/trace.go:171","msg":"trace[1562824036] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"152.173133ms","start":"2026-07-27T20:08:13.59096Z","end":"2026-07-27T20:08:13.743133Z","steps":["trace[1562824036] 'process raft request' (duration: 151.973027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:08:13.743277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.390782ms","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-07-27T20:08:13.743312Z","caller":"traceutil/trace.go:171","msg":"trace[761097807] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2142; }","duration":"133.471295ms","start":"2026-07-27T20:08:13.609829Z","end":"2026-07-27T20:08:13.7433Z","steps":["trace[761097807] 'agreement among raft nodes before linearized reading' (duration: 133.330531ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:13.962769Z","caller":"traceutil/trace.go:171","msg":"trace[1742820095] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"192.976186ms","start":"2026-07-27T20:08:13.769737Z","end":"2026-07-27T20:08:13.962713Z","steps":["trace[1742820095] 'process raft request' (duration: 160.333051ms)","trace[1742820095] 'compare' (duration: 32.272285ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:08:13.96291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.937906ms","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-27T20:08:13.963009Z","caller":"traceutil/trace.go:171","msg":"trace[2106637615] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2143; }","duration":"192.07998ms","start":"2026-07-27T20:08:13.770916Z","end":"2026-07-27T20:08:13.962996Z","steps":["trace[2106637615] 'agreement among raft nodes before linearized reading' (duration: 191.856694ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:08:13.962507Z","caller":"traceutil/trace.go:171","msg":"trace[348963490] linearizableReadLoop","detail":"{readStateIndex:2222; appliedIndex:2221; }","duration":"191.517045ms","start":"2026-07-27T20:08:13.770963Z","end":"2026-07-27T20:08:13.96248Z","steps":["trace[348963490] 'read index received' (duration: 159.160728ms)","trace[348963490] 'applied index is now lower than readState.Index' (duration: 32.355376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:08:13.96325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.820106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:08:13.963289Z","caller":"traceutil/trace.go:171","msg":"trace[1789646710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2143; }","duration":"173.910239ms","start":"2026-07-27T20:08:13.789369Z","end":"2026-07-27T20:08:13.963279Z","steps":["trace[1789646710] 'agreement among raft nodes before linearized reading' (duration: 173.828126ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:42.216062Z","caller":"traceutil/trace.go:171","msg":"trace[873771502] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"201.307925ms","start":"2026-07-27T20:09:42.014721Z","end":"2026-07-27T20:09:42.216029Z","steps":["trace[873771502] 'process raft request' (duration: 121.551234ms)","trace[873771502] 'compare' (duration: 79.556615ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:09:42.216424Z","caller":"traceutil/trace.go:171","msg":"trace[1501154226] transaction","detail":"{read_only:false; response_revision:2815; number_of_response:1; }","duration":"153.861729ms","start":"2026-07-27T20:09:42.062531Z","end":"2026-07-27T20:09:42.216393Z","steps":["trace[1501154226] 'process raft request' (duration: 153.802027ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:09:42.216516Z","caller":"traceutil/trace.go:171","msg":"trace[2072349241] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"201.204663ms","start":"2026-07-27T20:09:42.015283Z","end":"2026-07-27T20:09:42.216487Z","steps":["trace[2072349241] 'process raft request' (duration: 200.900474ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:07.082302Z","caller":"traceutil/trace.go:171","msg":"trace[932066236] transaction","detail":"{read_only:false; response_revision:3777; number_of_response:1; }","duration":"142.594484ms","start":"2026-07-27T20:12:06.93968Z","end":"2026-07-27T20:12:07.082275Z","steps":["trace[932066236] 'process raft request' (duration: 142.417309ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:07.184037Z","caller":"traceutil/trace.go:171","msg":"trace[2052168852] transaction","detail":"{read_only:false; response_revision:3778; number_of_response:1; }","duration":"243.870291ms","start":"2026-07-27T20:12:06.940127Z","end":"2026-07-27T20:12:07.183998Z","steps":["trace[2052168852] 'process raft request' (duration: 243.642845ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:12:07.184053Z","caller":"traceutil/trace.go:171","msg":"trace[2046163025] transaction","detail":"{read_only:false; response_revision:3779; number_of_response:1; }","duration":"119.247563ms","start":"2026-07-27T20:12:07.064779Z","end":"2026-07-27T20:12:07.184027Z","steps":["trace[2046163025] 'process raft request' (duration: 119.13921ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:35.269663Z","caller":"traceutil/trace.go:171","msg":"trace[1412774481] linearizableReadLoop","detail":"{readStateIndex:5167; appliedIndex:5166; }","duration":"180.324253ms","start":"2026-07-27T20:13:35.089293Z","end":"2026-07-27T20:13:35.269617Z","steps":["trace[1412774481] 'read index received' (duration: 132.502897ms)","trace[1412774481] 'applied index is now lower than readState.Index' (duration: 47.819456ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:35.269697Z","caller":"traceutil/trace.go:171","msg":"trace[1438746268] transaction","detail":"{read_only:false; response_revision:4979; number_of_response:1; }","duration":"186.738262ms","start":"2026-07-27T20:13:35.082923Z","end":"2026-07-27T20:13:35.269661Z","steps":["trace[1438746268] 'process raft request' (duration: 138.921296ms)","trace[1438746268] 'compare' (duration: 47.549348ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:13:35.269942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.639743ms","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-07-27T20:13:35.270009Z","caller":"traceutil/trace.go:171","msg":"trace[696587151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4979; }","duration":"180.745536ms","start":"2026-07-27T20:13:35.089248Z","end":"2026-07-27T20:13:35.269993Z","steps":["trace[696587151] 'agreement among raft nodes before linearized reading' (duration: 180.505129ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:35.414152Z","caller":"traceutil/trace.go:171","msg":"trace[1833268135] linearizableReadLoop","detail":"{readStateIndex:5168; appliedIndex:5167; }","duration":"100.965357ms","start":"2026-07-27T20:13:35.313158Z","end":"2026-07-27T20:13:35.414123Z","steps":["trace[1833268135] 'read index received' (duration: 69.246451ms)","trace[1833268135] 'applied index is now lower than readState.Index' (duration: 31.717726ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:35.414283Z","caller":"traceutil/trace.go:171","msg":"trace[1039692050] transaction","detail":"{read_only:false; response_revision:4980; number_of_response:1; }","duration":"140.270235ms","start":"2026-07-27T20:13:35.273976Z","end":"2026-07-27T20:13:35.414246Z","steps":["trace[1039692050] 'process raft request' (duration: 108.479676ms)","trace[1039692050] 'compare' (duration: 31.530691ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:13:35.414393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.216045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-52fd5979-55ea-401d-b716-7c6835a9a40f\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-07-27T20:13:35.414442Z","caller":"traceutil/trace.go:171","msg":"trace[1581559419] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-52fd5979-55ea-401d-b716-7c6835a9a40f; range_end:; response_count:1; response_revision:4980; }","duration":"101.325188ms","start":"2026-07-27T20:13:35.313101Z","end":"2026-07-27T20:13:35.414426Z","steps":["trace[1581559419] 'agreement among raft nodes before linearized reading' (duration: 101.129572ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:35.805024Z","caller":"traceutil/trace.go:171","msg":"trace[45912082] linearizableReadLoop","detail":"{readStateIndex:5172; appliedIndex:5171; }","duration":"189.821402ms","start":"2026-07-27T20:13:35.615163Z","end":"2026-07-27T20:13:35.804985Z","steps":["trace[45912082] 'read index received' (duration: 128.573564ms)","trace[45912082] 'applied index is now lower than readState.Index' (duration: 61.246328ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:35.805184Z","caller":"traceutil/trace.go:171","msg":"trace[1447821941] transaction","detail":"{read_only:false; response_revision:4984; number_of_response:1; }","duration":"198.241906ms","start":"2026-07-27T20:13:35.606899Z","end":"2026-07-27T20:13:35.805141Z","steps":["trace[1447821941] 'process raft request' (duration: 136.804133ms)","trace[1447821941] 'compare' (duration: 61.097184ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:13:35.805315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.12238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-27T20:13:35.805367Z","caller":"traceutil/trace.go:171","msg":"trace[1897547065] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4984; }","duration":"190.233673ms","start":"2026-07-27T20:13:35.61512Z","end":"2026-07-27T20:13:35.805354Z","steps":["trace[1897547065] 'agreement among raft nodes before linearized reading' (duration: 190.013817ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:13:47.617877Z","caller":"traceutil/trace.go:171","msg":"trace[214218591] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5418; }","duration":"145.917489ms","start":"2026-07-27T20:13:47.471934Z","end":"2026-07-27T20:13:47.617852Z","steps":["trace[214218591] 'read index received' (duration: 145.674872ms)","trace[214218591] 'applied index is now lower than readState.Index' (duration: 241.437µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:13:47.61794Z","caller":"traceutil/trace.go:171","msg":"trace[984517598] transaction","detail":"{read_only:false; response_revision:5224; number_of_response:1; }","duration":"181.461672ms","start":"2026-07-27T20:13:47.436436Z","end":"2026-07-27T20:13:47.617898Z","steps":["trace[984517598] 'process raft request' (duration: 181.170614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:47.618924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.97428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-27T20:13:47.618996Z","caller":"traceutil/trace.go:171","msg":"trace[669470833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5224; }","duration":"147.091023ms","start":"2026-07-27T20:13:47.471885Z","end":"2026-07-27T20:13:47.618976Z","steps":["trace[669470833] 'agreement among raft nodes before linearized reading' (duration: 146.130316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:13:54.826867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.633271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-27T20:13:54.826953Z","caller":"traceutil/trace.go:171","msg":"trace[1899804259] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5413; }","duration":"208.752544ms","start":"2026-07-27T20:13:54.618182Z","end":"2026-07-27T20:13:54.826935Z","steps":["trace[1899804259] 'range keys from in-memory index tree' (duration: 207.958001ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:04.820113Z","caller":"traceutil/trace.go:171","msg":"trace[1377794425] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"140.720309ms","start":"2026-07-27T20:14:04.679366Z","end":"2026-07-27T20:14:04.820086Z","steps":["trace[1377794425] 'process raft request' (duration: 138.553976ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:04.820423Z","caller":"traceutil/trace.go:171","msg":"trace[2124290890] transaction","detail":"{read_only:false; response_revision:5534; number_of_response:1; }","duration":"141.006948ms","start":"2026-07-27T20:14:04.679396Z","end":"2026-07-27T20:14:04.820403Z","steps":["trace[2124290890] 'process raft request' (duration: 140.583815ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:14:04.820891Z","caller":"traceutil/trace.go:171","msg":"trace[1150144976] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"141.038198ms","start":"2026-07-27T20:14:04.679828Z","end":"2026-07-27T20:14:04.820866Z","steps":["trace[1150144976] 'process raft request' (duration: 140.658097ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:15:19.366692Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3019} {"level":"info","ts":"2026-07-27T20:15:19.448782Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3019,"took":"80.144589ms","hash":4208066788,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":32268288,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T20:15:19.448852Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4208066788,"revision":3019,"compact-revision":-1} {"level":"info","ts":"2026-07-27T20:20:02.058283Z","caller":"traceutil/trace.go:171","msg":"trace[1194076116] linearizableReadLoop","detail":"{readStateIndex:8510; appliedIndex:8509; }","duration":"270.249475ms","start":"2026-07-27T20:20:01.788005Z","end":"2026-07-27T20:20:02.058255Z","steps":["trace[1194076116] 'read index received' (duration: 174.521039ms)","trace[1194076116] 'applied index is now lower than readState.Index' (duration: 95.727176ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:02.0584Z","caller":"traceutil/trace.go:171","msg":"trace[427717980] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"343.840177ms","start":"2026-07-27T20:20:01.714512Z","end":"2026-07-27T20:20:02.058352Z","steps":["trace[427717980] 'process raft request' (duration: 248.149282ms)","trace[427717980] 'compare' (duration: 95.106418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:02.058511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.489622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:20:02.058612Z","caller":"traceutil/trace.go:171","msg":"trace[130831910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8222; }","duration":"270.627536ms","start":"2026-07-27T20:20:01.787967Z","end":"2026-07-27T20:20:02.058594Z","steps":["trace[130831910] 'agreement among raft nodes before linearized reading' (duration: 270.4142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:02.058615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:20:01.714483Z","time spent":"344.005822ms","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-27T20:20:02.778407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.655424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:20:02.778539Z","caller":"traceutil/trace.go:171","msg":"trace[523150754] transaction","detail":"{read_only:false; response_revision:8226; number_of_response:1; }","duration":"238.603404ms","start":"2026-07-27T20:20:02.539917Z","end":"2026-07-27T20:20:02.77852Z","steps":["trace[523150754] 'process raft request' (duration: 131.211369ms)","trace[523150754] 'compare' (duration: 106.539451ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:02.784194Z","caller":"traceutil/trace.go:171","msg":"trace[740893876] transaction","detail":"{read_only:false; response_revision:8227; number_of_response:1; }","duration":"240.820699ms","start":"2026-07-27T20:20:02.543334Z","end":"2026-07-27T20:20:02.784155Z","steps":["trace[740893876] 'process raft request' (duration: 240.634923ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:19.373029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6147} {"level":"info","ts":"2026-07-27T20:20:19.469321Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6147,"took":"94.523702ms","hash":1954810047,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":23158784,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-07-27T20:20:19.469396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1954810047,"revision":6147,"compact-revision":3019} {"level":"info","ts":"2026-07-27T20:20:29.835627Z","caller":"traceutil/trace.go:171","msg":"trace[1460076881] transaction","detail":"{read_only:false; response_revision:8424; number_of_response:1; }","duration":"287.935131ms","start":"2026-07-27T20:20:29.547643Z","end":"2026-07-27T20:20:29.835578Z","steps":["trace[1460076881] 'process raft request' (duration: 287.675443ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:20:42.604538Z","caller":"traceutil/trace.go:171","msg":"trace[650417706] linearizableReadLoop","detail":"{readStateIndex:8838; appliedIndex:8837; }","duration":"197.941731ms","start":"2026-07-27T20:20:42.406551Z","end":"2026-07-27T20:20:42.604492Z","steps":["trace[650417706] 'read index received' (duration: 146.36291ms)","trace[650417706] 'applied index is now lower than readState.Index' (duration: 51.577531ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:42.60483Z","caller":"traceutil/trace.go:171","msg":"trace[54689547] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"251.537651ms","start":"2026-07-27T20:20:42.353252Z","end":"2026-07-27T20:20:42.60479Z","steps":["trace[54689547] 'process raft request' (duration: 199.649131ms)","trace[54689547] 'compare' (duration: 51.483848ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:20:42.60686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.29247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.222\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-27T20:20:42.606928Z","caller":"traceutil/trace.go:171","msg":"trace[1198422206] range","detail":"{range_begin:/registry/masterleases/199.204.45.222; range_end:; response_count:1; response_revision:8535; }","duration":"200.437214ms","start":"2026-07-27T20:20:42.406477Z","end":"2026-07-27T20:20:42.606914Z","steps":["trace[1198422206] 'agreement among raft nodes before linearized reading' (duration: 198.351403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:43.339732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.221291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-27T20:20:43.339841Z","caller":"traceutil/trace.go:171","msg":"trace[524995564] transaction","detail":"{read_only:false; response_revision:8543; number_of_response:1; }","duration":"224.798313ms","start":"2026-07-27T20:20:43.115025Z","end":"2026-07-27T20:20:43.339823Z","steps":["trace[524995564] 'process raft request' (duration: 106.391907ms)","trace[524995564] 'compare' (duration: 118.109077ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:44.016372Z","caller":"traceutil/trace.go:171","msg":"trace[808296215] linearizableReadLoop","detail":"{readStateIndex:8849; appliedIndex:8848; }","duration":"103.679048ms","start":"2026-07-27T20:20:43.912662Z","end":"2026-07-27T20:20:44.016341Z","steps":["trace[808296215] 'read index received' (duration: 103.435461ms)","trace[808296215] 'applied index is now lower than readState.Index' (duration: 242.787µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:20:44.016522Z","caller":"traceutil/trace.go:171","msg":"trace[1987200955] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"107.8563ms","start":"2026-07-27T20:20:43.908589Z","end":"2026-07-27T20:20:44.016445Z","steps":["trace[1987200955] 'process raft request' (duration: 107.575332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:44.016811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.292557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-27T20:20:44.016843Z","caller":"traceutil/trace.go:171","msg":"trace[293437382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8545; }","duration":"102.380689ms","start":"2026-07-27T20:20:43.914454Z","end":"2026-07-27T20:20:44.016835Z","steps":["trace[293437382] 'agreement among raft nodes before linearized reading' (duration: 102.253625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:20:44.016932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.970086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-27T20:20:44.017233Z","caller":"traceutil/trace.go:171","msg":"trace[1276594381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8545; }","duration":"104.595324ms","start":"2026-07-27T20:20:43.912619Z","end":"2026-07-27T20:20:44.017214Z","steps":["trace[1276594381] 'agreement among raft nodes before linearized reading' (duration: 103.886614ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:21:04.141092Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000239d.wal"} {"level":"info","ts":"2026-07-27T20:22:43.947331Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:22:43.957413Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-27T20:22:43.957525Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-27T20:25:19.384445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8321} {"level":"info","ts":"2026-07-27T20:25:19.470684Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8321,"took":"84.505421ms","hash":1830963291,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":26640384,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-27T20:25:19.47073Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1830963291,"revision":8321,"compact-revision":6147} {"level":"info","ts":"2026-07-27T20:25:31.7559Z","caller":"traceutil/trace.go:171","msg":"trace[730019597] transaction","detail":"{read_only:false; response_revision:11108; number_of_response:1; }","duration":"197.118967ms","start":"2026-07-27T20:25:31.558754Z","end":"2026-07-27T20:25:31.755873Z","steps":["trace[730019597] 'process raft request' (duration: 197.020464ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:25:31.755919Z","caller":"traceutil/trace.go:171","msg":"trace[1711079073] transaction","detail":"{read_only:false; response_revision:11107; number_of_response:1; }","duration":"198.555239ms","start":"2026-07-27T20:25:31.557317Z","end":"2026-07-27T20:25:31.755872Z","steps":["trace[1711079073] 'process raft request' (duration: 111.426853ms)","trace[1711079073] 'compare' (duration: 86.814197ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:30:19.393138Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11040} {"level":"info","ts":"2026-07-27T20:30:19.487577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11040,"took":"91.635763ms","hash":2355649540,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":28323840,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-27T20:30:19.487635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2355649540,"revision":11040,"compact-revision":8321} {"level":"warn","ts":"2026-07-27T20:31:52.409312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.546196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-07-27T20:31:52.4094Z","caller":"traceutil/trace.go:171","msg":"trace[1552366081] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13677; }","duration":"144.687771ms","start":"2026-07-27T20:31:52.264696Z","end":"2026-07-27T20:31:52.409384Z","steps":["trace[1552366081] 'range keys from in-memory index tree' (duration: 144.385662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:31:52.409448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.819924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-v9t5l\" ","response":"range_response_count:1 size:12364"} {"level":"info","ts":"2026-07-27T20:31:52.409565Z","caller":"traceutil/trace.go:171","msg":"trace[158475745] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-v9t5l; range_end:; response_count:1; response_revision:13677; }","duration":"109.985659ms","start":"2026-07-27T20:31:52.29955Z","end":"2026-07-27T20:31:52.409536Z","steps":["trace[158475745] 'range keys from in-memory index tree' (duration: 109.600717ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:31:52.532237Z","caller":"traceutil/trace.go:171","msg":"trace[1173589623] transaction","detail":"{read_only:false; response_revision:13679; number_of_response:1; }","duration":"101.354347ms","start":"2026-07-27T20:31:52.430851Z","end":"2026-07-27T20:31:52.532205Z","steps":["trace[1173589623] 'process raft request' (duration: 81.475037ms)","trace[1173589623] 'compare' (duration: 19.52344ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:31:53.470129Z","caller":"traceutil/trace.go:171","msg":"trace[1728427284] linearizableReadLoop","detail":"{readStateIndex:14151; appliedIndex:14150; }","duration":"118.60334ms","start":"2026-07-27T20:31:53.351497Z","end":"2026-07-27T20:31:53.4701Z","steps":["trace[1728427284] 'read index received' (duration: 118.357293ms)","trace[1728427284] 'applied index is now lower than readState.Index' (duration: 244.427µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:31:53.47041Z","caller":"traceutil/trace.go:171","msg":"trace[656931461] transaction","detail":"{read_only:false; response_revision:13682; number_of_response:1; }","duration":"132.508206ms","start":"2026-07-27T20:31:53.337874Z","end":"2026-07-27T20:31:53.470382Z","steps":["trace[656931461] 'process raft request' (duration: 131.9553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:31:53.470668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.144746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-27T20:31:53.470743Z","caller":"traceutil/trace.go:171","msg":"trace[1274409386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13682; }","duration":"119.259669ms","start":"2026-07-27T20:31:53.351467Z","end":"2026-07-27T20:31:53.470726Z","steps":["trace[1274409386] 'agreement among raft nodes before linearized reading' (duration: 118.747125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:32:01.910865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.086013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-27T20:32:01.910937Z","caller":"traceutil/trace.go:171","msg":"trace[2087535778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13841; }","duration":"121.433843ms","start":"2026-07-27T20:32:01.789487Z","end":"2026-07-27T20:32:01.910921Z","steps":["trace[2087535778] 'range keys from in-memory index tree' (duration: 120.99336ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:35:19.40021Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13077} {"level":"info","ts":"2026-07-27T20:35:19.475857Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13077,"took":"72.847203ms","hash":2815062038,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":31510528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-27T20:35:19.475935Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2815062038,"revision":13077,"compact-revision":11040} {"level":"info","ts":"2026-07-27T20:39:19.440323Z","caller":"traceutil/trace.go:171","msg":"trace[1943656688] transaction","detail":"{read_only:false; response_revision:17603; number_of_response:1; }","duration":"160.326533ms","start":"2026-07-27T20:39:19.279967Z","end":"2026-07-27T20:39:19.440294Z","steps":["trace[1943656688] 'process raft request' (duration: 99.768801ms)","trace[1943656688] 'compare' (duration: 60.452839ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:39:24.197603Z","caller":"traceutil/trace.go:171","msg":"trace[2016280886] linearizableReadLoop","detail":"{readStateIndex:18246; appliedIndex:18245; }","duration":"322.037636ms","start":"2026-07-27T20:39:23.875546Z","end":"2026-07-27T20:39:24.197584Z","steps":["trace[2016280886] 'read index received' (duration: 321.888352ms)","trace[2016280886] 'applied index is now lower than readState.Index' (duration: 148.344µs)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:39:24.198086Z","caller":"traceutil/trace.go:171","msg":"trace[1287063429] transaction","detail":"{read_only:false; response_revision:17653; number_of_response:1; }","duration":"330.907423ms","start":"2026-07-27T20:39:23.867139Z","end":"2026-07-27T20:39:24.198047Z","steps":["trace[1287063429] 'process raft request' (duration: 330.320137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:39:24.19811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.53123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:39:24.198222Z","caller":"traceutil/trace.go:171","msg":"trace[1278281353] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:17653; }","duration":"322.711355ms","start":"2026-07-27T20:39:23.875487Z","end":"2026-07-27T20:39:24.198198Z","steps":["trace[1278281353] 'agreement among raft nodes before linearized reading' (duration: 322.503469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:39:24.198249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.67994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"warn","ts":"2026-07-27T20:39:24.198291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:39:23.875465Z","time spent":"322.810328ms","remote":"127.0.0.1:49926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true "} {"level":"warn","ts":"2026-07-27T20:39:24.198139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.153827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-27T20:39:24.198239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.066388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-2zjg2\" ","response":"range_response_count:1 size:22126"} {"level":"warn","ts":"2026-07-27T20:39:24.198392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.621264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-27T20:39:24.198446Z","caller":"traceutil/trace.go:171","msg":"trace[9871738] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-2zjg2; range_end:; response_count:1; response_revision:17653; }","duration":"266.294005ms","start":"2026-07-27T20:39:23.932125Z","end":"2026-07-27T20:39:24.198419Z","steps":["trace[9871738] 'agreement among raft nodes before linearized reading' (duration: 266.033938ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:39:24.198444Z","caller":"traceutil/trace.go:171","msg":"trace[2146935879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17653; }","duration":"309.699926ms","start":"2026-07-27T20:39:23.888731Z","end":"2026-07-27T20:39:24.19843Z","steps":["trace[2146935879] 'agreement among raft nodes before linearized reading' (duration: 309.566923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:39:24.198308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.059061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-27T20:39:24.198483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:39:23.888719Z","time spent":"309.754398ms","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-27T20:39:24.198325Z","caller":"traceutil/trace.go:171","msg":"trace[468706821] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:17653; }","duration":"218.805214ms","start":"2026-07-27T20:39:23.979498Z","end":"2026-07-27T20:39:24.198303Z","steps":["trace[468706821] 'agreement among raft nodes before linearized reading' (duration: 218.583107ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:39:24.198504Z","caller":"traceutil/trace.go:171","msg":"trace[1129465547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17653; }","duration":"306.252146ms","start":"2026-07-27T20:39:23.892243Z","end":"2026-07-27T20:39:24.198496Z","steps":["trace[1129465547] 'agreement among raft nodes before linearized reading' (duration: 306.009419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:39:24.198545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:39:23.892208Z","time spent":"306.325248ms","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-27T20:39:24.19863Z","caller":"traceutil/trace.go:171","msg":"trace[1953157286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17653; }","duration":"312.468386ms","start":"2026-07-27T20:39:23.885927Z","end":"2026-07-27T20:39:24.198395Z","steps":["trace[1953157286] 'agreement among raft nodes before linearized reading' (duration: 312.111656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-27T20:39:24.198727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:39:23.885912Z","time spent":"312.773846ms","remote":"127.0.0.1:56832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-27T20:39:24.19927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-27T20:39:23.867116Z","time spent":"331.078889ms","remote":"127.0.0.1:56746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7172,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-27T20:40:19.407131Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15572} {"level":"info","ts":"2026-07-27T20:40:19.509528Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15572,"took":"100.020339ms","hash":2530832733,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":33722368,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-27T20:40:19.509584Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2530832733,"revision":15572,"compact-revision":13077} {"level":"info","ts":"2026-07-27T20:42:55.27466Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"380f6760faea5f9e","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-27T20:42:55.279233Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-27T20:42:55.279314Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-27T20:43:41.952653Z","caller":"traceutil/trace.go:171","msg":"trace[233006631] transaction","detail":"{read_only:false; response_revision:19620; number_of_response:1; }","duration":"114.751362ms","start":"2026-07-27T20:43:41.837878Z","end":"2026-07-27T20:43:41.952629Z","steps":["trace[233006631] 'process raft request' (duration: 114.620469ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:41.976746Z","caller":"traceutil/trace.go:171","msg":"trace[1651648154] transaction","detail":"{read_only:false; response_revision:19621; number_of_response:1; }","duration":"136.081821ms","start":"2026-07-27T20:43:41.840635Z","end":"2026-07-27T20:43:41.976717Z","steps":["trace[1651648154] 'process raft request' (duration: 135.912946ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:58.136253Z","caller":"traceutil/trace.go:171","msg":"trace[2104423576] transaction","detail":"{read_only:false; response_revision:19701; number_of_response:1; }","duration":"110.004765ms","start":"2026-07-27T20:43:58.026203Z","end":"2026-07-27T20:43:58.136208Z","steps":["trace[2104423576] 'process raft request' (duration: 109.732348ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:58.354961Z","caller":"traceutil/trace.go:171","msg":"trace[486689689] transaction","detail":"{read_only:false; response_revision:19702; number_of_response:1; }","duration":"215.041755ms","start":"2026-07-27T20:43:58.139885Z","end":"2026-07-27T20:43:58.354927Z","steps":["trace[486689689] 'process raft request' (duration: 144.048493ms)","trace[486689689] 'compare' (duration: 70.857588ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:43:58.381923Z","caller":"traceutil/trace.go:171","msg":"trace[402471608] transaction","detail":"{read_only:false; response_revision:19704; number_of_response:1; }","duration":"154.714883ms","start":"2026-07-27T20:43:58.227185Z","end":"2026-07-27T20:43:58.3819Z","steps":["trace[402471608] 'process raft request' (duration: 154.665441ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:58.381987Z","caller":"traceutil/trace.go:171","msg":"trace[1082345574] transaction","detail":"{read_only:false; response_revision:19703; number_of_response:1; }","duration":"155.057832ms","start":"2026-07-27T20:43:58.2269Z","end":"2026-07-27T20:43:58.381957Z","steps":["trace[1082345574] 'process raft request' (duration: 154.820525ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:43:58.675777Z","caller":"traceutil/trace.go:171","msg":"trace[270466301] transaction","detail":"{read_only:false; response_revision:19705; number_of_response:1; }","duration":"129.008896ms","start":"2026-07-27T20:43:58.546744Z","end":"2026-07-27T20:43:58.675752Z","steps":["trace[270466301] 'process raft request' (duration: 128.867672ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:45:19.41761Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18180} {"level":"info","ts":"2026-07-27T20:45:19.535589Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18180,"took":"113.715752ms","hash":570791776,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":30547968,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:45:19.535665Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":570791776,"revision":18180,"compact-revision":15572} {"level":"warn","ts":"2026-07-27T20:47:31.584535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.819529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-07-27T20:47:31.584797Z","caller":"traceutil/trace.go:171","msg":"trace[97237655] linearizableReadLoop","detail":"{readStateIndex:22786; appliedIndex:22784; }","duration":"114.66883ms","start":"2026-07-27T20:47:31.470107Z","end":"2026-07-27T20:47:31.584775Z","steps":["trace[97237655] 'read index received' (duration: 5.52154ms)","trace[97237655] 'applied index is now lower than readState.Index' (duration: 109.14602ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:47:31.58496Z","caller":"traceutil/trace.go:171","msg":"trace[1856940874] transaction","detail":"{read_only:false; response_revision:22054; number_of_response:1; }","duration":"131.067867ms","start":"2026-07-27T20:47:31.453848Z","end":"2026-07-27T20:47:31.584916Z","steps":["trace[1856940874] 'process raft request' (duration: 130.829029ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:47:31.584974Z","caller":"traceutil/trace.go:171","msg":"trace[152526500] transaction","detail":"{read_only:false; response_revision:22053; number_of_response:1; }","duration":"140.650596ms","start":"2026-07-27T20:47:31.44429Z","end":"2026-07-27T20:47:31.584941Z","steps":["trace[152526500] 'process raft request' (duration: 31.328971ms)","trace[152526500] 'compare' (duration: 108.564922ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:47:31.585141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.022081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:47:31.585208Z","caller":"traceutil/trace.go:171","msg":"trace[1833291276] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:22054; }","duration":"115.129533ms","start":"2026-07-27T20:47:31.470062Z","end":"2026-07-27T20:47:31.585191Z","steps":["trace[1833291276] 'agreement among raft nodes before linearized reading' (duration: 114.863145ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:47:31.918947Z","caller":"traceutil/trace.go:171","msg":"trace[2117114683] linearizableReadLoop","detail":"{readStateIndex:22787; appliedIndex:22786; }","duration":"232.053979ms","start":"2026-07-27T20:47:31.686868Z","end":"2026-07-27T20:47:31.918922Z","steps":["trace[2117114683] 'read index received' (duration: 196.693412ms)","trace[2117114683] 'applied index is now lower than readState.Index' (duration: 35.359587ms)"],"step_count":2} {"level":"warn","ts":"2026-07-27T20:47:31.919075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.190793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-27T20:47:31.919116Z","caller":"traceutil/trace.go:171","msg":"trace[981153864] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:22055; }","duration":"232.274115ms","start":"2026-07-27T20:47:31.686828Z","end":"2026-07-27T20:47:31.919102Z","steps":["trace[981153864] 'agreement among raft nodes before linearized reading' (duration: 232.188473ms)"],"step_count":1} {"level":"info","ts":"2026-07-27T20:47:31.919243Z","caller":"traceutil/trace.go:171","msg":"trace[967235882] transaction","detail":"{read_only:false; response_revision:22055; number_of_response:1; }","duration":"280.323751ms","start":"2026-07-27T20:47:31.638852Z","end":"2026-07-27T20:47:31.919176Z","steps":["trace[967235882] 'process raft request' (duration: 244.797568ms)","trace[967235882] 'compare' (duration: 34.706399ms)"],"step_count":2} {"level":"info","ts":"2026-07-27T20:50:19.426017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20473} {"level":"info","ts":"2026-07-27T20:50:19.533042Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20473,"took":"102.916368ms","hash":3545910614,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":43347968,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-27T20:50:19.533117Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3545910614,"revision":20473,"compact-revision":18180} {"level":"info","ts":"2026-07-27T20:55:19.431995Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23603} {"level":"info","ts":"2026-07-27T20:55:19.556236Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23603,"took":"120.929693ms","hash":514159489,"current-db-size-bytes":57937920,"current-db-size":"58 MB","current-db-size-in-use-bytes":29933568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-27T20:55:19.556302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":514159489,"revision":23603,"compact-revision":20473}