Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.03.2023 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15301124 kB
MemAvailable 30443796 kB
Buffers 0 kB
Cached 16428096 kB
SwapCached 3268 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-14 19:38:45
DTS_END                         2023-03-14 19:38:45.1750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48492
TRN_ID                          237782
ATT_ID                          681
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9147.76 18397 1097731
customer order: creation 172.44 333 20693
customer order: refuse 15.28 376 1833
order to supplier: creation 44.33 1302 5319
order to supplier: removal 3.21 112533 385
invoice (draft): creation 46.19 3535 5543
invoice (draft): removal 51.41 770 6169
invoice accept: apply 41.48 19017 4978
invoice accept: cancel 46.40 826 5568
customer reserve: creation 3875.04 186 465005
customer reserve: removal 4544.62 63 545354
realization accept: apply 83.56 188 10027
realization accept: cancel 92.34 104 11081
payment from customer: creation 60.84 113 7301
payment from customer: removal 5.56 65 667
payment to supplier: creation 49.17 131 5900
payment to supplier: removal 3.58 62 430
service: total inventory turnovers 4.73 1416 568
service: total monetary turnovers 6.65 37 798
service: aggregate perf. data 0.90 17042 108
service: refresh index statistics 0.03 209840 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-03-2023 19:40:15 to 14-03-2023 19:40:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2755
TEST_TIME 2 4648
TEST_TIME 3 4872
TEST_TIME 4 4885
TEST_TIME 5 5049
TEST_TIME 6 5000
TEST_TIME 7 5392
TEST_TIME 8 5457
TEST_TIME 9 5696
TEST_TIME 10 5937
TEST_TIME 11 6304
TEST_TIME 12 6600
TEST_TIME 13 6886
TEST_TIME 14 6986
TEST_TIME 15 6978
TEST_TIME 16 7315
TEST_TIME 17 7291
TEST_TIME 18 7347
TEST_TIME 19 7467
TEST_TIME 20 8256
TEST_TIME 21 8342
TEST_TIME 22 8340
TEST_TIME 23 8631
TEST_TIME 24 8963
TEST_TIME 25 8852
TEST_TIME 26 9362
TEST_TIME 27 9690
TEST_TIME 28 9709
TEST_TIME 29 9814
TEST_TIME 30 10771
TEST_TIME 31 10953
TEST_TIME 32 10645
TEST_TIME 33 10458
TEST_TIME 34 10673
TEST_TIME 35 10542
TEST_TIME 36 10332
TEST_TIME 37 10357
TEST_TIME 38 10261
TEST_TIME 39 10074
TEST_TIME 40 10065
TEST_TIME 41 10043
TEST_TIME 42 10020
TEST_TIME 43 9973
TEST_TIME 44 9944
TEST_TIME 45 9895
TEST_TIME 46 9819
TEST_TIME 47 9718
TEST_TIME 48 9735
TEST_TIME 49 9598
TEST_TIME 50 9553
TEST_TIME 51 9447
TEST_TIME 52 9388
TEST_TIME 53 9471
TEST_TIME 54 9507
TEST_TIME 55 9521
TEST_TIME 56 9386
TEST_TIME 57 9398
TEST_TIME 58 9370
TEST_TIME 59 9370
TEST_TIME 60 9372
TEST_TIME 61 9369
TEST_TIME 62 9385
TEST_TIME 63 9397
TEST_TIME 64 9457
TEST_TIME 65 9463
TEST_TIME 66 9558
TEST_TIME 67 9597
TEST_TIME 68 9555
TEST_TIME 69 9519
TEST_TIME 70 9676
TEST_TIME 71 9611
TEST_TIME 72 9625
TEST_TIME 73 9584
TEST_TIME 74 9482
TEST_TIME 75 9502
TEST_TIME 76 9442
TEST_TIME 77 9397
TEST_TIME 78 9415
TEST_TIME 79 9407
TEST_TIME 80 9392
TEST_TIME 81 9389
TEST_TIME 82 9382
TEST_TIME 83 9327
TEST_TIME 84 9275
TEST_TIME 85 9271
TEST_TIME 86 9261
TEST_TIME 87 9221
TEST_TIME 88 9223
TEST_TIME 89 9199
TEST_TIME 90 9243
TEST_TIME 91 9208
TEST_TIME 92 9239
TEST_TIME 93 9287
TEST_TIME 94 9328
TEST_TIME 95 9300
TEST_TIME 96 9337
TEST_TIME 97 9413
TEST_TIME 98 9397
TEST_TIME 99 9379
TEST_TIME 100 9395
TEST_TIME 101 9456
TEST_TIME 102 9529
TEST_TIME 103 9508
TEST_TIME 104 9428
TEST_TIME 105 9405
TEST_TIME 106 9358
TEST_TIME 107 9315
TEST_TIME 108 9352
TEST_TIME 109 9341
TEST_TIME 110 9315
TEST_TIME 111 9276
TEST_TIME 112 9253
TEST_TIME 113 9243
TEST_TIME 114 9247
TEST_TIME 115 9197
TEST_TIME 116 9192
TEST_TIME 117 9171
TEST_TIME 118 9196
TEST_TIME 119 9176
TEST_TIME 120 9148

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-03-2023 19:40:19 to 14-03-2023 19:40:22.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1526758 1526758 0 0.00 0 22309 6 0 0
doc_list_biud 1526757 1526757 0 0.00 0 43011 28 0 0
sp_add_invoice_to_stock 5100 4978 122 2.39 16 181477 19017 0 122
sp_cancel_adding_invoice 5568 5568 0 0.00 4 65783 827 0 0
sp_cancel_client_order 1833 1833 0 0.00 4 10153 377 0 0
sp_cancel_customer_reserve 545354 545354 0 0.00 0 43012 63 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 2 1638 66 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 2 3731 63 0 0
sp_cancel_supplier_invoice 6180 6169 11 0.17 1 16375 771 0 11
sp_cancel_supplier_order 385 385 0 0.00 6 909105 112534 0 0
sp_cancel_write_off 11081 11081 0 0.00 1 17308 104 0 0
sp_client_order 20693 20693 0 0.00 3 42104 333 0 0
sp_customer_reserve 465005 465005 0 0.00 0 76202 187 0 0
sp_fill_shopping_cart 31550 31550 0 0.00 1 33730 42 0 0
sp_fill_shopping_cart_clo_res 465005 465005 0 0.00 0 16482 14 0 0
sp_get_clo_for_invoice 4975 4975 0 0.00 0 26541 320 0 0
sp_kill_cost_storno 17746 17746 0 0.00 0 6123 50 0 0
sp_kill_qstorno_ret_qs2qd 1108343 1108343 0 0.00 0 43009 34 0 0
sp_kill_qty_storno 585391 585391 0 0.00 0 43011 73 0 0
sp_lock_selected_doc 20853 20853 0 0.00 0 8892 5 0 0
sp_make_cost_storno 28203 28203 0 0.00 0 22304 56 0 0
sp_multiply_rows_for_pdistr 28202 28202 0 0.00 0 6706 25 0 0
sp_multiply_rows_for_qdistr 20693 20693 0 0.00 0 40436 131 0 0
sp_pay_from_customer 7301 7301 0 0.00 4 10688 114 0 0
sp_pay_to_supplier 5900 5900 0 0.00 4 9924 131 0 0
sp_payment_common 13201 13201 0 0.00 1 9941 28 0 0
sp_qd_handle_on_cancel_clo 1833 1833 0 0.00 0 10149 343 0 0
sp_qd_handle_on_invoice_upd_sts 10542 10542 0 0.00 0 21600 347 0 0
sp_qd_handle_on_reserve_upd_sts 10027 10027 0 0.00 0 15535 68 0 0
sp_reserve_write_off 10027 10027 0 0.00 6 22318 188 0 0
sp_supplier_invoice 5543 5543 0 0.00 6 77143 3535 0 0
sp_supplier_order 5319 5319 0 0.00 5 61910 1303 0 0
srv_aggregate_perf_data 108 108 0 0.00 2719 85794 17043 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1329 135054 45347 0 0
srv_find_qd_qs_mism 1048927 1048927 0 0.00 0 14985 5 0 0
srv_make_invnt_saldo 568 568 0 0.00 28 51273 1417 0 0
srv_make_money_saldo 798 798 0 0.00 1 3785 37 0 0
srv_recalc_idx_stat 4 4 0 0.00 58991 378785 209840 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4978 4978 0 0.00 0 17503 195 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 7334 147 0 0
t$perf-norm:sp_cancel_client_order 1833 1833 0 0.00 0 4372 16 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 15306 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 2681 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3840 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1045 18 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 28632 2051 0 0
t$perf-norm:sp_cancel_write_off 1052 1052 0 0.00 0 5755 11 0 0
t$perf-norm:sp_client_order 20693 20693 0 0.00 0 15299 17 0 0
t$perf-norm:sp_customer_reserve 958 958 0 0.00 0 506 3 0 0
t$perf-norm:sp_pay_from_customer 7301 7301 0 0.00 0 15284 14 0 0
t$perf-norm:sp_pay_to_supplier 5900 5900 0 0.00 0 12935 13 0 0
t$perf-norm:sp_reserve_write_off 10027 10027 0 0.00 0 10257 13 0 0
t$perf-norm:sp_supplier_invoice 5543 5543 0 0.00 0 5900 16 0 0
t$perf-norm:sp_supplier_order 5319 5319 0 0.00 0 6220 20 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 5292 73 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 117 4 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 2415 28 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 2581 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10192 9948 244 2.39 0 3954 18 0 244
v_all_customers 37986 37986 0 0.00 0 20 1 0 0
v_all_suppliers 30378 30378 0 0.00 0 22 1 0 0
v_all_wares 41382 41382 0 0.00 0 168 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 4026 14 0 0
v_cancel_client_order 3666 3666 0 0.00 0 3593 6 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1595 6 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 0 450 4 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 3726 9 0 0
v_cancel_supplier_invoice 870 848 22 2.52 0 3317 26 0 22
v_cancel_supplier_order 770 770 0 0.00 0 1052 6 0 0
v_cancel_write_off 2104 2104 0 0.00 0 3322 15 0 0
v_max_id_clo_ord 5316 5316 0 0.00 0 924 1 0 0
v_max_id_clo_res 958 958 0 0.00 0 7332 19 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 4599 5 0 0
v_max_non_paid_invoice 1569 1569 0 0.00 0 141 2 0 0
v_max_non_paid_realizn 3724 3724 0 0.00 0 2722 6 0 0
v_min_id_clo_ord 5316 5316 0 0.00 0 4545 7 0 0
v_min_id_clo_res 958 958 0 0.00 0 2579 13 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 5119 7 0 0
v_min_non_paid_invoice 5900 5900 0 0.00 0 6829 19 0 0
v_min_non_paid_realizn 7301 7301 0 0.00 0 6315 19 0 0
v_our_firm 10550 10550 0 0.00 0 58 1 0 0
v_reserve_write_off 20052 20052 0 0.00 0 4077 7 0 0
x_lock_depdocs_on_canc_invoice 11313 11313 0 0.00 0 14665 128 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 8385 367 0 0
x_make_qstorno_1000_1200 5319 5319 0 0.00 1 58442 1220 0 0
x_make_qstorno_1200_2000 5543 5543 0 0.00 3 77088 3378 0 0
x_make_qstorno_2100_3300 465005 465005 0 0.00 0 76159 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-03-2023 19:40:23 to 14-03-2023 19:40:27.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:48 10 8192000000 8806976640 8820858880 11577376 0.141 3 3 4 0 11761280 76800 107104
17:09:49 24955 8192000000 9904813120 10215006208 986939392 10.752 100 89 178 0 1016466816 6300352 23227072
17:11:24 23104 8192000000 9950059344 10269937664 1029634736 11.165 100 95 190 0 1062667888 8770128 24263024
17:13:03 13932 8192000000 9988062336 10321588224 1068516464 11.538 100 83 165 0 1098899328 9537808 20845056
17:14:20 11609 8192000000 10032741488 10379866112 1118655920 12.014 100 88 174 1 1150621328 10608224 21357184
17:15:35 5327 8192000000 10084671600 10444038144 1170316880 12.500 100 85 165 2 1201667136 13075136 18275120
17:16:44 3182 8192000000 10117449104 10487377920 1211316096 12.881 100 77 153 0 1240804528 12232768 17255664
17:17:51 2163 8192000000 10147993296 10522681344 1239289280 13.140 100 80 161 0 1269214272 12916720 17008272
17:18:54 1458 8192000000 10176907616 10556497920 1264487056 13.371 100 74 146 0 1294186160 13463984 16235120
17:19:57 1734 8192000000 10215863280 10601009152 1296151248 13.660 100 85 170 1 1329195072 14750592 18293232
17:21:00 5119 8192000000 10215378864 10611806208 1306246144 13.752 100 79 157 1 1336738640 11625024 18867472
17:22:06 16433 8192000000 10204071312 10614259712 1312748752 13.811 100 43 84 1 1330186000 7377488 10059760
17:23:27 4971 8192000000 10204609664 10623860736 1321111024 13.887 100 27 54 1 1332878576 5738064 6029488
17:24:34 13290 8192000000 10209101392 10632777728 1328770512 13.956 100 17 31 0 1337552128 5511088 3270528
17:25:51 16707 8192000000 10217470464 10642042880 1335466208 14.017 100 10 20 0 1343398496 5882400 2049888
17:27:08 45357 8192000000 10241705392 10651406336 1337171344 14.032 100 34 67 0 1351835120 5346928 9316848
17:28:58 8487 8192000000 10227461888 10644905984 1332204320 13.987 100 19 38 0 1343535872 6765952 4565600
17:30:07 13341 8192000000 10156000640 10565435392 1216475984 12.929 94 20 41 0 1226743648 6231888 4035776
17:31:21 64025 8192000000 10128010400 10516504576 1142511888 12.239 90 28 55 0 1154534624 5075584 6947152
17:33:25 5852 8192000000 10148208992 10552119296 1244996208 13.192 99 35 70 3 1262588816 9780432 7812176
17:34:32 9766 8192000000 10147868624 10552246272 1250538032 13.243 99 23 47 1 1261209440 5454560 5216848
17:35:44 9603 8192000000 10171894592 10586517504 1286015824 13.568 100 22 43 1 1296411664 6019120 4376720
17:36:54 27604 8192000000 10173323808 10591068160 1292224736 13.624 100 45 88 2 1308609008 5013696 11370576
17:38:22 9345 8192000000 10217622736 10629443584 1321978048 13.895 100 21 42 0 1331593296 5410800 4204448
17:39:36 29254 8192000000 10252565104 10665574400 1339416640 14.052 100 45 90 0 1364339920 15069824 9853456
17:41:09 15961 8192000000 10266295776 10685067264 1362375904 14.259 100 42 85 0 1384290080 12239504 9674672
17:42:27 33603 8192000000 10281489072 10708111360 1377509568 14.394 100 31 62 0 1400303056 15362000 7431488
17:44:08 12431 8192000000 10287158144 10713448448 1382264432 14.437 100 32 61 1 1401593584 12074096 7255056
17:45:26 7025 8192000000 10305804352 10728570880 1396250320 14.562 100 39 77 0 1418421232 13361088 8809824
17:46:36 9255 8192000000 10324996544 10749669376 1402792864 14.620 100 54 109 0 1436161376 20765776 12602736
17:47:51 7730 8192000000 10338581504 10759790592 1402926144 14.621 100 57 113 0 1439722240 22573568 14222528
17:49:03 9727 8192000000 10335593424 10760810496 1396248256 14.562 99 53 105 0 1438040576 28933264 12859056
17:50:16 7273 8192000000 10327468736 10749595648 1398012416 14.577 100 57 111 2 1436484592 24100752 14371424
17:51:33 5796 8192000000 10315784992 10737451008 1389255664 14.499 100 56 111 1 1425609520 23467744 12886112
17:52:45 3131 8192000000 10328776992 10751803392 1399334880 14.589 100 58 118 0 1438068176 24570624 14162672
17:53:55 4380 8192000000 10352707984 10772090880 1413325680 14.713 100 70 137 0 1460664880 31660336 15678864
17:55:04 6079 8192000000 10363539360 10780622848 1405234640 14.642 99 76 153 1 1459477744 35866256 18376848
17:56:15 4540 8192000000 10366351408 10782720000 1391721216 14.521 98 71 142 0 1450691152 41647040 17322896
17:57:28 1327 8192000000 10369542128 10778411008 1402332640 14.616 100 89 178 2 1472083712 46264624 23486448
17:58:35 3123 8192000000 10364374144 10773155840 1384328512 14.455 98 79 154 0 1462438784 57529792 20580480
17:59:51 5531 8192000000 10348780640 10774966272 1386598912 14.476 99 42 84 1 1448261504 50350144 11312448
18:01:00 4045 8192000000 10333719440 10753499136 1367104128 14.301 99 43 83 0 1433502096 54815328 11582640
18:02:10 10459 8192000000 10354801392 10790342656 1399751680 14.593 100 35 70 0 1464629840 54937664 9940496
18:03:24 7247 8192000000 10359296480 10785898496 1399152976 14.587 100 51 103 0 1464927696 51764928 14009792
18:05:20 14099 8192000000 10335225936 10771460096 1380688256 14.423 100 29 56 0 1440022544 51566640 7767648
18:06:43 15890 8192000000 10331208752 10774192128 1384914000 14.460 100 19 38 0 1434295824 43909952 5471872
18:08:09 17249 8192000000 10318388880 10755436544 1355063296 14.193 99 24 47 0 1413114080 51473392 6577392
18:09:38 73989 8192000000 10295135872 10726506496 1362761040 14.262 99 52 103 2 1385075120 10419936 11894144
18:11:54 7939 8192000000 10280329216 10712236032 1357704304 14.217 100 56 112 0 1386259136 15043056 13511776
18:13:08 53303 8192000000 10278013360 10724364288 1363140304 14.266 99 10 20 0 1378388224 13216832 2031088
18:15:02 71399 8192000000 10288128144 10741497856 1374876352 14.371 100 16 33 0 1397152640 17810480 4465808
18:17:35 36635 8192000000 10322485920 10757541888 1390453696 14.510 100 32 62 0 1417441888 18764080 8224112
18:19:19 104905 8192000000 10286073056 10739154944 1361556624 14.251 98 36 72 1 1393508736 23386272 8565840
18:22:37 95609 8192000000 10288392608 10742018048 1391269424 14.517 100 30 58 0 1410941984 13051712 6620848
18:25:14 54977 8192000000 10298084608 10738225152 1364009824 14.273 98 39 78 0 1382327792 8956576 9361392
18:27:09 55522 8192000000 10305646112 10750337024 1390753920 14.513 100 38 76 1 1411923008 12240368 8928720
18:29:13 39392 8192000000 10331116224 10772344832 1410133168 14.685 100 26 52 0 1431767536 15254288 6380080
18:30:54 68676 8192000000 10290826032 10761232384 1385937168 14.470 99 13 26 0 1409552416 20147088 3468160
18:33:07 64566 8192000000 10295179168 10760089600 1364850592 14.281 98 12 22 1 1381777216 13912400 3014224
18:35:46 106092 8192000000 10301555328 10765561856 1371083280 14.337 98 21 42 0 1386052064 10846032 4122752
18:38:32 94850 8192000000 10301834720 10762743808 1377306432 14.392 98 9 16 1 1390555152 11573792 1674928
18:41:12 131332 8192000000 10295455216 10750763008 1274629952 13.464 91 13 26 0 1293603168 16280608 2692608
18:44:30 85693 8192000000 10272645856 10733821952 1274938672 13.467 92 11 21 0 1298927840 21378928 2610240
18:46:56 40366 8192000000 10294496304 10735910912 1350147088 14.149 98 29 58 0 1376170096 19669280 6353728
18:48:38 79972 8192000000 10285598752 10729291776 1347236640 14.123 98 20 40 0 1362360304 10764080 4359584
18:51:01 74977 8192000000 10253200032 10700374016 1309703584 13.783 96 24 48 0 1324365088 9917568 4743936
18:53:47 51084 8192000000 10273117360 10705199104 1317657296 13.855 97 24 48 0 1330182816 7384448 5141072
18:55:53 50614 8192000000 10273971056 10725658624 1363531024 14.269 100 23 46 0 1387307040 18280496 5495520
18:57:47 52213 8192000000 10272303888 10723401728 1359728512 14.235 99 21 43 0 1375370464 11054272 4587680
18:59:39 71740 8192000000 10242510240 10694078464 1326570880 13.936 97 6 13 0 1337372640 9156544 1645216
19:01:52 64944 8192000000 10270887840 10726936576 1356986464 14.210 99 14 28 0 1373718960 13324640 3407856
19:03:57 29913 8192000000 10263549808 10712588288 1354727248 14.190 99 30 60 0 1370526080 9056000 6742832
19:05:34 73208 8192000000 10238312432 10694660096 1252922864 13.265 92 11 22 0 1264921760 9406736 2592160
19:07:55 21580 8192000000 10257279936 10698919936 1328734288 13.956 98 37 74 0 1346744320 10343120 7666912
19:09:20 37592 8192000000 10278485408 10719559680 1355890960 14.200 99 33 66 0 1376102192 13031584 7179648
19:11:11 66097 8192000000 10253015536 10708504576 1305893856 13.749 95 9 18 0 1319625056 11883552 1847648
19:13:17 12841 8192000000 10270271664 10713104384 1361853856 14.254 100 30 60 0 1380807248 12377744 6575648
19:14:39 88258 8192000000 10280009120 10752413696 1377336528 14.393 100 16 32 0 1396924352 16149616 3438208
19:17:18 67616 8192000000 10292723680 10751868928 1367075936 14.301 99 19 38 0 1382921984 11338912 4507136
19:19:26 104125 8192000000 10282347264 10748801024 1343434960 14.088 97 11 22 0 1370205184 24369040 2401184
19:22:15 135053 8192000000 10293355216 10746208256 1374917776 14.371 99 28 56 0 1392881584 11449568 6514240
19:25:31 40926 8192000000 10283634144 10733142016 1353588704 14.180 98 40 80 0 1376946896 14014912 9343280
19:28:33 65085 8192000000 10259488208 10713501696 1350317632 14.150 99 18 36 0 1364365984 9587200 4461152
19:30:38 71701 8192000000 10270699040 10727727104 1370560048 14.332 100 18 36 1 1386604544 11326480 4718016
19:32:58 69687 8192000000 10249680576 10704609280 1333274112 13.997 97 16 32 0 1344744624 7749472 3721040
19:35:34 82715 8192000000 10249066000 10715238400 1301054320 13.705 95 11 20 0 1313339872 9730976 2554576
19:37:58 24325 8192000000 10263864304 10709639168 1344214384 14.095 99 27 54 0 1361644736 11158320 6272032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-03-2023 19:40:29 to 14-03-2023 19:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-03-2023 19:40:45 to 14-03-2023 19:40:47.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          236155
MON$OLDEST_ACTIVE               238162
MON$OLDEST_SNAPSHOT             238162
MON$NEXT_TRANSACTION            238162
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2028448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:227: 
:228: mscs-test-161.budzdorov.ru    Tue Mar 14 19:38:49 2023
:229:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:230: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

14.03.23 19:40:48. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-03-14 19:40:51 No severe PSQL-related problems occured
14.03.23 19:40:52. End of report.