{"level":"warn","ts":"2026-09-07T10:37:41.268464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:37:41.268659Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.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://162.253.55.156:2380","--initial-cluster=instance=https://162.253.55.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.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-09-07T10:37:41.268776Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-07T10:37:41.268813Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-07T10:37:41.268859Z","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-09-07T10:37:41.269688Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"]} {"level":"info","ts":"2026-09-07T10:37:41.269823Z","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://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.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-09-07T10:37:41.274191Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.016762ms"} {"level":"info","ts":"2026-09-07T10:37:41.283156Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"40fb1eca294b6a5a","cluster-id":"17c6999805fcc0eb"} {"level":"info","ts":"2026-09-07T10:37:41.283355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=()"} {"level":"info","ts":"2026-09-07T10:37:41.28347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 0"} {"level":"info","ts":"2026-09-07T10:37:41.283512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 40fb1eca294b6a5a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-07T10:37:41.283572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became follower at term 1"} {"level":"info","ts":"2026-09-07T10:37:41.283677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"warn","ts":"2026-09-07T10:37:41.291166Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-07T10:37:41.293195Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-07T10:37:41.294839Z","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-09-07T10:37:41.298949Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"40fb1eca294b6a5a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-07T10:37:41.299268Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"40fb1eca294b6a5a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-07T10:37:41.29944Z","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-09-07T10:37:41.299581Z","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-09-07T10:37:41.300575Z","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-09-07T10:37:41.301574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a switched to configuration voters=(4682370091206797914)"} {"level":"info","ts":"2026-09-07T10:37:41.301897Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","added-peer-id":"40fb1eca294b6a5a","added-peer-peer-urls":["https://162.253.55.156:2380"]} {"level":"info","ts":"2026-09-07T10:37:41.303955Z","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-09-07T10:37:41.304091Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-07T10:37:41.304283Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"40fb1eca294b6a5a","initial-advertise-peer-urls":["https://162.253.55.156:2380"],"listen-peer-urls":["https://162.253.55.156:2380"],"advertise-client-urls":["https://162.253.55.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-07T10:37:41.304362Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-07T10:37:41.304151Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.156:2380"} {"level":"info","ts":"2026-09-07T10:37:42.284806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a is starting a new election at term 1"} {"level":"info","ts":"2026-09-07T10:37:42.284873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-07T10:37:42.284908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgPreVoteResp from 40fb1eca294b6a5a at term 1"} {"level":"info","ts":"2026-09-07T10:37:42.284927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became candidate at term 2"} {"level":"info","ts":"2026-09-07T10:37:42.284938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a received MsgVoteResp from 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-07T10:37:42.284953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"40fb1eca294b6a5a became leader at term 2"} {"level":"info","ts":"2026-09-07T10:37:42.284966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 40fb1eca294b6a5a elected leader 40fb1eca294b6a5a at term 2"} {"level":"info","ts":"2026-09-07T10:37:42.285996Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"40fb1eca294b6a5a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.156:2379]}","request-path":"/0/members/40fb1eca294b6a5a/attributes","cluster-id":"17c6999805fcc0eb","publish-timeout":"7s"} {"level":"info","ts":"2026-09-07T10:37:42.286126Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:37:42.286236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:37:42.286231Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-07T10:37:42.28674Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-07T10:37:42.286792Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-07T10:37:42.288359Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.156:2379"} {"level":"info","ts":"2026-09-07T10:37:42.289649Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-07T10:37:42.28975Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"17c6999805fcc0eb","local-member-id":"40fb1eca294b6a5a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:37:42.289859Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-07T10:37:42.289896Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-09-07T10:37:44.11394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.928946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-09-07T10:37:44.114172Z","caller":"traceutil/trace.go:171","msg":"trace[421158425] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"321.318235ms","start":"2026-09-07T10:37:43.792831Z","end":"2026-09-07T10:37:44.114149Z","steps":["trace[421158425] 'read index received' (duration: 51.263353ms)","trace[421158425] 'applied index is now lower than readState.Index' (duration: 270.053582ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:37:44.114288Z","caller":"traceutil/trace.go:171","msg":"trace[847478211] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"329.966373ms","start":"2026-09-07T10:37:43.784307Z","end":"2026-09-07T10:37:44.114273Z","steps":["trace[847478211] 'process raft request' (duration: 216.107814ms)","trace[847478211] 'compare' (duration: 112.791164ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:37:44.114422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:37:43.784282Z","time spent":"330.093725ms","remote":"127.0.0.1:59604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:37:44.11494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.038846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.115049Z","caller":"traceutil/trace.go:171","msg":"trace[111157585] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"322.223389ms","start":"2026-09-07T10:37:43.792804Z","end":"2026-09-07T10:37:44.115028Z","steps":["trace[111157585] 'agreement among raft nodes before linearized reading' (duration: 321.892535ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.115095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:37:43.79279Z","time spent":"322.29479ms","remote":"127.0.0.1:59942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-09-07T10:37:44.11686Z","caller":"traceutil/trace.go:171","msg":"trace[1106771178] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"264.402289ms","start":"2026-09-07T10:37:43.852431Z","end":"2026-09-07T10:37:44.116834Z","steps":["trace[1106771178] 'process raft request' (duration: 264.245837ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.11695Z","caller":"traceutil/trace.go:171","msg":"trace[135019287] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"264.51211ms","start":"2026-09-07T10:37:43.8524Z","end":"2026-09-07T10:37:44.116912Z","steps":["trace[135019287] 'process raft request' (duration: 264.197576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.117025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.939827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.117091Z","caller":"traceutil/trace.go:171","msg":"trace[212154379] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"297.11055ms","start":"2026-09-07T10:37:43.819959Z","end":"2026-09-07T10:37:44.11707Z","steps":["trace[212154379] 'agreement among raft nodes before linearized reading' (duration: 296.941597ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117249Z","caller":"traceutil/trace.go:171","msg":"trace[881853116] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"250.632047ms","start":"2026-09-07T10:37:43.866574Z","end":"2026-09-07T10:37:44.117206Z","steps":["trace[881853116] 'process raft request' (duration: 250.286542ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.117312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.508894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-07T10:37:44.117365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.131005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-07T10:37:44.117379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.335713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.117399Z","caller":"traceutil/trace.go:171","msg":"trace[281225177] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"266.187356ms","start":"2026-09-07T10:37:43.851199Z","end":"2026-09-07T10:37:44.117387Z","steps":["trace[281225177] 'agreement among raft nodes before linearized reading' (duration: 266.145576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.117382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.315244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.117442Z","caller":"traceutil/trace.go:171","msg":"trace[557591019] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"295.443015ms","start":"2026-09-07T10:37:43.821974Z","end":"2026-09-07T10:37:44.117417Z","steps":["trace[557591019] 'agreement among raft nodes before linearized reading' (duration: 295.321673ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117458Z","caller":"traceutil/trace.go:171","msg":"trace[1695201158] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"288.587385ms","start":"2026-09-07T10:37:43.828779Z","end":"2026-09-07T10:37:44.117366Z","steps":["trace[1695201158] 'agreement among raft nodes before linearized reading' (duration: 288.497484ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117497Z","caller":"traceutil/trace.go:171","msg":"trace[2092534930] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"263.479866ms","start":"2026-09-07T10:37:43.853974Z","end":"2026-09-07T10:37:44.117454Z","steps":["trace[2092534930] 'agreement among raft nodes before linearized reading' (duration: 263.329984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.117512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.558057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.117536Z","caller":"traceutil/trace.go:171","msg":"trace[71714183] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"264.806025ms","start":"2026-09-07T10:37:43.852718Z","end":"2026-09-07T10:37:44.117524Z","steps":["trace[71714183] 'process raft request' (duration: 264.072035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.117539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.868898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.117571Z","caller":"traceutil/trace.go:171","msg":"trace[197052420] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"214.663199ms","start":"2026-09-07T10:37:43.902894Z","end":"2026-09-07T10:37:44.117557Z","steps":["trace[197052420] 'agreement among raft nodes before linearized reading' (duration: 214.567698ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117592Z","caller":"traceutil/trace.go:171","msg":"trace[535799424] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"264.936067ms","start":"2026-09-07T10:37:43.852641Z","end":"2026-09-07T10:37:44.117577Z","steps":["trace[535799424] 'process raft request' (duration: 264.101215ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117605Z","caller":"traceutil/trace.go:171","msg":"trace[1375757614] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"290.950229ms","start":"2026-09-07T10:37:43.826629Z","end":"2026-09-07T10:37:44.117579Z","steps":["trace[1375757614] 'agreement among raft nodes before linearized reading' (duration: 290.866988ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117665Z","caller":"traceutil/trace.go:171","msg":"trace[1132880986] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"264.978107ms","start":"2026-09-07T10:37:43.852677Z","end":"2026-09-07T10:37:44.117655Z","steps":["trace[1132880986] 'process raft request' (duration: 264.093474ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.117705Z","caller":"traceutil/trace.go:171","msg":"trace[1110285536] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"265.089249ms","start":"2026-09-07T10:37:43.852602Z","end":"2026-09-07T10:37:44.117691Z","steps":["trace[1110285536] 'process raft request' (duration: 264.109634ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:37:44.11774Z","caller":"traceutil/trace.go:171","msg":"trace[2088162799] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"256.923869ms","start":"2026-09-07T10:37:43.860805Z","end":"2026-09-07T10:37:44.117729Z","steps":["trace[2088162799] 'process raft request' (duration: 256.011836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:37:44.117307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.110224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.156\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-07T10:37:44.117937Z","caller":"traceutil/trace.go:171","msg":"trace[296705042] range","detail":"{range_begin:/registry/masterleases/162.253.55.156; range_end:; response_count:0; response_revision:11; }","duration":"268.771523ms","start":"2026-09-07T10:37:43.849155Z","end":"2026-09-07T10:37:44.117926Z","steps":["trace[296705042] 'agreement among raft nodes before linearized reading' (duration: 268.091483ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:38:06.284403Z","caller":"traceutil/trace.go:171","msg":"trace[1377892651] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"109.485496ms","start":"2026-09-07T10:38:06.174877Z","end":"2026-09-07T10:38:06.284362Z","steps":["trace[1377892651] 'process raft request' (duration: 92.677594ms)","trace[1377892651] 'compare' (duration: 16.65077ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:38:24.730428Z","caller":"traceutil/trace.go:171","msg":"trace[2050558636] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"306.200982ms","start":"2026-09-07T10:38:24.424165Z","end":"2026-09-07T10:38:24.730366Z","steps":["trace[2050558636] 'process raft request' (duration: 306.001189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:24.730636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:38:24.424143Z","time spent":"306.389403ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:38:24.752132Z","caller":"traceutil/trace.go:171","msg":"trace[2070804525] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"105.778621ms","start":"2026-09-07T10:38:24.646319Z","end":"2026-09-07T10:38:24.752098Z","steps":["trace[2070804525] 'read index received' (duration: 83.838321ms)","trace[2070804525] 'applied index is now lower than readState.Index' (duration: 21.93912ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:38:24.752166Z","caller":"traceutil/trace.go:171","msg":"trace[2127828526] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"326.897289ms","start":"2026-09-07T10:38:24.425244Z","end":"2026-09-07T10:38:24.752141Z","steps":["trace[2127828526] 'process raft request' (duration: 326.671257ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:24.752291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.960783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:38:24.752327Z","caller":"traceutil/trace.go:171","msg":"trace[555086959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"106.035814ms","start":"2026-09-07T10:38:24.64628Z","end":"2026-09-07T10:38:24.752316Z","steps":["trace[555086959] 'agreement among raft nodes before linearized reading' (duration: 105.936503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:24.752328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:38:24.425232Z","time spent":"327.00152ms","remote":"127.0.0.1:59742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:38:24.752369Z","caller":"traceutil/trace.go:171","msg":"trace[449154893] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"319.428654ms","start":"2026-09-07T10:38:24.432894Z","end":"2026-09-07T10:38:24.752323Z","steps":["trace[449154893] 'process raft request' (duration: 319.162101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:24.752716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:38:24.432867Z","time spent":"319.632276ms","remote":"127.0.0.1:59648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1749,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-07T10:38:25.061186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.573216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:38:25.06132Z","caller":"traceutil/trace.go:171","msg":"trace[582619792] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"261.325618ms","start":"2026-09-07T10:38:24.799971Z","end":"2026-09-07T10:38:25.061296Z","steps":["trace[582619792] 'process raft request' (duration: 124.511ms)","trace[582619792] 'compare' (duration: 136.393734ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:38:25.081581Z","caller":"traceutil/trace.go:171","msg":"trace[591955483] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"256.092675ms","start":"2026-09-07T10:38:24.825459Z","end":"2026-09-07T10:38:25.081552Z","steps":["trace[591955483] 'process raft request' (duration: 255.276906ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:38:28.421223Z","caller":"traceutil/trace.go:171","msg":"trace[912899349] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"169.05524ms","start":"2026-09-07T10:38:28.252119Z","end":"2026-09-07T10:38:28.421174Z","steps":["trace[912899349] 'process raft request' (duration: 168.307243ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:38:28.937735Z","caller":"traceutil/trace.go:171","msg":"trace[1065305195] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"439.618748ms","start":"2026-09-07T10:38:28.49809Z","end":"2026-09-07T10:38:28.937709Z","steps":["trace[1065305195] 'process raft request' (duration: 358.822744ms)","trace[1065305195] 'compare' (duration: 80.666063ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:38:28.937887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:38:28.498057Z","time spent":"439.763069ms","remote":"127.0.0.1:60058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-07T10:38:28.964074Z","caller":"traceutil/trace.go:171","msg":"trace[419246237] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"315.882992ms","start":"2026-09-07T10:38:28.648167Z","end":"2026-09-07T10:38:28.96405Z","steps":["trace[419246237] 'read index received' (duration: 209.047883ms)","trace[419246237] 'applied index is now lower than readState.Index' (duration: 106.833859ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:38:28.964379Z","caller":"traceutil/trace.go:171","msg":"trace[376443335] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"463.712315ms","start":"2026-09-07T10:38:28.500653Z","end":"2026-09-07T10:38:28.964365Z","steps":["trace[376443335] 'process raft request' (duration: 463.278041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:28.964486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:38:28.500644Z","time spent":"463.771846ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:38:28.964708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.539978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:38:28.964749Z","caller":"traceutil/trace.go:171","msg":"trace[883810413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"316.633039ms","start":"2026-09-07T10:38:28.648104Z","end":"2026-09-07T10:38:28.964737Z","steps":["trace[883810413] 'agreement among raft nodes before linearized reading' (duration: 316.573159ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:28.964776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:38:28.648076Z","time spent":"316.69472ms","remote":"127.0.0.1:59530","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-09-07T10:38:28.96501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.523258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-twz9d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-07T10:38:28.965043Z","caller":"traceutil/trace.go:171","msg":"trace[911206006] range","detail":"{range_begin:/registry/pods/kube-system/cilium-twz9d; range_end:; response_count:1; response_revision:639; }","duration":"264.575498ms","start":"2026-09-07T10:38:28.700459Z","end":"2026-09-07T10:38:28.965035Z","steps":["trace[911206006] 'agreement among raft nodes before linearized reading' (duration: 264.446197ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:38:28.965487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.018312ms","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-09-07T10:38:28.965528Z","caller":"traceutil/trace.go:171","msg":"trace[74276529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"211.081172ms","start":"2026-09-07T10:38:28.754437Z","end":"2026-09-07T10:38:28.965518Z","steps":["trace[74276529] 'agreement among raft nodes before linearized reading' (duration: 210.970821ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:39:36.695261Z","caller":"traceutil/trace.go:171","msg":"trace[1759228725] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"141.120084ms","start":"2026-09-07T10:39:36.554113Z","end":"2026-09-07T10:39:36.695233Z","steps":["trace[1759228725] 'process raft request' (duration: 140.89918ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:39:57.192102Z","caller":"traceutil/trace.go:171","msg":"trace[921229062] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1738; }","duration":"185.937941ms","start":"2026-09-07T10:39:57.006139Z","end":"2026-09-07T10:39:57.192077Z","steps":["trace[921229062] 'process raft request' (duration: 97.785144ms)","trace[921229062] 'compare' (duration: 88.093916ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:39:58.363333Z","caller":"traceutil/trace.go:171","msg":"trace[399429573] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"144.844082ms","start":"2026-09-07T10:39:58.218458Z","end":"2026-09-07T10:39:58.363302Z","steps":["trace[399429573] 'process raft request' (duration: 144.293834ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:10.087037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.676471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-07T10:40:10.087207Z","caller":"traceutil/trace.go:171","msg":"trace[250373155] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"123.422618ms","start":"2026-09-07T10:40:09.963754Z","end":"2026-09-07T10:40:10.087176Z","steps":["trace[250373155] 'process raft request' (duration: 17.462653ms)","trace[250373155] 'compare' (duration: 105.478768ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:10.520728Z","caller":"traceutil/trace.go:171","msg":"trace[655870244] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"413.694412ms","start":"2026-09-07T10:40:10.106997Z","end":"2026-09-07T10:40:10.520691Z","steps":["trace[655870244] 'process raft request' (duration: 319.205982ms)","trace[655870244] 'compare' (duration: 94.293648ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:10.52107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:10.106976Z","time spent":"413.859115ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-07T10:40:10.659787Z","caller":"traceutil/trace.go:171","msg":"trace[1081378828] linearizableReadLoop","detail":"{readStateIndex:1987; appliedIndex:1985; }","duration":"415.980016ms","start":"2026-09-07T10:40:10.243776Z","end":"2026-09-07T10:40:10.659756Z","steps":["trace[1081378828] 'read index received' (duration: 182.443855ms)","trace[1081378828] 'applied index is now lower than readState.Index' (duration: 233.534991ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:10.660009Z","caller":"traceutil/trace.go:171","msg":"trace[200647017] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"501.342544ms","start":"2026-09-07T10:40:10.158647Z","end":"2026-09-07T10:40:10.65999Z","steps":["trace[200647017] 'process raft request' (duration: 475.656544ms)","trace[200647017] 'compare' (duration: 25.169803ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:10.660163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:10.158608Z","time spent":"501.440055ms","remote":"127.0.0.1:59796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-07T10:40:10.660195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.423393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-536dd91b-000d-4020-b7bc-f72191508466\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-07T10:40:10.660251Z","caller":"traceutil/trace.go:171","msg":"trace[770501992] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-536dd91b-000d-4020-b7bc-f72191508466; range_end:; response_count:1; response_revision:1914; }","duration":"416.541075ms","start":"2026-09-07T10:40:10.243694Z","end":"2026-09-07T10:40:10.660235Z","steps":["trace[770501992] 'agreement among raft nodes before linearized reading' (duration: 416.349632ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:10.660291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:10.243666Z","time spent":"416.613605ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-536dd91b-000d-4020-b7bc-f72191508466\" "} {"level":"info","ts":"2026-09-07T10:40:10.660539Z","caller":"traceutil/trace.go:171","msg":"trace[2055976303] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"241.803249ms","start":"2026-09-07T10:40:10.418674Z","end":"2026-09-07T10:40:10.660477Z","steps":["trace[2055976303] 'process raft request' (duration: 241.011998ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:10.850832Z","caller":"traceutil/trace.go:171","msg":"trace[1908292780] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"180.271244ms","start":"2026-09-07T10:40:10.67041Z","end":"2026-09-07T10:40:10.850682Z","steps":["trace[1908292780] 'process raft request' (duration: 103.925725ms)","trace[1908292780] 'compare' (duration: 75.998874ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:11.05406Z","caller":"traceutil/trace.go:171","msg":"trace[374460068] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"207.793697ms","start":"2026-09-07T10:40:10.846242Z","end":"2026-09-07T10:40:11.054035Z","steps":["trace[374460068] 'process raft request' (duration: 116.896342ms)","trace[374460068] 'compare' (duration: 90.591251ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:11.05416Z","caller":"traceutil/trace.go:171","msg":"trace[1467985559] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"172.105074ms","start":"2026-09-07T10:40:10.882008Z","end":"2026-09-07T10:40:11.054113Z","steps":["trace[1467985559] 'process raft request' (duration: 171.838ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:11.054165Z","caller":"traceutil/trace.go:171","msg":"trace[379828300] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"108.169093ms","start":"2026-09-07T10:40:10.945967Z","end":"2026-09-07T10:40:11.054136Z","steps":["trace[379828300] 'process raft request' (duration: 107.919089ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:11.054353Z","caller":"traceutil/trace.go:171","msg":"trace[1116578108] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1989; }","duration":"109.584564ms","start":"2026-09-07T10:40:10.944758Z","end":"2026-09-07T10:40:11.054343Z","steps":["trace[1116578108] 'read index received' (duration: 18.537086ms)","trace[1116578108] 'applied index is now lower than readState.Index' (duration: 91.046108ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:11.06268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.855332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T10:40:11.06278Z","caller":"traceutil/trace.go:171","msg":"trace[439981741] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1918; }","duration":"118.071655ms","start":"2026-09-07T10:40:10.94469Z","end":"2026-09-07T10:40:11.062761Z","steps":["trace[439981741] 'agreement among raft nodes before linearized reading' (duration: 109.698006ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:23.205615Z","caller":"traceutil/trace.go:171","msg":"trace[876063124] linearizableReadLoop","detail":"{readStateIndex:2093; appliedIndex:2092; }","duration":"154.569081ms","start":"2026-09-07T10:40:23.05102Z","end":"2026-09-07T10:40:23.205589Z","steps":["trace[876063124] 'read index received' (duration: 154.434919ms)","trace[876063124] 'applied index is now lower than readState.Index' (duration: 133.342µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:23.205676Z","caller":"traceutil/trace.go:171","msg":"trace[1736508512] transaction","detail":"{read_only:false; response_revision:2016; number_of_response:1; }","duration":"169.057729ms","start":"2026-09-07T10:40:23.036591Z","end":"2026-09-07T10:40:23.205649Z","steps":["trace[1736508512] 'process raft request' (duration: 168.847106ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:23.205776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.727313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:40:23.205809Z","caller":"traceutil/trace.go:171","msg":"trace[1271078764] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2016; }","duration":"154.800794ms","start":"2026-09-07T10:40:23.050999Z","end":"2026-09-07T10:40:23.2058Z","steps":["trace[1271078764] 'agreement among raft nodes before linearized reading' (duration: 154.715402ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:23.206786Z","caller":"traceutil/trace.go:171","msg":"trace[667340146] transaction","detail":"{read_only:false; response_revision:2017; number_of_response:1; }","duration":"106.429904ms","start":"2026-09-07T10:40:23.100332Z","end":"2026-09-07T10:40:23.206762Z","steps":["trace[667340146] 'process raft request' (duration: 105.985908ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:23.877137Z","caller":"traceutil/trace.go:171","msg":"trace[1750769101] transaction","detail":"{read_only:false; response_revision:2022; number_of_response:1; }","duration":"121.056543ms","start":"2026-09-07T10:40:23.756039Z","end":"2026-09-07T10:40:23.877095Z","steps":["trace[1750769101] 'process raft request' (duration: 120.78376ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:33.42893Z","caller":"traceutil/trace.go:171","msg":"trace[1055188828] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"158.850375ms","start":"2026-09-07T10:40:33.270058Z","end":"2026-09-07T10:40:33.428909Z","steps":["trace[1055188828] 'read index received' (duration: 158.728984ms)","trace[1055188828] 'applied index is now lower than readState.Index' (duration: 120.411µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:33.429001Z","caller":"traceutil/trace.go:171","msg":"trace[1578863158] transaction","detail":"{read_only:false; response_revision:2078; number_of_response:1; }","duration":"181.299551ms","start":"2026-09-07T10:40:33.247684Z","end":"2026-09-07T10:40:33.428983Z","steps":["trace[1578863158] 'process raft request' (duration: 181.092418ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:33.429131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.460819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-07T10:40:33.429163Z","caller":"traceutil/trace.go:171","msg":"trace[1826430422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2078; }","duration":"136.543511ms","start":"2026-09-07T10:40:33.292608Z","end":"2026-09-07T10:40:33.429152Z","steps":["trace[1826430422] 'agreement among raft nodes before linearized reading' (duration: 136.425359ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:33.430456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.387165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-07T10:40:33.430576Z","caller":"traceutil/trace.go:171","msg":"trace[722240643] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2078; }","duration":"160.547308ms","start":"2026-09-07T10:40:33.270011Z","end":"2026-09-07T10:40:33.430558Z","steps":["trace[722240643] 'agreement among raft nodes before linearized reading' (duration: 159.014597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:45.160951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.99236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-07T10:40:45.161033Z","caller":"traceutil/trace.go:171","msg":"trace[11469178] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2153; }","duration":"103.129141ms","start":"2026-09-07T10:40:45.057885Z","end":"2026-09-07T10:40:45.161014Z","steps":["trace[11469178] 'count revisions from in-memory index tree' (duration: 102.899029ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:45.665206Z","caller":"traceutil/trace.go:171","msg":"trace[1538352638] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"105.707595ms","start":"2026-09-07T10:40:45.559457Z","end":"2026-09-07T10:40:45.665165Z","steps":["trace[1538352638] 'process raft request' (duration: 52.750352ms)","trace[1538352638] 'compare' (duration: 52.784361ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:45.979301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.025106ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-09-07T10:40:45.979484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:45.666014Z","time spent":"313.465617ms","remote":"127.0.0.1:59574","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-09-07T10:40:45.979658Z","caller":"traceutil/trace.go:171","msg":"trace[169842965] linearizableReadLoop","detail":"{readStateIndex:2237; appliedIndex:2236; }","duration":"312.94699ms","start":"2026-09-07T10:40:45.666462Z","end":"2026-09-07T10:40:45.979409Z","steps":["trace[169842965] 'read index received' (duration: 141.701762ms)","trace[169842965] 'applied index is now lower than readState.Index' (duration: 171.243578ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:45.980141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.297945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:40:45.980203Z","caller":"traceutil/trace.go:171","msg":"trace[413406380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2155; }","duration":"313.740211ms","start":"2026-09-07T10:40:45.666439Z","end":"2026-09-07T10:40:45.980179Z","steps":["trace[413406380] 'agreement among raft nodes before linearized reading' (duration: 313.279745ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:45.980252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-07T10:40:45.666408Z","time spent":"313.830832ms","remote":"127.0.0.1:59530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-07T10:40:46.223765Z","caller":"traceutil/trace.go:171","msg":"trace[793541429] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"206.480528ms","start":"2026-09-07T10:40:46.017263Z","end":"2026-09-07T10:40:46.223743Z","steps":["trace[793541429] 'process raft request' (duration: 151.579262ms)","trace[793541429] 'compare' (duration: 54.672013ms)"],"step_count":2} {"level":"warn","ts":"2026-09-07T10:40:46.223946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.594868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-07T10:40:46.223688Z","caller":"traceutil/trace.go:171","msg":"trace[1874855266] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"205.331064ms","start":"2026-09-07T10:40:46.018329Z","end":"2026-09-07T10:40:46.22366Z","steps":["trace[1874855266] 'read index received' (duration: 150.504019ms)","trace[1874855266] 'applied index is now lower than readState.Index' (duration: 54.810915ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:40:46.224007Z","caller":"traceutil/trace.go:171","msg":"trace[2055501295] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2157; }","duration":"205.690059ms","start":"2026-09-07T10:40:46.018303Z","end":"2026-09-07T10:40:46.223993Z","steps":["trace[2055501295] 'agreement among raft nodes before linearized reading' (duration: 205.563167ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:40:46.224353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.621856ms","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-09-07T10:40:46.224462Z","caller":"traceutil/trace.go:171","msg":"trace[1946004293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2157; }","duration":"193.760287ms","start":"2026-09-07T10:40:46.030677Z","end":"2026-09-07T10:40:46.224438Z","steps":["trace[1946004293] 'agreement among raft nodes before linearized reading' (duration: 193.482154ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:40:46.47739Z","caller":"traceutil/trace.go:171","msg":"trace[1495516927] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"156.09037ms","start":"2026-09-07T10:40:46.321273Z","end":"2026-09-07T10:40:46.477364Z","steps":["trace[1495516927] 'process raft request' (duration: 155.061787ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:26.951979Z","caller":"traceutil/trace.go:171","msg":"trace[995635092] linearizableReadLoop","detail":"{readStateIndex:2896; appliedIndex:2895; }","duration":"176.344044ms","start":"2026-09-07T10:42:26.775611Z","end":"2026-09-07T10:42:26.951955Z","steps":["trace[995635092] 'read index received' (duration: 176.133012ms)","trace[995635092] 'applied index is now lower than readState.Index' (duration: 209.792µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:42:26.952297Z","caller":"traceutil/trace.go:171","msg":"trace[353805278] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"206.004498ms","start":"2026-09-07T10:42:26.746277Z","end":"2026-09-07T10:42:26.952281Z","steps":["trace[353805278] 'process raft request' (duration: 205.538904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:42:26.952647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.051342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-07T10:42:26.952702Z","caller":"traceutil/trace.go:171","msg":"trace[440623296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2791; }","duration":"177.157313ms","start":"2026-09-07T10:42:26.77553Z","end":"2026-09-07T10:42:26.952688Z","steps":["trace[440623296] 'agreement among raft nodes before linearized reading' (duration: 176.965031ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:42:53.213224Z","caller":"traceutil/trace.go:171","msg":"trace[955122001] transaction","detail":"{read_only:false; response_revision:2936; number_of_response:1; }","duration":"114.804812ms","start":"2026-09-07T10:42:53.098317Z","end":"2026-09-07T10:42:53.213122Z","steps":["trace[955122001] 'process raft request' (duration: 51.135963ms)","trace[955122001] 'compare' (duration: 63.246547ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:47:38.255658Z","caller":"traceutil/trace.go:171","msg":"trace[930756216] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"119.922525ms","start":"2026-09-07T10:47:38.135711Z","end":"2026-09-07T10:47:38.255633Z","steps":["trace[930756216] 'process raft request' (duration: 119.842534ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:38.255659Z","caller":"traceutil/trace.go:171","msg":"trace[172954828] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"121.036486ms","start":"2026-09-07T10:47:38.134596Z","end":"2026-09-07T10:47:38.255632Z","steps":["trace[172954828] 'process raft request' (duration: 53.2641ms)","trace[172954828] 'compare' (duration: 67.583823ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:47:42.326134Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2882} {"level":"info","ts":"2026-09-07T10:47:42.398629Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2882,"took":"71.51636ms","hash":1752018139,"current-db-size-bytes":52469760,"current-db-size":"52 MB","current-db-size-in-use-bytes":27099136,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-09-07T10:47:42.398691Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1752018139,"revision":2882,"compact-revision":-1} {"level":"info","ts":"2026-09-07T10:47:43.756766Z","caller":"traceutil/trace.go:171","msg":"trace[2127270435] linearizableReadLoop","detail":"{readStateIndex:5419; appliedIndex:5416; }","duration":"110.845854ms","start":"2026-09-07T10:47:43.645905Z","end":"2026-09-07T10:47:43.756751Z","steps":["trace[2127270435] 'read index received' (duration: 25.880581ms)","trace[2127270435] 'applied index is now lower than readState.Index' (duration: 84.964603ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:47:43.756812Z","caller":"traceutil/trace.go:171","msg":"trace[1284944568] transaction","detail":"{read_only:false; response_revision:5218; number_of_response:1; }","duration":"131.016888ms","start":"2026-09-07T10:47:43.625777Z","end":"2026-09-07T10:47:43.756794Z","steps":["trace[1284944568] 'process raft request' (duration: 130.845694ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:43.75694Z","caller":"traceutil/trace.go:171","msg":"trace[26612577] transaction","detail":"{read_only:false; response_revision:5219; number_of_response:1; }","duration":"129.115162ms","start":"2026-09-07T10:47:43.62782Z","end":"2026-09-07T10:47:43.756935Z","steps":["trace[26612577] 'process raft request' (duration: 128.895927ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:43.757025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.12046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-07T10:47:43.757042Z","caller":"traceutil/trace.go:171","msg":"trace[306540061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5219; }","duration":"111.18024ms","start":"2026-09-07T10:47:43.645856Z","end":"2026-09-07T10:47:43.757037Z","steps":["trace[306540061] 'agreement among raft nodes before linearized reading' (duration: 111.14277ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:58.014237Z","caller":"traceutil/trace.go:171","msg":"trace[1800777180] linearizableReadLoop","detail":"{readStateIndex:5615; appliedIndex:5614; }","duration":"202.22814ms","start":"2026-09-07T10:47:57.811978Z","end":"2026-09-07T10:47:58.014206Z","steps":["trace[1800777180] 'read index received' (duration: 201.957675ms)","trace[1800777180] 'applied index is now lower than readState.Index' (duration: 269.585µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:47:58.014289Z","caller":"traceutil/trace.go:171","msg":"trace[177495034] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"280.436794ms","start":"2026-09-07T10:47:57.73383Z","end":"2026-09-07T10:47:58.014267Z","steps":["trace[177495034] 'process raft request' (duration: 280.206949ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:58.0144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.409972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-09-07T10:47:58.014518Z","caller":"traceutil/trace.go:171","msg":"trace[270999468] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5410; }","duration":"202.577965ms","start":"2026-09-07T10:47:57.811926Z","end":"2026-09-07T10:47:58.014504Z","steps":["trace[270999468] 'agreement among raft nodes before linearized reading' (duration: 202.411673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:58.015029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.85425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-07T10:47:58.015301Z","caller":"traceutil/trace.go:171","msg":"trace[1469364619] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:5410; }","duration":"196.141236ms","start":"2026-09-07T10:47:57.819135Z","end":"2026-09-07T10:47:58.015276Z","steps":["trace[1469364619] 'agreement among raft nodes before linearized reading' (duration: 195.29928ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:58.126061Z","caller":"traceutil/trace.go:171","msg":"trace[1862654015] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"103.640534ms","start":"2026-09-07T10:47:58.022388Z","end":"2026-09-07T10:47:58.126028Z","steps":["trace[1862654015] 'process raft request' (duration: 76.371768ms)","trace[1862654015] 'compare' (duration: 26.752977ms)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:47:58.126159Z","caller":"traceutil/trace.go:171","msg":"trace[999092847] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"103.4335ms","start":"2026-09-07T10:47:58.022701Z","end":"2026-09-07T10:47:58.126135Z","steps":["trace[999092847] 'process raft request' (duration: 103.021143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:47:58.126611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.557767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2005177d-2f7d-4c82-949c-1c6353a55c71\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-09-07T10:47:58.126694Z","caller":"traceutil/trace.go:171","msg":"trace[1110177479] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2005177d-2f7d-4c82-949c-1c6353a55c71; range_end:; response_count:1; response_revision:5414; }","duration":"100.672639ms","start":"2026-09-07T10:47:58.026005Z","end":"2026-09-07T10:47:58.126677Z","steps":["trace[1110177479] 'agreement among raft nodes before linearized reading' (duration: 100.396944ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:58.127276Z","caller":"traceutil/trace.go:171","msg":"trace[24018776] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"104.648252ms","start":"2026-09-07T10:47:58.022615Z","end":"2026-09-07T10:47:58.127263Z","steps":["trace[24018776] 'process raft request' (duration: 103.061173ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:47:58.154898Z","caller":"traceutil/trace.go:171","msg":"trace[1663650092] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"100.297902ms","start":"2026-09-07T10:47:58.054572Z","end":"2026-09-07T10:47:58.15487Z","steps":["trace[1663650092] 'process raft request' (duration: 99.730482ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:48:46.537529Z","caller":"traceutil/trace.go:171","msg":"trace[30620346] linearizableReadLoop","detail":"{readStateIndex:6011; appliedIndex:6010; }","duration":"120.128731ms","start":"2026-09-07T10:48:46.417376Z","end":"2026-09-07T10:48:46.537504Z","steps":["trace[30620346] 'read index received' (duration: 119.889477ms)","trace[30620346] 'applied index is now lower than readState.Index' (duration: 238.224µs)"],"step_count":2} {"level":"info","ts":"2026-09-07T10:48:46.537593Z","caller":"traceutil/trace.go:171","msg":"trace[1203593252] transaction","detail":"{read_only:false; response_revision:5795; number_of_response:1; }","duration":"121.901564ms","start":"2026-09-07T10:48:46.415661Z","end":"2026-09-07T10:48:46.537562Z","steps":["trace[1203593252] 'process raft request' (duration: 121.7091ms)"],"step_count":1} {"level":"warn","ts":"2026-09-07T10:48:46.537933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.534649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5651"} {"level":"info","ts":"2026-09-07T10:48:46.537979Z","caller":"traceutil/trace.go:171","msg":"trace[903075674] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:5795; }","duration":"120.626501ms","start":"2026-09-07T10:48:46.417339Z","end":"2026-09-07T10:48:46.537966Z","steps":["trace[903075674] 'agreement among raft nodes before linearized reading' (duration: 120.302025ms)"],"step_count":1} {"level":"info","ts":"2026-09-07T10:52:42.334605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5201} {"level":"info","ts":"2026-09-07T10:52:42.403582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5201,"took":"67.882259ms","hash":1907890960,"current-db-size-bytes":52469760,"current-db-size":"52 MB","current-db-size-in-use-bytes":19525632,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-07T10:52:42.403631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1907890960,"revision":5201,"compact-revision":2882}