{"level":"warn","ts":"2026-04-07T15:12:18.194864Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:12:18.195039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-07T15:12:18.195131Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T15:12:18.19516Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-07T15:12:18.195208Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:12:18.196138Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-07T15:12:18.196294Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-07T15:12:18.20011Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.476775ms"} {"level":"info","ts":"2026-04-07T15:12:18.206263Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-07T15:12:18.206501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T15:12:18.206638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-07T15:12:18.206777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T15:12:18.207318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-07T15:12:18.207502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-07T15:12:18.215618Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T15:12:18.217068Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T15:12:18.218426Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-07T15:12:18.22057Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T15:12:18.221028Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T15:12:18.221188Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:12:18.221255Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:12:18.221271Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-07T15:12:18.2216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-07T15:12:18.222106Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-07T15:12:18.225058Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-07T15:12:18.22517Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-07T15:12:18.22521Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-07T15:12:18.225348Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T15:12:18.225385Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T15:12:19.10847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T15:12:19.108558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T15:12:19.108582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-07T15:12:19.108598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-07T15:12:19.108606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-07T15:12:19.108619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-07T15:12:19.108631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-07T15:12:19.109781Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T15:12:19.110129Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:19.110286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:12:19.110473Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T15:12:19.111429Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:19.111625Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:19.111655Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T15:12:19.112087Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T15:12:19.112138Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T15:12:19.112948Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T15:12:19.115247Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-04-07T15:12:24.519655Z","caller":"traceutil/trace.go:171","msg":"trace[1668828951] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"328.145056ms","start":"2026-04-07T15:12:24.191482Z","end":"2026-04-07T15:12:24.519627Z","steps":["trace[1668828951] 'read index received' (duration: 328.011115ms)","trace[1668828951] 'applied index is now lower than readState.Index' (duration: 132.911µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:24.519719Z","caller":"traceutil/trace.go:171","msg":"trace[1107854348] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"337.846689ms","start":"2026-04-07T15:12:24.181836Z","end":"2026-04-07T15:12:24.519683Z","steps":["trace[1107854348] 'process raft request' (duration: 337.655668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.520022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.163586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-07T15:12:24.52119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:24.181804Z","time spent":"338.860076ms","remote":"127.0.0.1:48996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:12:24.520017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.345877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:24.521383Z","caller":"traceutil/trace.go:171","msg":"trace[1418066284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:302; }","duration":"329.889718ms","start":"2026-04-07T15:12:24.191472Z","end":"2026-04-07T15:12:24.521362Z","steps":["trace[1418066284] 'agreement among raft nodes before linearized reading' (duration: 328.294238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.521434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:24.1914Z","time spent":"330.018509ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T15:12:24.521248Z","caller":"traceutil/trace.go:171","msg":"trace[1826516929] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:302; }","duration":"329.420054ms","start":"2026-04-07T15:12:24.191809Z","end":"2026-04-07T15:12:24.521229Z","steps":["trace[1826516929] 'agreement among raft nodes before linearized reading' (duration: 328.157146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.521539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:24.191786Z","time spent":"329.702356ms","remote":"127.0.0.1:48906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-07T15:12:24.754227Z","caller":"traceutil/trace.go:171","msg":"trace[1924601414] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"229.090998ms","start":"2026-04-07T15:12:24.525106Z","end":"2026-04-07T15:12:24.754197Z","steps":["trace[1924601414] 'process raft request' (duration: 224.187766ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.780134Z","caller":"traceutil/trace.go:171","msg":"trace[928777768] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:309; }","duration":"230.77077ms","start":"2026-04-07T15:12:24.549336Z","end":"2026-04-07T15:12:24.780107Z","steps":["trace[928777768] 'read index received' (duration: 200.066589ms)","trace[928777768] 'applied index is now lower than readState.Index' (duration: 30.703131ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:24.780247Z","caller":"traceutil/trace.go:171","msg":"trace[587119795] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"254.777466ms","start":"2026-04-07T15:12:24.525398Z","end":"2026-04-07T15:12:24.780175Z","steps":["trace[587119795] 'process raft request' (duration: 254.640075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.780332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.007781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-07T15:12:24.780361Z","caller":"traceutil/trace.go:171","msg":"trace[1035555874] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"254.881317ms","start":"2026-04-07T15:12:24.525454Z","end":"2026-04-07T15:12:24.780335Z","steps":["trace[1035555874] 'process raft request' (duration: 254.615685ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.780294Z","caller":"traceutil/trace.go:171","msg":"trace[282756997] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"254.985608ms","start":"2026-04-07T15:12:24.525292Z","end":"2026-04-07T15:12:24.780278Z","steps":["trace[282756997] 'process raft request' (duration: 254.598355ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.78043Z","caller":"traceutil/trace.go:171","msg":"trace[1175840853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:306; }","duration":"231.153192ms","start":"2026-04-07T15:12:24.549256Z","end":"2026-04-07T15:12:24.780409Z","steps":["trace[1175840853] 'agreement among raft nodes before linearized reading' (duration: 230.996991ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.959306Z","caller":"traceutil/trace.go:171","msg":"trace[100769706] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"196.875057ms","start":"2026-04-07T15:12:24.762393Z","end":"2026-04-07T15:12:24.959269Z","steps":["trace[100769706] 'process raft request' (duration: 196.651026ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.959371Z","caller":"traceutil/trace.go:171","msg":"trace[1510525488] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:313; }","duration":"179.122632ms","start":"2026-04-07T15:12:24.780225Z","end":"2026-04-07T15:12:24.959348Z","steps":["trace[1510525488] 'read index received' (duration: 178.938971ms)","trace[1510525488] 'applied index is now lower than readState.Index' (duration: 182.491µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:24.959346Z","caller":"traceutil/trace.go:171","msg":"trace[842922181] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"194.769584ms","start":"2026-04-07T15:12:24.76453Z","end":"2026-04-07T15:12:24.9593Z","steps":["trace[842922181] 'process raft request' (duration: 194.683703ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.959454Z","caller":"traceutil/trace.go:171","msg":"trace[1017608371] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"194.808034ms","start":"2026-04-07T15:12:24.764637Z","end":"2026-04-07T15:12:24.959445Z","steps":["trace[1017608371] 'process raft request' (duration: 194.633883ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.95936Z","caller":"traceutil/trace.go:171","msg":"trace[1228653024] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"194.747494ms","start":"2026-04-07T15:12:24.764579Z","end":"2026-04-07T15:12:24.959326Z","steps":["trace[1228653024] 'process raft request' (duration: 194.663703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.959529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.770454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:24.9595Z","caller":"traceutil/trace.go:171","msg":"trace[575180969] transaction","detail":"{read_only:false; number_of_response:0; response_revision:306; }","duration":"194.713974ms","start":"2026-04-07T15:12:24.764743Z","end":"2026-04-07T15:12:24.959457Z","steps":["trace[575180969] 'process raft request' (duration: 194.558233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.959664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.284152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:24.959632Z","caller":"traceutil/trace.go:171","msg":"trace[1061806557] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:306; }","duration":"194.925615ms","start":"2026-04-07T15:12:24.764689Z","end":"2026-04-07T15:12:24.959615Z","steps":["trace[1061806557] 'agreement among raft nodes before linearized reading' (duration: 194.778034ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.959714Z","caller":"traceutil/trace.go:171","msg":"trace[1322659284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:306; }","duration":"295.401082ms","start":"2026-04-07T15:12:24.664299Z","end":"2026-04-07T15:12:24.9597Z","steps":["trace[1322659284] 'agreement among raft nodes before linearized reading' (duration: 295.292512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.960272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.055346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-07T15:12:24.960354Z","caller":"traceutil/trace.go:171","msg":"trace[28640737] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:306; }","duration":"195.73349ms","start":"2026-04-07T15:12:24.7646Z","end":"2026-04-07T15:12:24.960333Z","steps":["trace[28640737] 'agreement among raft nodes before linearized reading' (duration: 194.990216ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.960845Z","caller":"traceutil/trace.go:171","msg":"trace[813136185] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"175.254617ms","start":"2026-04-07T15:12:24.785575Z","end":"2026-04-07T15:12:24.96083Z","steps":["trace[813136185] 'process raft request' (duration: 174.780813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:24.960863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.053955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-07T15:12:24.960868Z","caller":"traceutil/trace.go:171","msg":"trace[1959538190] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"175.058035ms","start":"2026-04-07T15:12:24.785792Z","end":"2026-04-07T15:12:24.96085Z","steps":["trace[1959538190] 'process raft request' (duration: 174.889054ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:24.960926Z","caller":"traceutil/trace.go:171","msg":"trace[1051071191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:308; }","duration":"175.138985ms","start":"2026-04-07T15:12:24.785769Z","end":"2026-04-07T15:12:24.960908Z","steps":["trace[1051071191] 'agreement among raft nodes before linearized reading' (duration: 175.012684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:49.202294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.398964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:12:49.202631Z","caller":"traceutil/trace.go:171","msg":"trace[331751976] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"102.69467ms","start":"2026-04-07T15:12:49.099896Z","end":"2026-04-07T15:12:49.202591Z","steps":["trace[331751976] 'compare' (duration: 101.237664ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.213087Z","caller":"traceutil/trace.go:171","msg":"trace[250447507] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"109.921677ms","start":"2026-04-07T15:12:49.103144Z","end":"2026-04-07T15:12:49.213066Z","steps":["trace[250447507] 'process raft request' (duration: 109.834516ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.218162Z","caller":"traceutil/trace.go:171","msg":"trace[746385792] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"113.580214ms","start":"2026-04-07T15:12:49.104563Z","end":"2026-04-07T15:12:49.218143Z","steps":["trace[746385792] 'process raft request' (duration: 113.076852ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.218453Z","caller":"traceutil/trace.go:171","msg":"trace[607904566] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"110.898381ms","start":"2026-04-07T15:12:49.107544Z","end":"2026-04-07T15:12:49.218442Z","steps":["trace[607904566] 'process raft request' (duration: 110.337398ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.218602Z","caller":"traceutil/trace.go:171","msg":"trace[1154655795] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"114.004737ms","start":"2026-04-07T15:12:49.104574Z","end":"2026-04-07T15:12:49.218578Z","steps":["trace[1154655795] 'process raft request' (duration: 113.233563ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.218955Z","caller":"traceutil/trace.go:171","msg":"trace[1339971500] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"111.272933ms","start":"2026-04-07T15:12:49.107666Z","end":"2026-04-07T15:12:49.218939Z","steps":["trace[1339971500] 'process raft request' (duration: 110.395799ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.220449Z","caller":"traceutil/trace.go:171","msg":"trace[87629795] transaction","detail":"{read_only:false; number_of_response:1; response_revision:516; }","duration":"109.541994ms","start":"2026-04-07T15:12:49.109938Z","end":"2026-04-07T15:12:49.21948Z","steps":["trace[87629795] 'process raft request' (duration: 108.229998ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.221716Z","caller":"traceutil/trace.go:171","msg":"trace[1971493101] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"107.974767ms","start":"2026-04-07T15:12:49.113728Z","end":"2026-04-07T15:12:49.221703Z","steps":["trace[1971493101] 'process raft request' (duration: 107.666795ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.222438Z","caller":"traceutil/trace.go:171","msg":"trace[893348169] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"108.63013ms","start":"2026-04-07T15:12:49.113798Z","end":"2026-04-07T15:12:49.222428Z","steps":["trace[893348169] 'process raft request' (duration: 107.821116ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:49.222768Z","caller":"traceutil/trace.go:171","msg":"trace[494091329] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"108.303218ms","start":"2026-04-07T15:12:49.114458Z","end":"2026-04-07T15:12:49.222762Z","steps":["trace[494091329] 'process raft request' (duration: 107.826256ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:52.37061Z","caller":"traceutil/trace.go:171","msg":"trace[1553532643] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"237.529769ms","start":"2026-04-07T15:12:52.133014Z","end":"2026-04-07T15:12:52.370544Z","steps":["trace[1553532643] 'read index received' (duration: 231.120848ms)","trace[1553532643] 'applied index is now lower than readState.Index' (duration: 6.407851ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:12:52.370711Z","caller":"traceutil/trace.go:171","msg":"trace[1226173148] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"319.152046ms","start":"2026-04-07T15:12:52.051547Z","end":"2026-04-07T15:12:52.370699Z","steps":["trace[1226173148] 'process raft request' (duration: 313.846291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:52.370813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:52.051512Z","time spent":"319.236708ms","remote":"127.0.0.1:48884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-07T15:12:52.375824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.793764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T15:12:52.375876Z","caller":"traceutil/trace.go:171","msg":"trace[457984608] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:595; }","duration":"242.888775ms","start":"2026-04-07T15:12:52.132976Z","end":"2026-04-07T15:12:52.375865Z","steps":["trace[457984608] 'agreement among raft nodes before linearized reading' (duration: 237.69338ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:56.029161Z","caller":"traceutil/trace.go:171","msg":"trace[240840179] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"186.931907ms","start":"2026-04-07T15:12:55.842159Z","end":"2026-04-07T15:12:56.029091Z","steps":["trace[240840179] 'read index received' (duration: 185.54925ms)","trace[240840179] 'applied index is now lower than readState.Index' (duration: 1.381107ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:56.02938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.188058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5g8lh\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-07T15:12:56.029435Z","caller":"traceutil/trace.go:171","msg":"trace[979759825] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5g8lh; range_end:; response_count:1; response_revision:621; }","duration":"187.310928ms","start":"2026-04-07T15:12:55.842111Z","end":"2026-04-07T15:12:56.029422Z","steps":["trace[979759825] 'agreement among raft nodes before linearized reading' (duration: 187.133057ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:12:56.029216Z","caller":"traceutil/trace.go:171","msg":"trace[1761685300] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"224.957902ms","start":"2026-04-07T15:12:55.804238Z","end":"2026-04-07T15:12:56.029196Z","steps":["trace[1761685300] 'process raft request' (duration: 223.820277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:56.778986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.476982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:12:56.779245Z","caller":"traceutil/trace.go:171","msg":"trace[2084706106] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"717.04429ms","start":"2026-04-07T15:12:56.062146Z","end":"2026-04-07T15:12:56.779191Z","steps":["trace[2084706106] 'process raft request' (duration: 75.268997ms)","trace[2084706106] 'compare' (duration: 641.198441ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:12:56.779422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.857223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:12:56.779471Z","caller":"traceutil/trace.go:171","msg":"trace[2060329509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"304.941124ms","start":"2026-04-07T15:12:56.47452Z","end":"2026-04-07T15:12:56.779461Z","steps":["trace[2060329509] 'agreement among raft nodes before linearized reading' (duration: 304.838153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:56.779517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:56.474498Z","time spent":"305.009304ms","remote":"127.0.0.1:48764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T15:12:56.77951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:56.062124Z","time spent":"717.262111ms","remote":"127.0.0.1:48986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:12:56.779802Z","caller":"traceutil/trace.go:171","msg":"trace[1351180245] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"693.863503ms","start":"2026-04-07T15:12:56.085927Z","end":"2026-04-07T15:12:56.779791Z","steps":["trace[1351180245] 'process raft request' (duration: 693.16414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:12:56.779867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:12:56.085898Z","time spent":"693.941534ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T15:12:56.779321Z","caller":"traceutil/trace.go:171","msg":"trace[1243900638] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:644; }","duration":"304.742003ms","start":"2026-04-07T15:12:56.474557Z","end":"2026-04-07T15:12:56.779299Z","steps":["trace[1243900638] 'read index received' (duration: 36.088116ms)","trace[1243900638] 'applied index is now lower than readState.Index' (duration: 268.652877ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:13:47.616059Z","caller":"traceutil/trace.go:171","msg":"trace[649486875] linearizableReadLoop","detail":"{readStateIndex:1231; appliedIndex:1230; }","duration":"212.439931ms","start":"2026-04-07T15:13:47.403589Z","end":"2026-04-07T15:13:47.616029Z","steps":["trace[649486875] 'read index received' (duration: 193.640418ms)","trace[649486875] 'applied index is now lower than readState.Index' (duration: 18.797903ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:13:47.616301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.684619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-07T15:13:47.616367Z","caller":"traceutil/trace.go:171","msg":"trace[84411709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"212.813933ms","start":"2026-04-07T15:13:47.403542Z","end":"2026-04-07T15:13:47.616356Z","steps":["trace[84411709] 'agreement among raft nodes before linearized reading' (duration: 212.561984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:47.616416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.164517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:13:47.61647Z","caller":"traceutil/trace.go:171","msg":"trace[1495396429] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"142.25892ms","start":"2026-04-07T15:13:47.474195Z","end":"2026-04-07T15:13:47.616454Z","steps":["trace[1495396429] 'agreement among raft nodes before linearized reading' (duration: 142.172257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:47.616481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.716719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-07T15:13:47.616518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.976768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-07T15:13:47.616557Z","caller":"traceutil/trace.go:171","msg":"trace[756252430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1195; }","duration":"203.849533ms","start":"2026-04-07T15:13:47.412688Z","end":"2026-04-07T15:13:47.616538Z","steps":["trace[756252430] 'agreement among raft nodes before linearized reading' (duration: 203.626975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:13:47.616561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.17098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T15:13:47.616634Z","caller":"traceutil/trace.go:171","msg":"trace[393713190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"134.259723ms","start":"2026-04-07T15:13:47.482349Z","end":"2026-04-07T15:13:47.616609Z","steps":["trace[393713190] 'agreement among raft nodes before linearized reading' (duration: 134.103318ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:13:47.616631Z","caller":"traceutil/trace.go:171","msg":"trace[1661440089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"140.114333ms","start":"2026-04-07T15:13:47.4765Z","end":"2026-04-07T15:13:47.616614Z","steps":["trace[1661440089] 'agreement among raft nodes before linearized reading' (duration: 139.833653ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:14:40.480991Z","caller":"traceutil/trace.go:171","msg":"trace[770901620] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"139.368739ms","start":"2026-04-07T15:14:40.341601Z","end":"2026-04-07T15:14:40.48097Z","steps":["trace[770901620] 'process raft request' (duration: 138.964881ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:16.150613Z","caller":"traceutil/trace.go:171","msg":"trace[1228707796] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"114.07042ms","start":"2026-04-07T15:15:16.036518Z","end":"2026-04-07T15:15:16.150589Z","steps":["trace[1228707796] 'process raft request' (duration: 113.904297ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:16.264056Z","caller":"traceutil/trace.go:171","msg":"trace[821279716] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"107.699343ms","start":"2026-04-07T15:15:16.156327Z","end":"2026-04-07T15:15:16.264026Z","steps":["trace[821279716] 'process raft request' (duration: 79.082426ms)","trace[821279716] 'compare' (duration: 28.511805ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:16.272735Z","caller":"traceutil/trace.go:171","msg":"trace[1352915831] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"116.344284ms","start":"2026-04-07T15:15:16.156367Z","end":"2026-04-07T15:15:16.272711Z","steps":["trace[1352915831] 'process raft request' (duration: 115.854947ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:16.272989Z","caller":"traceutil/trace.go:171","msg":"trace[708651804] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"116.529007ms","start":"2026-04-07T15:15:16.156439Z","end":"2026-04-07T15:15:16.272968Z","steps":["trace[708651804] 'process raft request' (duration: 115.892288ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:17.282077Z","caller":"traceutil/trace.go:171","msg":"trace[2009438237] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"240.471392ms","start":"2026-04-07T15:15:17.04158Z","end":"2026-04-07T15:15:17.282051Z","steps":["trace[2009438237] 'process raft request' (duration: 240.185118ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:15:17.287911Z","caller":"traceutil/trace.go:171","msg":"trace[773190471] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"217.23914ms","start":"2026-04-07T15:15:17.07065Z","end":"2026-04-07T15:15:17.287889Z","steps":["trace[773190471] 'process raft request' (duration: 216.778183ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:17.583237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.29334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:15:17.583397Z","caller":"traceutil/trace.go:171","msg":"trace[1049482667] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"290.294178ms","start":"2026-04-07T15:15:17.293077Z","end":"2026-04-07T15:15:17.583371Z","steps":["trace[1049482667] 'process raft request' (duration: 187.762995ms)","trace[1049482667] 'compare' (duration: 102.030925ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:15:17.588485Z","caller":"traceutil/trace.go:171","msg":"trace[1268749287] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2227; }","duration":"118.874381ms","start":"2026-04-07T15:15:17.469587Z","end":"2026-04-07T15:15:17.588461Z","steps":["trace[1268749287] 'read index received' (duration: 11.310701ms)","trace[1268749287] 'applied index is now lower than readState.Index' (duration: 107.562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:15:17.588705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.898205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:15:17.588796Z","caller":"traceutil/trace.go:171","msg":"trace[121428745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2149; }","duration":"114.022658ms","start":"2026-04-07T15:15:17.474751Z","end":"2026-04-07T15:15:17.588774Z","steps":["trace[121428745] 'agreement among raft nodes before linearized reading' (duration: 113.869435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:15:17.589055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.225156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-04-07T15:15:17.589138Z","caller":"traceutil/trace.go:171","msg":"trace[294560382] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2149; }","duration":"119.611252ms","start":"2026-04-07T15:15:17.469509Z","end":"2026-04-07T15:15:17.58912Z","steps":["trace[294560382] 'agreement among raft nodes before linearized reading' (duration: 119.072654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:16:22.247533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.393346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T15:16:22.247642Z","caller":"traceutil/trace.go:171","msg":"trace[1142040474] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:2595; }","duration":"241.601689ms","start":"2026-04-07T15:16:22.00602Z","end":"2026-04-07T15:16:22.247622Z","steps":["trace[1142040474] 'count revisions from in-memory index tree' (duration: 241.235904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:16:22.247762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.595258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-04-07T15:16:22.247814Z","caller":"traceutil/trace.go:171","msg":"trace[2083212886] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2595; }","duration":"186.686848ms","start":"2026-04-07T15:16:22.061112Z","end":"2026-04-07T15:16:22.247799Z","steps":["trace[2083212886] 'range keys from in-memory index tree' (duration: 186.397186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:16:22.247885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.138916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.77\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-07T15:16:22.248005Z","caller":"traceutil/trace.go:171","msg":"trace[1699078842] range","detail":"{range_begin:/registry/masterleases/199.204.45.77; range_end:; response_count:1; response_revision:2595; }","duration":"103.298028ms","start":"2026-04-07T15:16:22.144654Z","end":"2026-04-07T15:16:22.247952Z","steps":["trace[1699078842] 'range keys from in-memory index tree' (duration: 102.906634ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:54.973822Z","caller":"traceutil/trace.go:171","msg":"trace[593913546] linearizableReadLoop","detail":"{readStateIndex:2880; appliedIndex:2878; }","duration":"194.713833ms","start":"2026-04-07T15:16:54.779067Z","end":"2026-04-07T15:16:54.973781Z","steps":["trace[593913546] 'read index received' (duration: 12.673326ms)","trace[593913546] 'applied index is now lower than readState.Index' (duration: 182.038917ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:16:54.973964Z","caller":"traceutil/trace.go:171","msg":"trace[1800388491] transaction","detail":"{read_only:false; response_revision:2777; number_of_response:1; }","duration":"194.860025ms","start":"2026-04-07T15:16:54.77905Z","end":"2026-04-07T15:16:54.97391Z","steps":["trace[1800388491] 'process raft request' (duration: 184.747094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:16:54.97408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.991786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-07T15:16:54.974125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.625052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-04-07T15:16:54.974163Z","caller":"traceutil/trace.go:171","msg":"trace[670071442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2777; }","duration":"195.100757ms","start":"2026-04-07T15:16:54.77904Z","end":"2026-04-07T15:16:54.974141Z","steps":["trace[670071442] 'agreement among raft nodes before linearized reading' (duration: 194.864425ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:54.97421Z","caller":"traceutil/trace.go:171","msg":"trace[2128702566] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2777; }","duration":"194.718133ms","start":"2026-04-07T15:16:54.77947Z","end":"2026-04-07T15:16:54.974188Z","steps":["trace[2128702566] 'agreement among raft nodes before linearized reading' (duration: 194.516471ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:55.239677Z","caller":"traceutil/trace.go:171","msg":"trace[1779220782] transaction","detail":"{read_only:false; response_revision:2779; number_of_response:1; }","duration":"224.625088ms","start":"2026-04-07T15:16:55.015014Z","end":"2026-04-07T15:16:55.239639Z","steps":["trace[1779220782] 'process raft request' (duration: 224.397816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:16:55.436271Z","caller":"traceutil/trace.go:171","msg":"trace[1480681654] transaction","detail":"{read_only:false; response_revision:2780; number_of_response:1; }","duration":"192.994492ms","start":"2026-04-07T15:16:55.24325Z","end":"2026-04-07T15:16:55.436245Z","steps":["trace[1480681654] 'process raft request' (duration: 148.161076ms)","trace[1480681654] 'compare' (duration: 44.157529ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:48.521567Z","caller":"traceutil/trace.go:171","msg":"trace[338164394] linearizableReadLoop","detail":"{readStateIndex:4871; appliedIndex:4870; }","duration":"101.602669ms","start":"2026-04-07T15:20:48.419943Z","end":"2026-04-07T15:20:48.521546Z","steps":["trace[338164394] 'read index received' (duration: 97.030316ms)","trace[338164394] 'applied index is now lower than readState.Index' (duration: 4.571503ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:48.521822Z","caller":"traceutil/trace.go:171","msg":"trace[340440489] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"104.411233ms","start":"2026-04-07T15:20:48.417403Z","end":"2026-04-07T15:20:48.521814Z","steps":["trace[340440489] 'process raft request' (duration: 99.543003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:48.522288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.324515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:6 size:60435"} {"level":"info","ts":"2026-04-07T15:20:48.522324Z","caller":"traceutil/trace.go:171","msg":"trace[1591564935] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:6; response_revision:4693; }","duration":"102.396298ms","start":"2026-04-07T15:20:48.419919Z","end":"2026-04-07T15:20:48.522315Z","steps":["trace[1591564935] 'agreement among raft nodes before linearized reading' (duration: 102.201463ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:48.822451Z","caller":"traceutil/trace.go:171","msg":"trace[1004563290] linearizableReadLoop","detail":"{readStateIndex:4880; appliedIndex:4879; }","duration":"116.130129ms","start":"2026-04-07T15:20:48.706299Z","end":"2026-04-07T15:20:48.822429Z","steps":["trace[1004563290] 'read index received' (duration: 115.796191ms)","trace[1004563290] 'applied index is now lower than readState.Index' (duration: 332.938µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:20:48.822559Z","caller":"traceutil/trace.go:171","msg":"trace[977472971] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"116.704322ms","start":"2026-04-07T15:20:48.705813Z","end":"2026-04-07T15:20:48.822517Z","steps":["trace[977472971] 'process raft request' (duration: 116.259072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:20:48.822669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.346613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-07T15:20:48.822739Z","caller":"traceutil/trace.go:171","msg":"trace[923885933] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4700; }","duration":"116.482846ms","start":"2026-04-07T15:20:48.706241Z","end":"2026-04-07T15:20:48.822724Z","steps":["trace[923885933] 'agreement among raft nodes before linearized reading' (duration: 116.280032ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:52.000987Z","caller":"traceutil/trace.go:171","msg":"trace[2120295149] transaction","detail":"{read_only:false; response_revision:4783; number_of_response:1; }","duration":"135.845935ms","start":"2026-04-07T15:20:51.865109Z","end":"2026-04-07T15:20:52.000955Z","steps":["trace[2120295149] 'process raft request' (duration: 135.477397ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:20:53.455081Z","caller":"traceutil/trace.go:171","msg":"trace[1549001000] transaction","detail":"{read_only:false; response_revision:4792; number_of_response:1; }","duration":"115.470893ms","start":"2026-04-07T15:20:53.339586Z","end":"2026-04-07T15:20:53.455056Z","steps":["trace[1549001000] 'process raft request' (duration: 104.863917ms)","trace[1549001000] 'compare' (duration: 10.490793ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:05.402361Z","caller":"traceutil/trace.go:171","msg":"trace[1480324009] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"182.558385ms","start":"2026-04-07T15:21:05.21978Z","end":"2026-04-07T15:21:05.402339Z","steps":["trace[1480324009] 'process raft request' (duration: 182.35799ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:05.431096Z","caller":"traceutil/trace.go:171","msg":"trace[1932306242] linearizableReadLoop","detail":"{readStateIndex:5141; appliedIndex:5139; }","duration":"112.587939ms","start":"2026-04-07T15:21:05.318454Z","end":"2026-04-07T15:21:05.431042Z","steps":["trace[1932306242] 'read index received' (duration: 83.71689ms)","trace[1932306242] 'applied index is now lower than readState.Index' (duration: 28.869749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:05.431477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.000588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-04-07T15:21:05.431538Z","caller":"traceutil/trace.go:171","msg":"trace[824472640] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4953; }","duration":"113.591651ms","start":"2026-04-07T15:21:05.317933Z","end":"2026-04-07T15:21:05.431525Z","steps":["trace[824472640] 'agreement among raft nodes before linearized reading' (duration: 113.320195ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:05.431898Z","caller":"traceutil/trace.go:171","msg":"trace[248269168] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"124.632479ms","start":"2026-04-07T15:21:05.307212Z","end":"2026-04-07T15:21:05.431844Z","steps":["trace[248269168] 'process raft request' (duration: 123.665308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:06.024174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.590916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T15:21:06.024366Z","caller":"traceutil/trace.go:171","msg":"trace[1064617972] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"350.020592ms","start":"2026-04-07T15:21:05.674327Z","end":"2026-04-07T15:21:06.024348Z","steps":["trace[1064617972] 'process raft request' (duration: 349.959281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:06.02444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:05.674304Z","time spent":"350.103615ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T15:21:06.024617Z","caller":"traceutil/trace.go:171","msg":"trace[1153235604] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"435.067081ms","start":"2026-04-07T15:21:05.58951Z","end":"2026-04-07T15:21:06.024577Z","steps":["trace[1153235604] 'process raft request' (duration: 116.982575ms)","trace[1153235604] 'compare' (duration: 317.274079ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:06.024736Z","caller":"traceutil/trace.go:171","msg":"trace[2019842471] linearizableReadLoop","detail":"{readStateIndex:5149; appliedIndex:5148; }","duration":"408.657924ms","start":"2026-04-07T15:21:05.616037Z","end":"2026-04-07T15:21:06.024695Z","steps":["trace[2019842471] 'read index received' (duration: 90.609948ms)","trace[2019842471] 'applied index is now lower than readState.Index' (duration: 318.044596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:06.024849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:05.589486Z","time spent":"435.250766ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T15:21:06.024977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.94399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-07T15:21:06.025031Z","caller":"traceutil/trace.go:171","msg":"trace[244556944] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4962; }","duration":"409.034792ms","start":"2026-04-07T15:21:05.615981Z","end":"2026-04-07T15:21:06.025015Z","steps":["trace[244556944] 'agreement among raft nodes before linearized reading' (duration: 408.896219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T15:21:06.025062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:21:05.615961Z","time spent":"409.094383ms","remote":"127.0.0.1:38494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3267,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-04-07T15:21:06.025148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.120403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-04-07T15:21:06.025202Z","caller":"traceutil/trace.go:171","msg":"trace[711845666] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4962; }","duration":"195.251975ms","start":"2026-04-07T15:21:05.829935Z","end":"2026-04-07T15:21:06.025187Z","steps":["trace[711845666] 'agreement among raft nodes before linearized reading' (duration: 194.98103ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:21:06.186795Z","caller":"traceutil/trace.go:171","msg":"trace[977444949] linearizableReadLoop","detail":"{readStateIndex:5151; appliedIndex:5150; }","duration":"143.279211ms","start":"2026-04-07T15:21:06.043492Z","end":"2026-04-07T15:21:06.186771Z","steps":["trace[977444949] 'read index received' (duration: 83.03476ms)","trace[977444949] 'applied index is now lower than readState.Index' (duration: 60.243371ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T15:21:06.186934Z","caller":"traceutil/trace.go:171","msg":"trace[2115328543] transaction","detail":"{read_only:false; response_revision:4963; number_of_response:1; }","duration":"145.775355ms","start":"2026-04-07T15:21:06.041144Z","end":"2026-04-07T15:21:06.186919Z","steps":["trace[2115328543] 'process raft request' (duration: 85.441922ms)","trace[2115328543] 'compare' (duration: 60.050797ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T15:21:06.187266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.750671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-04-07T15:21:06.187304Z","caller":"traceutil/trace.go:171","msg":"trace[1615883531] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4963; }","duration":"143.825153ms","start":"2026-04-07T15:21:06.043468Z","end":"2026-04-07T15:21:06.187294Z","steps":["trace[1615883531] 'agreement among raft nodes before linearized reading' (duration: 143.68546ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:22:19.133676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2906} {"level":"info","ts":"2026-04-07T15:22:19.205957Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2906,"took":"70.512089ms","hash":2296112066,"current-db-size-bytes":54112256,"current-db-size":"54 MB","current-db-size-in-use-bytes":29327360,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-07T15:22:19.206036Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2296112066,"revision":2906,"compact-revision":-1} {"level":"info","ts":"2026-04-07T15:24:46.74222Z","caller":"traceutil/trace.go:171","msg":"trace[1380093305] transaction","detail":"{read_only:false; response_revision:6573; number_of_response:1; }","duration":"119.362065ms","start":"2026-04-07T15:24:46.622838Z","end":"2026-04-07T15:24:46.7422Z","steps":["trace[1380093305] 'process raft request' (duration: 119.245433ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T15:27:19.141479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5552} {"level":"info","ts":"2026-04-07T15:27:19.211844Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5552,"took":"68.964468ms","hash":3435293311,"current-db-size-bytes":54112256,"current-db-size":"54 MB","current-db-size-in-use-bytes":18587648,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-04-07T15:27:19.211888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3435293311,"revision":5552,"compact-revision":2906}