{"level":"warn","ts":"2026-05-29T11:21:29.177428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T11:21:29.177513Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.156:2380","--initial-cluster=instance=https://199.19.213.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.156:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-29T11:21:29.177573Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T11:21:29.177585Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-05-29T11:21:29.177602Z","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-05-29T11:21:29.178248Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"]} {"level":"info","ts":"2026-05-29T11:21:29.178326Z","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.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.156:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-29T11:21:29.183072Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.562453ms"} {"level":"info","ts":"2026-05-29T11:21:29.188298Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"163ef968c92247ab","cluster-id":"59d11c119c278888"} {"level":"info","ts":"2026-05-29T11:21:29.188372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T11:21:29.188394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 0"} {"level":"info","ts":"2026-05-29T11:21:29.188411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 163ef968c92247ab [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T11:21:29.188423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became follower at term 1"} {"level":"info","ts":"2026-05-29T11:21:29.188462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"warn","ts":"2026-05-29T11:21:29.194739Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T11:21:29.197727Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T11:21:29.198899Z","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-05-29T11:21:29.201077Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"163ef968c92247ab","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T11:21:29.20147Z","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-05-29T11:21:29.201559Z","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-05-29T11:21:29.201577Z","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-05-29T11:21:29.201752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"163ef968c92247ab","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T11:21:29.203804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab switched to configuration voters=(1602992745836857259)"} {"level":"info","ts":"2026-05-29T11:21:29.204165Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","added-peer-id":"163ef968c92247ab","added-peer-peer-urls":["https://199.19.213.156:2380"]} {"level":"info","ts":"2026-05-29T11:21:29.209411Z","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-05-29T11:21:29.209535Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-05-29T11:21:29.209577Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.156:2380"} {"level":"info","ts":"2026-05-29T11:21:29.210279Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T11:21:29.210223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"163ef968c92247ab","initial-advertise-peer-urls":["https://199.19.213.156:2380"],"listen-peer-urls":["https://199.19.213.156:2380"],"advertise-client-urls":["https://199.19.213.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T11:21:29.489787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T11:21:29.489841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T11:21:29.489886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgPreVoteResp from 163ef968c92247ab at term 1"} {"level":"info","ts":"2026-05-29T11:21:29.489905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became candidate at term 2"} {"level":"info","ts":"2026-05-29T11:21:29.489921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab received MsgVoteResp from 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-05-29T11:21:29.489939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"163ef968c92247ab became leader at term 2"} {"level":"info","ts":"2026-05-29T11:21:29.48999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 163ef968c92247ab elected leader 163ef968c92247ab at term 2"} {"level":"info","ts":"2026-05-29T11:21:29.607454Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"163ef968c92247ab","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.156:2379]}","request-path":"/0/members/163ef968c92247ab/attributes","cluster-id":"59d11c119c278888","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T11:21:29.607474Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T11:21:29.607579Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T11:21:29.607514Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:21:29.607968Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T11:21:29.608152Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T11:21:29.610645Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.156:2379"} {"level":"info","ts":"2026-05-29T11:21:29.610929Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"59d11c119c278888","local-member-id":"163ef968c92247ab","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:21:29.611056Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:21:29.611122Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T11:21:29.611345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T11:21:34.757718Z","caller":"traceutil/trace.go:171","msg":"trace[345085855] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"158.125366ms","start":"2026-05-29T11:21:34.599568Z","end":"2026-05-29T11:21:34.757693Z","steps":["trace[345085855] 'read index received' (duration: 157.990061ms)","trace[345085855] 'applied index is now lower than readState.Index' (duration: 134.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:21:34.757757Z","caller":"traceutil/trace.go:171","msg":"trace[857123375] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"233.328513ms","start":"2026-05-29T11:21:34.524405Z","end":"2026-05-29T11:21:34.757733Z","steps":["trace[857123375] 'process raft request' (duration: 233.166697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:34.757862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.28444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:21:34.757917Z","caller":"traceutil/trace.go:171","msg":"trace[1823791801] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:227; }","duration":"158.353791ms","start":"2026-05-29T11:21:34.599555Z","end":"2026-05-29T11:21:34.757909Z","steps":["trace[1823791801] 'agreement among raft nodes before linearized reading' (duration: 158.246209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:34.82161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.020823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-29T11:21:34.82161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.446032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:21:34.821666Z","caller":"traceutil/trace.go:171","msg":"trace[1641465022] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:227; }","duration":"122.113866ms","start":"2026-05-29T11:21:34.699541Z","end":"2026-05-29T11:21:34.821655Z","steps":["trace[1641465022] 'agreement among raft nodes before linearized reading' (duration: 122.018783ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:34.82168Z","caller":"traceutil/trace.go:171","msg":"trace[808848640] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:227; }","duration":"123.536565ms","start":"2026-05-29T11:21:34.698126Z","end":"2026-05-29T11:21:34.821663Z","steps":["trace[808848640] 'agreement among raft nodes before linearized reading' (duration: 123.448623ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:35.133579Z","caller":"traceutil/trace.go:171","msg":"trace[1658191325] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"335.954148ms","start":"2026-05-29T11:21:34.797595Z","end":"2026-05-29T11:21:35.133549Z","steps":["trace[1658191325] 'process raft request' (duration: 335.766513ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:35.133604Z","caller":"traceutil/trace.go:171","msg":"trace[861373027] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"320.390671ms","start":"2026-05-29T11:21:34.81319Z","end":"2026-05-29T11:21:35.13358Z","steps":["trace[861373027] 'process raft request' (duration: 320.268868ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:35.133669Z","caller":"traceutil/trace.go:171","msg":"trace[1730295821] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:234; }","duration":"312.089072ms","start":"2026-05-29T11:21:34.821553Z","end":"2026-05-29T11:21:35.133642Z","steps":["trace[1730295821] 'read index received' (duration: 311.885757ms)","trace[1730295821] 'applied index is now lower than readState.Index' (duration: 202.365µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:21:35.133706Z","caller":"traceutil/trace.go:171","msg":"trace[784848068] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"320.258897ms","start":"2026-05-29T11:21:34.813439Z","end":"2026-05-29T11:21:35.133698Z","steps":["trace[784848068] 'process raft request' (duration: 320.042791ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:35.133783Z","caller":"traceutil/trace.go:171","msg":"trace[1377789213] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"319.811574ms","start":"2026-05-29T11:21:34.813951Z","end":"2026-05-29T11:21:35.133762Z","steps":["trace[1377789213] 'process raft request' (duration: 319.650059ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:35.133821Z","caller":"traceutil/trace.go:171","msg":"trace[134417762] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"320.303488ms","start":"2026-05-29T11:21:34.81351Z","end":"2026-05-29T11:21:35.133813Z","steps":["trace[134417762] 'process raft request' (duration: 319.99086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.133825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.487522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:21:35.133849Z","caller":"traceutil/trace.go:171","msg":"trace[844474914] transaction","detail":"{read_only:false; number_of_response:0; response_revision:228; }","duration":"320.212415ms","start":"2026-05-29T11:21:34.813631Z","end":"2026-05-29T11:21:35.133843Z","steps":["trace[844474914] 'process raft request' (duration: 319.944748ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:21:35.133863Z","caller":"traceutil/trace.go:171","msg":"trace[1294850687] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:229; }","duration":"368.566164ms","start":"2026-05-29T11:21:34.765285Z","end":"2026-05-29T11:21:35.133851Z","steps":["trace[1294850687] 'agreement among raft nodes before linearized reading' (duration: 368.477762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.133895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.765246Z","time spent":"368.641446ms","remote":"127.0.0.1:57866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/coredns\" "} {"level":"warn","ts":"2026-05-29T11:21:35.133935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.339595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:21:35.133968Z","caller":"traceutil/trace.go:171","msg":"trace[2045292957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:229; }","duration":"373.398886ms","start":"2026-05-29T11:21:34.760559Z","end":"2026-05-29T11:21:35.133958Z","steps":["trace[2045292957] 'agreement among raft nodes before linearized reading' (duration: 373.321564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.133994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.760555Z","time spent":"373.432568ms","remote":"127.0.0.1:57776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T11:21:35.134081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.813167Z","time spent":"320.494843ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T11:21:35.134224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.696485ms","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-05-29T11:21:35.134253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.797572Z","time spent":"336.087252ms","remote":"127.0.0.1:57946","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T11:21:35.134319Z","caller":"traceutil/trace.go:171","msg":"trace[1146665051] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:229; }","duration":"373.821448ms","start":"2026-05-29T11:21:34.760446Z","end":"2026-05-29T11:21:35.134267Z","steps":["trace[1146665051] 'agreement among raft nodes before linearized reading' (duration: 373.638072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.134358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.813423Z","time spent":"320.320848ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T11:21:35.134402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.760428Z","time spent":"373.927321ms","remote":"127.0.0.1:58018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T11:21:35.134465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.813939Z","time spent":"319.866886ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T11:21:35.134551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.813502Z","time spent":"320.334919ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T11:21:35.134633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.813618Z","time spent":"320.248655ms","remote":"127.0.0.1:57958","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T11:21:35.143707Z","caller":"traceutil/trace.go:171","msg":"trace[739940222] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"321.142577ms","start":"2026-05-29T11:21:34.822548Z","end":"2026-05-29T11:21:35.14369Z","steps":["trace[739940222] 'process raft request' (duration: 320.999464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.143791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.822529Z","time spent":"321.21762ms","remote":"127.0.0.1:57832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T11:21:35.143928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.164544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-05-29T11:21:35.14399Z","caller":"traceutil/trace.go:171","msg":"trace[1902830473] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:231; }","duration":"319.243016ms","start":"2026-05-29T11:21:34.824734Z","end":"2026-05-29T11:21:35.143977Z","steps":["trace[1902830473] 'agreement among raft nodes before linearized reading' (duration: 318.989979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.143971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.335615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-05-29T11:21:35.144034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.82473Z","time spent":"319.298187ms","remote":"127.0.0.1:57946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4039,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-29T11:21:35.144089Z","caller":"traceutil/trace.go:171","msg":"trace[1047226175] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:231; }","duration":"320.48092ms","start":"2026-05-29T11:21:34.823579Z","end":"2026-05-29T11:21:35.14406Z","steps":["trace[1047226175] 'agreement among raft nodes before linearized reading' (duration: 320.216972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.144155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.823572Z","time spent":"320.574912ms","remote":"127.0.0.1:58150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-29T11:21:35.14446Z","caller":"traceutil/trace.go:171","msg":"trace[1375940166] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"321.811176ms","start":"2026-05-29T11:21:34.822624Z","end":"2026-05-29T11:21:35.144435Z","steps":["trace[1375940166] 'process raft request' (duration: 321.023114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:21:35.144584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:21:34.822594Z","time spent":"321.926949ms","remote":"127.0.0.1:57832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":753,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T11:22:06.082858Z","caller":"traceutil/trace.go:171","msg":"trace[779086725] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"112.489249ms","start":"2026-05-29T11:22:05.970345Z","end":"2026-05-29T11:22:06.082834Z","steps":["trace[779086725] 'process raft request' (duration: 103.098451ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:22:06.085244Z","caller":"traceutil/trace.go:171","msg":"trace[1111046320] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"113.659142ms","start":"2026-05-29T11:22:05.971573Z","end":"2026-05-29T11:22:06.085232Z","steps":["trace[1111046320] 'process raft request' (duration: 113.208794ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:22:10.274042Z","caller":"traceutil/trace.go:171","msg":"trace[562558025] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"156.113332ms","start":"2026-05-29T11:22:10.117911Z","end":"2026-05-29T11:22:10.274024Z","steps":["trace[562558025] 'read index received' (duration: 155.446549ms)","trace[562558025] 'applied index is now lower than readState.Index' (duration: 666.003µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:22:10.274689Z","caller":"traceutil/trace.go:171","msg":"trace[1177470544] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"201.087012ms","start":"2026-05-29T11:22:10.073594Z","end":"2026-05-29T11:22:10.274681Z","steps":["trace[1177470544] 'process raft request' (duration: 199.968253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.274839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.910796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.274868Z","caller":"traceutil/trace.go:171","msg":"trace[346971194] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"156.953456ms","start":"2026-05-29T11:22:10.117906Z","end":"2026-05-29T11:22:10.27486Z","steps":["trace[346971194] 'agreement among raft nodes before linearized reading' (duration: 156.893235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.274977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.004348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275184Z","caller":"traceutil/trace.go:171","msg":"trace[167169093] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"157.229713ms","start":"2026-05-29T11:22:10.11795Z","end":"2026-05-29T11:22:10.275179Z","steps":["trace[167169093] 'agreement among raft nodes before linearized reading' (duration: 157.009609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.468545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275299Z","caller":"traceutil/trace.go:171","msg":"trace[327729670] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"112.503766ms","start":"2026-05-29T11:22:10.162789Z","end":"2026-05-29T11:22:10.275292Z","steps":["trace[327729670] 'agreement among raft nodes before linearized reading' (duration: 112.457535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.364411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275425Z","caller":"traceutil/trace.go:171","msg":"trace[966441131] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"113.393091ms","start":"2026-05-29T11:22:10.162026Z","end":"2026-05-29T11:22:10.275419Z","steps":["trace[966441131] 'agreement among raft nodes before linearized reading' (duration: 113.35221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.566815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275536Z","caller":"traceutil/trace.go:171","msg":"trace[1128531546] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"113.604535ms","start":"2026-05-29T11:22:10.161926Z","end":"2026-05-29T11:22:10.27553Z","steps":["trace[1128531546] 'agreement among raft nodes before linearized reading' (duration: 113.567304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.208852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275653Z","caller":"traceutil/trace.go:171","msg":"trace[1333225260] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:575; }","duration":"134.245192ms","start":"2026-05-29T11:22:10.1414Z","end":"2026-05-29T11:22:10.275645Z","steps":["trace[1333225260] 'agreement among raft nodes before linearized reading' (duration: 134.206131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.086364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275786Z","caller":"traceutil/trace.go:171","msg":"trace[271817781] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:575; }","duration":"137.122554ms","start":"2026-05-29T11:22:10.138658Z","end":"2026-05-29T11:22:10.27578Z","steps":["trace[271817781] 'agreement among raft nodes before linearized reading' (duration: 137.084614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.251237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.275901Z","caller":"traceutil/trace.go:171","msg":"trace[2036856093] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:575; }","duration":"137.277557ms","start":"2026-05-29T11:22:10.138617Z","end":"2026-05-29T11:22:10.275895Z","steps":["trace[2036856093] 'agreement among raft nodes before linearized reading' (duration: 137.241367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:10.275984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.075349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:10.27601Z","caller":"traceutil/trace.go:171","msg":"trace[691081664] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:575; }","duration":"157.102409ms","start":"2026-05-29T11:22:10.118901Z","end":"2026-05-29T11:22:10.276003Z","steps":["trace[691081664] 'agreement among raft nodes before linearized reading' (duration: 157.065559ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:22:12.897604Z","caller":"traceutil/trace.go:171","msg":"trace[790601073] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"214.683961ms","start":"2026-05-29T11:22:12.682892Z","end":"2026-05-29T11:22:12.897576Z","steps":["trace[790601073] 'read index received' (duration: 214.500588ms)","trace[790601073] 'applied index is now lower than readState.Index' (duration: 182.353µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:22:12.897839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.053437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.156\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-29T11:22:12.89775Z","caller":"traceutil/trace.go:171","msg":"trace[360526496] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"433.933413ms","start":"2026-05-29T11:22:12.463763Z","end":"2026-05-29T11:22:12.897696Z","steps":["trace[360526496] 'process raft request' (duration: 433.683679ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:22:12.897882Z","caller":"traceutil/trace.go:171","msg":"trace[180523275] range","detail":"{range_begin:/registry/masterleases/199.19.213.156; range_end:; response_count:1; response_revision:592; }","duration":"135.143778ms","start":"2026-05-29T11:22:12.762725Z","end":"2026-05-29T11:22:12.897868Z","steps":["trace[180523275] 'agreement among raft nodes before linearized reading' (duration: 135.014266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:12.897869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.954316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:22:12.897956Z","caller":"traceutil/trace.go:171","msg":"trace[234909861] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"215.053488ms","start":"2026-05-29T11:22:12.682878Z","end":"2026-05-29T11:22:12.897931Z","steps":["trace[234909861] 'agreement among raft nodes before linearized reading' (duration: 214.858195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:22:12.898009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T11:22:12.463738Z","time spent":"434.173547ms","remote":"127.0.0.1:58262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T11:23:56.231878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.853743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-29T11:23:56.231928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.917573ms","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-05-29T11:23:56.231977Z","caller":"traceutil/trace.go:171","msg":"trace[931777167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"146.995313ms","start":"2026-05-29T11:23:56.084959Z","end":"2026-05-29T11:23:56.231954Z","steps":["trace[931777167] 'range keys from in-memory index tree' (duration: 146.66032ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:23:56.231995Z","caller":"traceutil/trace.go:171","msg":"trace[2128041184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"147.027144ms","start":"2026-05-29T11:23:56.084949Z","end":"2026-05-29T11:23:56.231976Z","steps":["trace[2128041184] 'range keys from in-memory index tree' (duration: 146.74786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:23:56.231877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.548189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-29T11:23:56.232076Z","caller":"traceutil/trace.go:171","msg":"trace[2013055809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"172.795252ms","start":"2026-05-29T11:23:56.05926Z","end":"2026-05-29T11:23:56.232055Z","steps":["trace[2013055809] 'range keys from in-memory index tree' (duration: 172.338538ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:23:56.446857Z","caller":"traceutil/trace.go:171","msg":"trace[45790098] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"124.432723ms","start":"2026-05-29T11:23:56.322377Z","end":"2026-05-29T11:23:56.446809Z","steps":["trace[45790098] 'process raft request' (duration: 124.150621ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:24:26.189691Z","caller":"traceutil/trace.go:171","msg":"trace[388720885] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"106.980104ms","start":"2026-05-29T11:24:26.082677Z","end":"2026-05-29T11:24:26.189657Z","steps":["trace[388720885] 'process raft request' (duration: 106.798602ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:24:26.771485Z","caller":"traceutil/trace.go:171","msg":"trace[787304590] linearizableReadLoop","detail":"{readStateIndex:2171; appliedIndex:2168; }","duration":"147.29579ms","start":"2026-05-29T11:24:26.624143Z","end":"2026-05-29T11:24:26.771439Z","steps":["trace[787304590] 'read index received' (duration: 58.620386ms)","trace[787304590] 'applied index is now lower than readState.Index' (duration: 88.674304ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:24:26.771693Z","caller":"traceutil/trace.go:171","msg":"trace[50522321] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"241.174444ms","start":"2026-05-29T11:24:26.530497Z","end":"2026-05-29T11:24:26.771671Z","steps":["trace[50522321] 'process raft request' (duration: 152.184417ms)","trace[50522321] 'compare' (duration: 88.298571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:24:26.771787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.621493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-29T11:24:26.77185Z","caller":"traceutil/trace.go:171","msg":"trace[1543046847] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2085; }","duration":"147.730074ms","start":"2026-05-29T11:24:26.624101Z","end":"2026-05-29T11:24:26.771831Z","steps":["trace[1543046847] 'agreement among raft nodes before linearized reading' (duration: 147.479772ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:24:26.77201Z","caller":"traceutil/trace.go:171","msg":"trace[1953622591] transaction","detail":"{read_only:false; response_revision:2083; number_of_response:1; }","duration":"241.261634ms","start":"2026-05-29T11:24:26.530733Z","end":"2026-05-29T11:24:26.771995Z","steps":["trace[1953622591] 'process raft request' (duration: 240.527548ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:24:26.772133Z","caller":"traceutil/trace.go:171","msg":"trace[757229311] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"241.260384ms","start":"2026-05-29T11:24:26.530861Z","end":"2026-05-29T11:24:26.772121Z","steps":["trace[757229311] 'process raft request' (duration: 240.480818ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:24:26.772177Z","caller":"traceutil/trace.go:171","msg":"trace[1438880506] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"117.146701ms","start":"2026-05-29T11:24:26.654994Z","end":"2026-05-29T11:24:26.772141Z","steps":["trace[1438880506] 'process raft request' (duration: 116.395105ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:25:36.640532Z","caller":"traceutil/trace.go:171","msg":"trace[2037094144] transaction","detail":"{read_only:false; response_revision:2550; number_of_response:1; }","duration":"107.869699ms","start":"2026-05-29T11:25:36.532618Z","end":"2026-05-29T11:25:36.640488Z","steps":["trace[2037094144] 'process raft request' (duration: 107.599484ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:26:14.398633Z","caller":"traceutil/trace.go:171","msg":"trace[2051975065] transaction","detail":"{read_only:false; response_revision:2769; number_of_response:1; }","duration":"170.649288ms","start":"2026-05-29T11:26:14.227965Z","end":"2026-05-29T11:26:14.398614Z","steps":["trace[2051975065] 'process raft request' (duration: 170.24805ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:00.736658Z","caller":"traceutil/trace.go:171","msg":"trace[358072015] transaction","detail":"{read_only:false; response_revision:4583; number_of_response:1; }","duration":"100.461358ms","start":"2026-05-29T11:30:00.636181Z","end":"2026-05-29T11:30:00.736643Z","steps":["trace[358072015] 'process raft request' (duration: 98.454878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:00.737424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.923183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-29T11:30:00.737492Z","caller":"traceutil/trace.go:171","msg":"trace[1311084971] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4584; }","duration":"101.221327ms","start":"2026-05-29T11:30:00.636262Z","end":"2026-05-29T11:30:00.737483Z","steps":["trace[1311084971] 'agreement among raft nodes before linearized reading' (duration: 100.894004ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:09.313507Z","caller":"traceutil/trace.go:171","msg":"trace[582321030] transaction","detail":"{read_only:false; response_revision:4738; number_of_response:1; }","duration":"110.134163ms","start":"2026-05-29T11:30:09.203348Z","end":"2026-05-29T11:30:09.313483Z","steps":["trace[582321030] 'process raft request' (duration: 109.78697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:09.313762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.354547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:30:09.313843Z","caller":"traceutil/trace.go:171","msg":"trace[715822466] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:4738; }","duration":"105.490298ms","start":"2026-05-29T11:30:09.208332Z","end":"2026-05-29T11:30:09.313822Z","steps":["trace[715822466] 'agreement among raft nodes before linearized reading' (duration: 105.272246ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:09.313334Z","caller":"traceutil/trace.go:171","msg":"trace[477773168] linearizableReadLoop","detail":"{readStateIndex:4931; appliedIndex:4930; }","duration":"104.938863ms","start":"2026-05-29T11:30:09.208372Z","end":"2026-05-29T11:30:09.31331Z","steps":["trace[477773168] 'read index received' (duration: 104.702771ms)","trace[477773168] 'applied index is now lower than readState.Index' (duration: 234.792µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T11:30:09.364247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.809551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-05-29T11:30:09.364333Z","caller":"traceutil/trace.go:171","msg":"trace[1811564426] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4740; }","duration":"100.964673ms","start":"2026-05-29T11:30:09.263348Z","end":"2026-05-29T11:30:09.364313Z","steps":["trace[1811564426] 'agreement among raft nodes before linearized reading' (duration: 100.750281ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:09.364713Z","caller":"traceutil/trace.go:171","msg":"trace[1840520830] transaction","detail":"{read_only:false; response_revision:4739; number_of_response:1; }","duration":"151.234513ms","start":"2026-05-29T11:30:09.213468Z","end":"2026-05-29T11:30:09.364702Z","steps":["trace[1840520830] 'process raft request' (duration: 150.435015ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:09.900025Z","caller":"traceutil/trace.go:171","msg":"trace[1154776821] linearizableReadLoop","detail":"{readStateIndex:4949; appliedIndex:4946; }","duration":"247.761892ms","start":"2026-05-29T11:30:09.65224Z","end":"2026-05-29T11:30:09.900002Z","steps":["trace[1154776821] 'read index received' (duration: 7.250042ms)","trace[1154776821] 'applied index is now lower than readState.Index' (duration: 240.51077ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:30:09.900083Z","caller":"traceutil/trace.go:171","msg":"trace[1863584511] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"248.59219ms","start":"2026-05-29T11:30:09.651465Z","end":"2026-05-29T11:30:09.900057Z","steps":["trace[1863584511] 'process raft request' (duration: 235.082125ms)","trace[1863584511] 'compare' (duration: 13.108931ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T11:30:09.900082Z","caller":"traceutil/trace.go:171","msg":"trace[1266338605] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"248.127116ms","start":"2026-05-29T11:30:09.651915Z","end":"2026-05-29T11:30:09.900042Z","steps":["trace[1266338605] 'process raft request' (duration: 248.000435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:09.900566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.309907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130998"} {"level":"info","ts":"2026-05-29T11:30:09.90063Z","caller":"traceutil/trace.go:171","msg":"trace[2100707375] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:4756; }","duration":"248.409179ms","start":"2026-05-29T11:30:09.652208Z","end":"2026-05-29T11:30:09.900618Z","steps":["trace[2100707375] 'agreement among raft nodes before linearized reading' (duration: 247.899133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T11:30:09.928657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.928535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T11:30:09.928729Z","caller":"traceutil/trace.go:171","msg":"trace[147010576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4757; }","duration":"245.051765ms","start":"2026-05-29T11:30:09.683662Z","end":"2026-05-29T11:30:09.928714Z","steps":["trace[147010576] 'agreement among raft nodes before linearized reading' (duration: 244.952134ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:30:22.352366Z","caller":"traceutil/trace.go:171","msg":"trace[1456162590] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"112.009717ms","start":"2026-05-29T11:30:22.240335Z","end":"2026-05-29T11:30:22.352344Z","steps":["trace[1456162590] 'process raft request' (duration: 111.856126ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T11:31:29.996744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2852} {"level":"info","ts":"2026-05-29T11:31:30.065851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2852,"took":"67.908467ms","hash":350589372,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":28995584,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-29T11:31:30.065904Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":350589372,"revision":2852,"compact-revision":-1} {"level":"info","ts":"2026-05-29T11:36:30.00534Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5545} {"level":"info","ts":"2026-05-29T11:36:30.081127Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5545,"took":"74.571122ms","hash":2536529431,"current-db-size-bytes":53153792,"current-db-size":"53 MB","current-db-size-in-use-bytes":18493440,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-29T11:36:30.081202Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2536529431,"revision":5545,"compact-revision":2852}