Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.12.2022 16: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 15279188 kB
MemAvailable 30337416 kB
Buffers 0 kB
Cached 16401332 kB
SwapCached 10976 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 2022-12-29 16:38:37
DTS_END                         2022-12-29 16:38:37.0130
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/52200
TRN_ID                          246781
ATT_ID                          687
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: 9410.38 16986 1129246
customer order: creation 182.46 331 21895
customer order: refuse 15.70 347 1884
order to supplier: creation 48.18 1170 5781
order to supplier: removal 3.48 84817 418
invoice (draft): creation 48.13 3143 5776
invoice (draft): removal 52.79 649 6335
invoice accept: apply 43.63 17620 5236
invoice accept: cancel 47.83 673 5740
customer reserve: creation 3991.43 177 478972
customer reserve: removal 4668.02 49 560162
realization accept: apply 88.24 170 10589
realization accept: cancel 86.01 106 10321
payment from customer: creation 62.52 105 7502
payment from customer: removal 5.95 93 714
payment to supplier: creation 50.02 111 6002
payment to supplier: removal 3.79 71 455
service: total inventory turnovers 4.71 1291 565
service: total monetary turnovers 6.72 22 806
service: aggregate perf. data 0.74 26454 89
service: refresh index statistics 0.03 202324 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-12-2022 16:39:35 to 29-12-2022 16:39:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6417
TEST_TIME 2 5916
TEST_TIME 3 6722
TEST_TIME 4 6142
TEST_TIME 5 6141
TEST_TIME 6 5909
TEST_TIME 7 5720
TEST_TIME 8 5561
TEST_TIME 9 5656
TEST_TIME 10 5691
TEST_TIME 11 5711
TEST_TIME 12 5939
TEST_TIME 13 6220
TEST_TIME 14 6621
TEST_TIME 15 6995
TEST_TIME 16 7261
TEST_TIME 17 8230
TEST_TIME 18 8445
TEST_TIME 19 8707
TEST_TIME 20 8740
TEST_TIME 21 9067
TEST_TIME 22 9757
TEST_TIME 23 10026
TEST_TIME 24 9800
TEST_TIME 25 9782
TEST_TIME 26 9816
TEST_TIME 27 10299
TEST_TIME 28 10065
TEST_TIME 29 9966
TEST_TIME 30 9938
TEST_TIME 31 9747
TEST_TIME 32 9835
TEST_TIME 33 9693
TEST_TIME 34 9971
TEST_TIME 35 9866
TEST_TIME 36 9944
TEST_TIME 37 10017
TEST_TIME 38 9962
TEST_TIME 39 10106
TEST_TIME 40 10008
TEST_TIME 41 9941
TEST_TIME 42 9745
TEST_TIME 43 9785
TEST_TIME 44 9839
TEST_TIME 45 9753
TEST_TIME 46 9701
TEST_TIME 47 9632
TEST_TIME 48 9617
TEST_TIME 49 9622
TEST_TIME 50 9640
TEST_TIME 51 9545
TEST_TIME 52 9459
TEST_TIME 53 9388
TEST_TIME 54 9359
TEST_TIME 55 9360
TEST_TIME 56 9312
TEST_TIME 57 9302
TEST_TIME 58 9412
TEST_TIME 59 9350
TEST_TIME 60 9340
TEST_TIME 61 9373
TEST_TIME 62 9450
TEST_TIME 63 9522
TEST_TIME 64 9529
TEST_TIME 65 9538
TEST_TIME 66 9646
TEST_TIME 67 9679
TEST_TIME 68 9743
TEST_TIME 69 9672
TEST_TIME 70 9705
TEST_TIME 71 9729
TEST_TIME 72 9634
TEST_TIME 73 9569
TEST_TIME 74 9569
TEST_TIME 75 9496
TEST_TIME 76 9477
TEST_TIME 77 9460
TEST_TIME 78 9407
TEST_TIME 79 9338
TEST_TIME 80 9454
TEST_TIME 81 9379
TEST_TIME 82 9412
TEST_TIME 83 9453
TEST_TIME 84 9411
TEST_TIME 85 9374
TEST_TIME 86 9457
TEST_TIME 87 9502
TEST_TIME 88 9578
TEST_TIME 89 9543
TEST_TIME 90 9485
TEST_TIME 91 9580
TEST_TIME 92 9680
TEST_TIME 93 9668
TEST_TIME 94 9654
TEST_TIME 95 9739
TEST_TIME 96 9701
TEST_TIME 97 9640
TEST_TIME 98 9617
TEST_TIME 99 9598
TEST_TIME 100 9543
TEST_TIME 101 9504
TEST_TIME 102 9564
TEST_TIME 103 9536
TEST_TIME 104 9501
TEST_TIME 105 9457
TEST_TIME 106 9435
TEST_TIME 107 9391
TEST_TIME 108 9428
TEST_TIME 109 9443
TEST_TIME 110 9422
TEST_TIME 111 9435
TEST_TIME 112 9404
TEST_TIME 113 9464
TEST_TIME 114 9464
TEST_TIME 115 9455
TEST_TIME 116 9459
TEST_TIME 117 9460
TEST_TIME 118 9491
TEST_TIME 119 9452
TEST_TIME 120 9417

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-12-2022 16:39:38 to 29-12-2022 16:39:42.

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 1574424 1574424 0 0.00 0 25718 6 0 0
doc_list_biud 1574420 1574420 0 0.00 0 35824 23 0 0
sp_add_invoice_to_stock 5370 5236 134 2.49 30 198768 17621 0 134
sp_cancel_adding_invoice 5740 5740 0 0.00 5 56990 673 0 0
sp_cancel_client_order 1884 1884 0 0.00 4 11500 348 0 0
sp_cancel_customer_reserve 560162 560162 0 0.00 0 20821 50 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 2 4832 94 0 0
sp_cancel_pay_to_supplier 455 455 0 0.00 2 4878 71 0 0
sp_cancel_supplier_invoice 6350 6335 15 0.23 1 11336 649 0 15
sp_cancel_supplier_order 418 418 0 0.00 7 983603 84817 0 0
sp_cancel_write_off 10321 10321 0 0.00 1 28222 107 0 0
sp_client_order 21895 21895 0 0.00 3 16693 332 0 0
sp_customer_reserve 478972 478972 0 0.00 0 72923 178 0 0
sp_fill_shopping_cart 33440 33440 0 0.00 1 18890 34 0 0
sp_fill_shopping_cart_clo_res 478961 478961 0 0.00 0 16080 12 0 0
sp_get_clo_for_invoice 5225 5225 0 0.00 0 17856 224 0 0
sp_kill_cost_storno 17230 17230 0 0.00 0 21960 46 0 0
sp_kill_qstorno_ret_qs2qd 1137398 1137398 0 0.00 0 28209 27 0 0
sp_kill_qty_storno 600674 600674 0 0.00 0 35823 59 0 0
sp_lock_selected_doc 21847 21847 0 0.00 0 10396 6 0 0
sp_make_cost_storno 29318 29318 0 0.00 0 11040 50 0 0
sp_multiply_rows_for_pdistr 29317 29317 0 0.00 0 5766 24 0 0
sp_multiply_rows_for_qdistr 21895 21895 0 0.00 0 9753 132 0 0
sp_pay_from_customer 7502 7502 0 0.00 4 9139 106 0 0
sp_pay_to_supplier 6002 6002 0 0.00 4 8488 112 0 0
sp_payment_common 13503 13503 0 0.00 1 7021 27 0 0
sp_qd_handle_on_cancel_clo 1884 1884 0 0.00 0 11495 317 0 0
sp_qd_handle_on_invoice_upd_sts 10965 10965 0 0.00 0 35821 290 0 0
sp_qd_handle_on_reserve_upd_sts 10589 10589 0 0.00 0 20125 60 0 0
sp_reserve_write_off 10589 10589 0 0.00 6 20136 171 0 0
sp_supplier_invoice 5776 5776 0 0.00 7 97106 3144 0 0
sp_supplier_order 5781 5781 0 0.00 5 23107 1170 0 0
srv_aggregate_perf_data 89 89 0 0.00 2798 709292 26455 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2080 118666 44072 0 0
srv_find_qd_qs_mism 1080220 1080220 0 0.00 0 25717 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 57 93872 1292 0 0
srv_make_money_saldo 806 806 0 0.00 0 688 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 77852 336458 202325 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5236 5236 0 0.00 0 13782 156 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 6678 82 0 0
t$perf-norm:sp_cancel_client_order 1884 1884 0 0.00 0 1380 9 0 0
t$perf-norm:sp_cancel_customer_reserve 660 660 0 0.00 0 2642 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 720 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 455 455 0 0.00 0 1273 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 1878 17 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 26560 1564 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 1449 7 0 0
t$perf-norm:sp_client_order 21895 21895 0 0.00 0 9132 15 0 0
t$perf-norm:sp_customer_reserve 1085 1085 0 0.00 0 696 4 0 0
t$perf-norm:sp_pay_from_customer 7502 7502 0 0.00 0 7187 8 0 0
t$perf-norm:sp_pay_to_supplier 6002 6002 0 0.00 0 5897 11 0 0
t$perf-norm:sp_reserve_write_off 10589 10589 0 0.00 0 3087 8 0 0
t$perf-norm:sp_supplier_invoice 5776 5776 0 0.00 0 8189 22 0 0
t$perf-norm:sp_supplier_order 5781 5781 0 0.00 0 1772 11 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 997 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 85 2 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 2507 26 0 0
t$perf-norm:srv_make_money_saldo 806 806 0 0.00 0 4819 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 10 3 0 0
v_add_invoice_to_stock 10716 10448 268 2.50 0 7323 20 0 268
v_all_customers 39664 39664 0 0.00 0 19 0 0 0
v_all_suppliers 31524 31524 0 0.00 0 19 1 0 0
v_all_wares 43786 43786 0 0.00 0 133 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 3050 11 0 0
v_cancel_client_order 3768 3768 0 0.00 0 4509 7 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 3334 15 0 0
v_cancel_customer_reserve 1320 1320 0 0.00 0 647 4 0 0
v_cancel_payment_to_supplier 910 910 0 0.00 0 4440 11 0 0
v_cancel_supplier_invoice 886 856 30 3.38 0 3451 29 0 30
v_cancel_supplier_order 836 836 0 0.00 0 2039 10 0 0
v_cancel_write_off 2094 2094 0 0.00 0 5600 20 0 0
v_max_id_clo_ord 5779 5779 0 0.00 0 994 1 0 0
v_max_id_clo_res 1085 1085 0 0.00 0 2890 22 0 0
v_max_id_ord_sup 5768 5768 0 0.00 0 2768 2 0 0
v_max_non_paid_invoice 1786 1786 0 0.00 0 541 3 0 0
v_max_non_paid_realizn 4121 4121 0 0.00 0 5850 7 0 0
v_min_id_clo_ord 5779 5779 0 0.00 0 3320 6 0 0
v_min_id_clo_res 1085 1085 0 0.00 0 1745 5 0 0
v_min_id_ord_sup 5768 5768 0 0.00 0 9051 9 0 0
v_min_non_paid_invoice 6001 6001 0 0.00 0 6940 20 0 0
v_min_non_paid_realizn 7502 7502 0 0.00 0 7007 17 0 0
v_our_firm 10884 10884 0 0.00 0 28 1 0 0
v_reserve_write_off 21178 21178 0 0.00 0 5179 7 0 0
x_lock_depdocs_on_canc_invoice 11647 11647 0 0.00 0 12053 110 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 9698 339 0 0
x_make_qstorno_1000_1200 5781 5781 0 0.00 1 23092 1102 0 0
x_make_qstorno_1200_2000 5776 5776 0 0.00 3 97093 3017 0 0
x_make_qstorno_2100_3300 478972 478972 0 0.00 0 72923 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-12-2022 16:39:43 to 29-12-2022 16:39:47.

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
14:08:41 2 8192000000 8799122064 8810561536 5521248 0.067 2 1 2 0 5620880 56848 42784
14:09:41 19914 8192000000 9886343120 10188886016 968824944 10.575 100 93 184 0 999816880 6177168 24814768
14:11:12 18181 8192000000 9918387872 10230964224 997907136 10.858 100 90 180 0 1029720192 7944640 23868416
14:12:34 10879 8192000000 9966083440 10294935552 1054535984 11.404 100 77 154 0 1082311136 8766192 19008960
14:13:48 8815 8192000000 10014930096 10357063680 1102490256 11.861 100 77 154 0 1131140992 10424608 18226128
14:15:02 9415 8192000000 10065317824 10424791040 1155011504 12.357 100 73 146 0 1183096176 11289824 16794848
14:16:17 4041 8192000000 10122113600 10488315904 1208383088 12.854 100 84 165 0 1240390816 13801984 18205744
14:17:27 2522 8192000000 10145888080 10516938752 1230559136 13.059 100 84 167 0 1262379568 13622096 18198336
14:18:32 2069 8192000000 10187843728 10565136384 1268737152 13.410 100 88 177 0 1302186656 14760720 18688784
14:19:35 2487 8192000000 10208919696 10589786112 1291837200 13.621 100 90 179 2 1326105088 14227632 20040256
14:20:38 10052 8192000000 10181761200 10594619392 1301372992 13.708 100 15 28 1 1309908816 5743376 2792448
14:21:50 9267 8192000000 10197868832 10609168384 1308306800 13.771 100 24 47 1 1320252000 6803632 5141568
14:23:03 6233 8192000000 10213343744 10630737920 1321621024 13.891 100 25 49 0 1333739376 6217520 5900832
14:24:11 37096 8192000000 10231513888 10638729216 1326453616 13.935 100 59 118 2 1345790640 5383328 13953696
14:25:48 7608 8192000000 10235440176 10648993792 1333557872 13.999 100 27 55 2 1345219504 5574944 6086688
14:26:57 4076 8192000000 10225743920 10648735744 1338098896 14.040 100 21 42 0 1348993888 6370992 4524000
14:28:03 9425 8192000000 10191161984 10593751040 1285458080 13.563 98 23 46 0 1296189280 5634592 5096608
14:29:14 4420 8192000000 10166111936 10576359424 1242419632 13.169 96 31 62 1 1259925760 10349648 7156480
14:30:20 4175 8192000000 10165961184 10569920512 1263893072 13.366 99 31 62 0 1281813232 10910912 7009248
14:31:27 7162 8192000000 10149990128 10549612544 1257543872 13.307 100 29 60 1 1270685776 5817312 7324592
14:32:35 59719 8192000000 10151935376 10558803968 1252699824 13.263 99 17 34 0 1261455072 4742688 4012560
14:34:36 6453 8192000000 10176794784 10585608192 1284362144 13.553 100 28 56 0 1296985664 6096288 6527232
14:35:44 11469 8192000000 10199499680 10609242112 1300760048 13.702 100 24 48 0 1316090960 9861632 5469280
14:36:57 62385 8192000000 10199628752 10617987072 1310701824 13.792 100 39 79 2 1326003600 6758992 8542784
14:39:03 17536 8192000000 10245355824 10654216192 1334552464 14.008 100 47 93 0 1352554736 7510752 10491520
14:40:26 16616 8192000000 10273876560 10686124032 1359769056 14.235 100 59 114 0 1388457568 15957056 12731456
14:41:47 37077 8192000000 10284663792 10711601152 1372662464 14.351 100 34 68 0 1394287344 13771680 7853200
14:43:26 22868 8192000000 10294086112 10720768000 1385317344 14.464 100 38 76 0 1410259616 15796800 9145472
14:45:00 8988 8192000000 10318248400 10742071296 1402016640 14.613 100 52 101 1 1429644288 15801840 11825808
14:46:19 9399 8192000000 10327159888 10752151552 1405661536 14.645 100 48 97 0 1437439344 20308560 11469248
14:47:38 9143 8192000000 10340147328 10762330112 1412700800 14.708 100 51 99 1 1450114384 25599376 11814208
14:48:55 8852 8192000000 10350025248 10771177472 1412228944 14.704 100 59 116 0 1454180112 27465360 14485808
14:50:13 8790 8192000000 10356164720 10769494016 1407792928 14.664 100 72 143 2 1465102592 39484224 17825440
14:51:29 5799 8192000000 10352881856 10762596352 1397192112 14.570 100 69 137 0 1457697600 42941936 17563552
14:52:40 2596 8192000000 10355880752 10767077376 1382879472 14.442 99 77 152 0 1451578816 49201984 19497360
14:53:50 2080 8192000000 10342888976 10755387392 1384842016 14.460 100 80 155 0 1446087376 42007296 19238064
14:54:55 5155 8192000000 10338224000 10752253952 1380690880 14.423 100 75 147 0 1447942880 49759552 17492448
14:56:07 33800 8192000000 10312590416 10751180800 1377196144 14.391 100 31 60 0 1421294816 36056272 8042400
14:57:43 13256 8192000000 10271757040 10697605120 1280878000 13.521 93 25 48 0 1326488240 39258800 6351440
14:59:07 13719 8192000000 10295792976 10723639296 1354085840 14.184 100 42 81 2 1403463120 39297504 10079776
15:00:23 10391 8192000000 10259517536 10683953152 1330755904 13.974 100 41 80 1 1377040288 36912224 9372160
15:01:38 78093 8192000000 10257227696 10691858432 1341882224 14.074 99 33 66 0 1369781792 19629920 8269648
15:04:00 32471 8192000000 10255165856 10692378624 1318788576 13.866 98 18 36 0 1347312976 24173456 4350944
15:05:35 19752 8192000000 10221561136 10661777408 1277016352 13.486 96 19 39 1 1300128896 19050752 4061792
15:06:56 22817 8192000000 10260364432 10687803392 1345109280 14.103 100 49 95 2 1362974928 7149856 10715792
15:08:22 63683 8192000000 10263666272 10697183232 1328361120 13.952 97 16 32 0 1340597376 9021616 3214640
15:10:27 76943 8192000000 10287514160 10735370240 1365981072 14.291 100 11 22 0 1384636896 16418000 2237824
15:12:45 66034 8192000000 10288494512 10724483072 1352195536 14.167 98 16 32 1 1369255568 12832192 4227840
15:15:10 101887 8192000000 10297180208 10740084736 1372712240 14.351 99 21 39 1 1387669184 10657456 4299488
15:17:53 62697 8192000000 10314343520 10763177984 1397912848 14.576 100 18 36 0 1417326944 15456256 3957840
15:19:57 118666 8192000000 10305920032 10758582272 1392629008 14.529 99 36 70 1 1412723248 11703920 8390320
15:23:01 44228 8192000000 10308250368 10773950464 1404572768 14.636 100 16 33 0 1422414864 14508256 3333840
15:24:47 18435 8192000000 10296552272 10742611968 1366899248 14.299 98 26 53 0 1380768928 7668576 6201104
15:26:07 37399 8192000000 10308564832 10763849728 1392888048 14.532 99 45 90 1 1415657616 12331968 10437600
15:27:57 42780 8192000000 10301913008 10757992448 1398110496 14.578 100 16 33 0 1412810256 10493056 4206704
15:29:46 71540 8192000000 10280621488 10740994048 1332531040 13.990 95 8 14 1 1342270192 8330224 1408928
15:32:47 44534 8192000000 10260616016 10719010816 1316876944 13.848 95 18 36 0 1332718096 11843552 3997600
15:34:33 84821 8192000000 10255282656 10721562624 1295598336 13.655 94 12 24 0 1315190400 16606720 2985344
15:37:07 32653 8192000000 10271033728 10710765568 1330977392 13.976 98 29 58 0 1350640480 13061840 6601248
15:38:41 35800 8192000000 10267923184 10714025984 1331645776 13.982 98 28 55 0 1352663456 14757392 6260288
15:40:20 41355 8192000000 10270376272 10717904896 1345197392 14.104 98 25 50 0 1362910256 12565760 5147104
15:42:07 47941 8192000000 10281700304 10726404096 1325276880 13.924 97 21 42 0 1341692064 11526416 4888768
15:43:58 54061 8192000000 10271054064 10715770880 1314275552 13.825 96 10 20 0 1333451568 16457328 2718688
15:45:55 76260 8192000000 10265890352 10699300864 1277845552 13.493 95 19 38 0 1294940816 12751376 4343888
15:48:13 31337 8192000000 10268638240 10704171008 1313160752 13.815 97 37 74 0 1334691488 13589024 7941712
15:49:44 105569 8192000000 10275891680 10713604096 1347389248 14.124 99 32 64 0 1363205536 8907392 6908896
15:52:34 47448 8192000000 10285476224 10737274880 1377132832 14.391 100 29 58 0 1396677008 13040272 6503904
15:54:26 20221 8192000000 10261030432 10718781440 1355261760 14.195 99 27 52 1 1374380544 12784816 6333968
15:56:02 45995 8192000000 10278354608 10727788544 1347174016 14.122 98 11 22 0 1362266800 12559936 2532848
15:57:50 51855 8192000000 10274981136 10745671680 1377259520 14.392 100 15 30 0 1394936976 13869216 3808240
15:59:48 45328 8192000000 10274770800 10748461056 1384389952 14.456 100 9 18 1 1399066128 12146960 2529216
16:01:35 55894 8192000000 10284139648 10746826752 1355149344 14.194 98 12 24 0 1370812032 12858688 2804000
16:03:32 60810 8192000000 10287280496 10745036800 1380858848 14.424 99 26 50 0 1403006368 16495408 5652112
16:05:33 118051 8192000000 10292697984 10745913344 1363018672 14.264 98 13 26 0 1382748736 16867872 2862192
16:08:34 34474 8192000000 10326537424 10778894336 1403312064 14.624 100 44 87 0 1429522752 16818608 9392080
16:10:15 61634 8192000000 10313500560 10782158848 1384990112 14.461 99 11 22 0 1411530720 24014464 2526144
16:12:29 72059 8192000000 10319885280 10780430336 1378403552 14.402 98 20 40 0 1403489120 20703472 4382096
16:14:47 26530 8192000000 10309117792 10753323008 1372490944 14.349 98 18 36 0 1386564128 10127696 3945488
16:16:15 79741 8192000000 10299402112 10761695232 1397071312 14.569 100 24 48 1 1414440576 11279760 6089504
16:18:35 63133 8192000000 10324335472 10768777216 1394356032 14.545 99 31 62 0 1412792480 11091936 7344512
16:20:39 62484 8192000000 10283425968 10750259200 1364472080 14.277 98 6 12 0 1379240960 13536048 1232832
16:22:41 59122 8192000000 10280634640 10733666304 1363983808 14.273 98 24 50 1 1380289248 10260160 6045280
16:24:44 60264 8192000000 10283757584 10737983488 1359773856 14.235 99 15 30 0 1377492800 13936480 3782464
16:26:47 55328 8192000000 10258192528 10719547392 1338826368 14.047 98 8 16 0 1357775888 17301920 1647600
16:28:42 69267 8192000000 10270003248 10728321024 1326991712 13.940 96 23 46 0 1349007248 16470704 5544832
16:30:59 71577 8192000000 10289879712 10741075968 1364372448 14.277 99 29 58 1 1382686256 12293264 6020544
16:33:13 35677 8192000000 10255022016 10690351104 1281273200 13.525 94 41 80 0 1299905760 9045024 9587536
16:34:53 18690 8192000000 10251849024 10687467520 1314999520 13.831 98 35 70 1 1331870448 9129840 7741088
16:36:13 36850 8192000000 10241228736 10688778240 1304104608 13.733 98 21 42 0 1323479248 14674624 4700016
16:37:57 18372 8192000000 10257720176 10683973632 1328412272 13.953 99 36 72 1 1348283552 12448176 7423104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-12-2022 16:39:49 to 29-12-2022 16:40:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-12-2022 16:40:05 to 29-12-2022 16:40:07.

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          246780
MON$OLDEST_ACTIVE               249098
MON$OLDEST_SNAPSHOT             249098
MON$NEXT_TRANSACTION            249098
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                       2029984
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1348: 
:1349: mscs-test-161.budzdorov.ru    Thu Dec 29 16:38:40 2022
:1350:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1351: 
:1352: 
:1353: mscs-test-161.budzdorov.ru    Thu Dec 29 16:38:50 2022
:1354:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1355: 
:1356: 
:1357: mscs-test-161.budzdorov.ru    Thu Dec 29 16:38:50 2022
:1358:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1359: 
:1360: 
:1361: mscs-test-161.budzdorov.ru    Thu Dec 29 16:38:52 2022
:1362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1363: 
--- end of diff output ---

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

29.12.22 16:40:08. 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
2022-12-29 16:40:11 No severe PSQL-related problems occured
29.12.22 16:40:12. End of report.