Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.06.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 16248632 kB
MemAvailable 31192128 kB
Buffers 0 kB
Cached 15435836 kB
SwapCached 2496 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.10
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-06-24 16:38:26
DTS_END                         2022-06-24 16:38:26.3710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36338
TRN_ID                          238210
ATT_ID                          627
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: 9306.76 26246 1116811
customer order: creation 165.98 391 19917
customer order: refuse 13.75 327 1650
order to supplier: creation 44.42 1256 5330
order to supplier: removal 3.53 100347 424
invoice (draft): creation 44.40 3358 5328
invoice (draft): removal 55.48 842 6658
invoice accept: apply 39.97 18248 4796
invoice accept: cancel 48.96 797 5875
customer reserve: creation 3756.03 179 450723
customer reserve: removal 4846.01 56 581521
realization accept: apply 80.92 179 9710
realization accept: cancel 83.98 109 10078
payment from customer: creation 56.79 108 6815
payment from customer: removal 5.61 63 673
payment to supplier: creation 46.28 122 5553
payment to supplier: removal 3.24 87 389
service: total inventory turnovers 4.57 1065 548
service: total monetary turnovers 6.15 21 738
service: aggregate perf. data 0.68 26908 81
service: refresh index statistics 0.03 370458 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-06-2022 16:39:37 to 24-06-2022 16:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3667
TEST_TIME 2 6297
TEST_TIME 3 6019
TEST_TIME 4 5211
TEST_TIME 5 5155
TEST_TIME 6 4858
TEST_TIME 7 4940
TEST_TIME 8 4949
TEST_TIME 9 4913
TEST_TIME 10 4892
TEST_TIME 11 5115
TEST_TIME 12 5165
TEST_TIME 13 5324
TEST_TIME 14 5628
TEST_TIME 15 6329
TEST_TIME 16 6150
TEST_TIME 17 6881
TEST_TIME 18 7653
TEST_TIME 19 8119
TEST_TIME 20 8651
TEST_TIME 21 9792
TEST_TIME 22 9571
TEST_TIME 23 9870
TEST_TIME 24 10226
TEST_TIME 25 10951
TEST_TIME 26 10902
TEST_TIME 27 10697
TEST_TIME 28 10504
TEST_TIME 29 10445
TEST_TIME 30 10816
TEST_TIME 31 10571
TEST_TIME 32 10456
TEST_TIME 33 10303
TEST_TIME 34 10256
TEST_TIME 35 10319
TEST_TIME 36 10293
TEST_TIME 37 10151
TEST_TIME 38 10045
TEST_TIME 39 10072
TEST_TIME 40 9999
TEST_TIME 41 9991
TEST_TIME 42 9983
TEST_TIME 43 9841
TEST_TIME 44 9749
TEST_TIME 45 9826
TEST_TIME 46 10105
TEST_TIME 47 10039
TEST_TIME 48 10071
TEST_TIME 49 9986
TEST_TIME 50 9871
TEST_TIME 51 9842
TEST_TIME 52 10059
TEST_TIME 53 10107
TEST_TIME 54 10105
TEST_TIME 55 9944
TEST_TIME 56 9831
TEST_TIME 57 9889
TEST_TIME 58 9765
TEST_TIME 59 9690
TEST_TIME 60 9718
TEST_TIME 61 9705
TEST_TIME 62 9643
TEST_TIME 63 9537
TEST_TIME 64 9523
TEST_TIME 65 9499
TEST_TIME 66 9529
TEST_TIME 67 9541
TEST_TIME 68 9553
TEST_TIME 69 9540
TEST_TIME 70 9607
TEST_TIME 71 9538
TEST_TIME 72 9615
TEST_TIME 73 9641
TEST_TIME 74 9719
TEST_TIME 75 9661
TEST_TIME 76 9672
TEST_TIME 77 9699
TEST_TIME 78 9677
TEST_TIME 79 9581
TEST_TIME 80 9595
TEST_TIME 81 9542
TEST_TIME 82 9556
TEST_TIME 83 9469
TEST_TIME 84 9452
TEST_TIME 85 9412
TEST_TIME 86 9376
TEST_TIME 87 9361
TEST_TIME 88 9423
TEST_TIME 89 9396
TEST_TIME 90 9413
TEST_TIME 91 9407
TEST_TIME 92 9438
TEST_TIME 93 9478
TEST_TIME 94 9557
TEST_TIME 95 9521
TEST_TIME 96 9592
TEST_TIME 97 9639
TEST_TIME 98 9603
TEST_TIME 99 9613
TEST_TIME 100 9655
TEST_TIME 101 9568
TEST_TIME 102 9528
TEST_TIME 103 9537
TEST_TIME 104 9533
TEST_TIME 105 9505
TEST_TIME 106 9489
TEST_TIME 107 9440
TEST_TIME 108 9403
TEST_TIME 109 9397
TEST_TIME 110 9356
TEST_TIME 111 9375
TEST_TIME 112 9345
TEST_TIME 113 9324
TEST_TIME 114 9342
TEST_TIME 115 9347
TEST_TIME 116 9365
TEST_TIME 117 9385
TEST_TIME 118 9361
TEST_TIME 119 9345
TEST_TIME 120 9315

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-06-2022 16:39:41 to 24-06-2022 16:39:44.

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 1534477 1534477 0 0.00 0 29764 6 0 0
doc_list_biud 1534472 1534472 0 0.00 0 31603 28 0 0
sp_add_invoice_to_stock 4917 4796 121 2.46 54 215619 18249 0 121
sp_cancel_adding_invoice 5875 5875 0 0.00 5 94692 798 0 0
sp_cancel_client_order 1650 1650 0 0.00 4 9814 327 0 0
sp_cancel_customer_reserve 581521 581521 0 0.00 1 31608 57 0 0
sp_cancel_pay_from_customer 673 673 0 0.00 3 3355 63 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 11263 88 0 0
sp_cancel_supplier_invoice 6666 6658 8 0.12 2 19447 842 0 8
sp_cancel_supplier_order 424 424 0 0.00 5 1242185 100348 0 0
sp_cancel_write_off 10078 10078 0 0.00 1 16284 109 0 0
sp_client_order 19917 19917 0 0.00 3 39894 392 0 0
sp_customer_reserve 450723 450723 0 0.00 0 80238 179 0 0
sp_fill_shopping_cart 30562 30562 0 0.00 1 11144 35 0 0
sp_fill_shopping_cart_clo_res 450709 450709 0 0.00 0 15377 15 0 0
sp_get_clo_for_invoice 4781 4781 0 0.00 0 29281 310 0 0
sp_kill_cost_storno 17015 17015 0 0.00 0 11128 54 0 0
sp_kill_qstorno_ret_qs2qd 1180202 1180202 0 0.00 0 31544 31 0 0
sp_kill_qty_storno 620696 620696 0 0.00 0 31601 68 0 0
sp_lock_selected_doc 20006 20006 0 0.00 0 7308 5 0 0
sp_make_cost_storno 26859 26859 0 0.00 0 16909 54 0 0
sp_multiply_rows_for_pdistr 26859 26859 0 0.00 0 8543 26 0 0
sp_multiply_rows_for_qdistr 19917 19917 0 0.00 0 17740 159 0 0
sp_pay_from_customer 6815 6815 0 0.00 4 14808 109 0 0
sp_pay_to_supplier 5553 5553 0 0.00 4 14538 122 0 0
sp_payment_common 12368 12368 0 0.00 1 14568 26 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 9807 306 0 0
sp_qd_handle_on_invoice_upd_sts 10656 10656 0 0.00 0 29085 329 0 0
sp_qd_handle_on_reserve_upd_sts 9709 9709 0 0.00 0 12808 63 0 0
sp_reserve_write_off 9710 9710 0 0.00 6 17083 180 0 0
sp_supplier_invoice 5328 5328 0 0.00 4 104257 3359 0 0
sp_supplier_order 5330 5330 0 0.00 5 87444 1257 0 0
srv_aggregate_perf_data 81 81 0 0.00 2739 487856 26909 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1697 170216 52304 0 0
srv_find_qd_qs_mism 1070880 1070880 0 0.00 0 29764 6 0 0
srv_make_invnt_saldo 548 548 0 0.00 45 54562 1065 0 0
srv_make_money_saldo 738 738 0 0.00 1 588 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 91837 620754 370458 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4796 4796 0 0.00 0 17866 180 0 0
t$perf-norm:sp_cancel_adding_invoice 351 351 0 0.00 1 9348 109 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 4630 17 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 3797 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 673 673 0 0.00 0 650 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 636 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2263 18 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 18689 1552 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 4629 9 0 0
t$perf-norm:sp_client_order 19917 19917 0 0.00 0 10128 19 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 4161 11 0 0
t$perf-norm:sp_pay_from_customer 6815 6815 0 0.00 0 5195 10 0 0
t$perf-norm:sp_pay_to_supplier 5553 5553 0 0.00 0 6552 11 0 0
t$perf-norm:sp_reserve_write_off 9710 9710 0 0.00 0 7735 12 0 0
t$perf-norm:sp_supplier_invoice 5328 5328 0 0.00 0 4674 18 0 0
t$perf-norm:sp_supplier_order 5330 5330 0 0.00 0 4887 19 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 1639 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 76 2 0 0
t$perf-norm:srv_make_invnt_saldo 548 548 0 0.00 0 661 14 0 0
t$perf-norm:srv_make_money_saldo 738 738 0 0.00 0 1754 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9796 9554 242 2.47 0 6509 22 0 242
v_all_customers 36196 36196 0 0.00 0 11 0 0 0
v_all_suppliers 29528 29528 0 0.00 0 27 1 0 0
v_all_wares 39828 39828 0 0.00 0 75 1 0 0
v_cancel_adding_invoice 702 702 0 0.00 0 2024 8 0 0
v_cancel_client_order 3300 3300 0 0.00 0 4104 6 0 0
v_cancel_customer_prepayment 1346 1346 0 0.00 0 3290 7 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 643 4 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 1202 5 0 0
v_cancel_supplier_invoice 858 842 16 1.86 0 2293 20 0 16
v_cancel_supplier_order 848 848 0 0.00 0 3314 10 0 0
v_cancel_write_off 1884 1884 0 0.00 0 3625 16 0 0
v_max_id_clo_ord 5327 5327 0 0.00 0 215 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 2165 22 0 0
v_max_id_ord_sup 5320 5320 0 0.00 0 1687 2 0 0
v_max_non_paid_invoice 1436 1436 0 0.00 0 481 3 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 4658 7 0 0
v_min_id_clo_ord 5327 5327 0 0.00 0 1795 4 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 1921 6 0 0
v_min_id_ord_sup 5320 5320 0 0.00 0 7652 10 0 0
v_min_non_paid_invoice 5553 5553 0 0.00 0 7262 20 0 0
v_min_non_paid_realizn 6815 6815 0 0.00 0 8380 17 0 0
v_our_firm 9968 9968 0 0.00 0 16 1 0 0
v_reserve_write_off 19418 19418 0 0.00 0 9330 8 0 0
x_lock_depdocs_on_canc_invoice 12112 12112 0 0.00 0 10909 111 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 12795 378 0 0
x_make_qstorno_1000_1200 5330 5330 0 0.00 1 87377 1188 0 0
x_make_qstorno_1200_2000 5328 5328 0 0.00 1 104220 3233 0 0
x_make_qstorno_2100_3300 450723 450723 0 0.00 0 80218 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-06-2022 16:39:45 to 24-06-2022 16:39:49.

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:30 12 8192000000 8806553920 8820592640 11807296 0.143 3 3 4 0 11991216 76800 107120
14:09:31 19879 8192000000 9881982272 10188644352 967199744 10.559 100 88 174 1 996782400 5988384 23594272
14:10:58 25095 8192000000 9933691712 10251579392 1015543696 11.029 100 87 174 0 1045820032 7837696 22438640
14:12:25 17852 8192000000 9975595088 10304421888 1050049808 11.361 100 80 160 0 1084235280 14134160 20051312
14:13:52 10810 8192000000 10035005696 10378514432 1112912560 11.960 100 79 159 0 1142868016 10954736 19000720
14:15:04 7772 8192000000 10084298192 10443722752 1174216016 12.536 100 73 148 0 1202109728 10991712 16902000
14:16:17 4590 8192000000 10130924320 10502553600 1225017088 13.008 100 86 172 1 1257115376 13170384 18927904
14:17:24 2572 8192000000 10176006464 10552438784 1263805872 13.365 100 92 180 1 1298421040 14093968 20521200
14:18:29 1232 8192000000 10214041344 10594050048 1287718272 13.583 100 92 185 2 1320933616 12690112 20525232
14:19:31 3101 8192000000 10185508624 10586959872 1296838256 13.666 100 46 91 0 1316137600 9553984 9745360
14:20:36 2444 8192000000 10190124544 10599182336 1305157872 13.742 100 42 81 1 1320927232 5285872 10483488
14:21:40 14834 8192000000 10203143760 10612051968 1314721616 13.829 100 25 49 5 1326114464 5952048 5440800
14:22:55 21478 8192000000 10216213600 10628730880 1319861184 13.875 100 38 75 3 1336398880 7409808 9127888
14:24:20 4916 8192000000 10226863968 10641514496 1329176640 13.960 100 34 68 3 1347179792 9376656 8626496
14:25:28 9272 8192000000 10222039920 10647056384 1338367104 14.043 100 19 37 0 1347672656 4871264 4434288
14:26:38 7686 8192000000 10226173792 10650206208 1330125088 13.968 99 21 41 1 1340599056 5631376 4842592
14:27:50 8552 8192000000 10244212288 10659196928 1344401104 14.097 100 49 100 1 1361742800 6027296 11314400
14:28:59 3684 8192000000 10198629840 10605584384 1275112032 13.468 97 45 90 0 1292948128 7485552 10350544
14:30:05 9148 8192000000 10131124832 10527854592 1211325024 12.881 97 21 42 2 1221369568 5376240 4668304
14:31:16 4935 8192000000 10149521216 10552774656 1259011904 13.321 100 25 51 0 1270734208 5761872 5960432
14:32:22 39189 8192000000 10155352416 10556653568 1228906240 13.044 97 49 92 2 1245224416 5401984 10916192
14:34:05 7052 8192000000 10162845152 10576723968 1277748624 13.492 100 25 50 0 1289768048 6462112 5557312
14:35:13 10908 8192000000 10186591344 10599919616 1295746720 13.657 100 24 48 1 1306582272 5230080 5605472
14:36:26 71557 8192000000 10209323936 10616586240 1308687504 13.774 100 49 99 1 1325957904 5280640 11989760
14:38:43 26299 8192000000 10240987696 10649460736 1331045648 13.977 100 41 82 0 1349704464 9735280 8923536
14:40:13 34072 8192000000 10257977104 10677952512 1348854384 14.137 100 43 84 1 1374861488 15406960 10600144
14:41:50 23829 8192000000 10279709520 10701500416 1370852496 14.335 100 46 92 0 1397594992 16229312 10513184
14:43:19 26704 8192000000 10302637680 10730315776 1388228240 14.490 100 32 64 0 1420556096 24832096 7495760
14:44:48 17693 8192000000 10316555472 10743087104 1401414784 14.608 100 42 84 0 1437546256 25735184 10396288
14:46:11 28274 8192000000 10347222944 10773983232 1411897728 14.701 100 48 96 1 1455068816 31314320 11856768
14:47:45 8985 8192000000 10364230576 10788245504 1423825920 14.807 100 53 106 1 1474390992 36989792 13575280
14:49:05 1805 8192000000 10370403584 10782167040 1414490480 14.724 100 78 156 1 1475032480 40464496 20077504
14:50:12 9008 8192000000 10377612336 10799116288 1424856560 14.816 100 66 132 0 1487193600 45634832 16702208
14:51:24 6698 8192000000 10370495280 10784571392 1409370336 14.678 100 71 142 2 1482427952 55104160 17953456
14:52:34 1696 8192000000 10399672016 10813079552 1423155408 14.801 100 80 160 1 1502548416 58604048 20788960
14:53:39 17270 8192000000 10343048064 10768941056 1380817328 14.424 98 55 110 0 1458272960 62898176 14557456
14:55:03 9587 8192000000 10348321632 10758533120 1358106048 14.220 99 63 126 1 1448176960 72791936 17278976
14:56:17 7706 8192000000 10328673152 10740965376 1334321632 14.006 98 43 86 0 1411461664 65056464 12083568
14:57:29 3509 8192000000 10312594544 10719637504 1328846880 13.957 98 66 129 0 1407637680 61143808 17646992
14:58:36 6832 8192000000 10315655472 10733146112 1346462368 14.116 99 44 88 0 1417114096 58930160 11721568
14:59:48 37199 8192000000 10301557296 10734039040 1358590896 14.225 99 26 52 2 1397537264 32028224 6918144
15:01:28 25123 8192000000 10281591040 10722054144 1318883248 13.867 97 16 32 1 1360596064 36896192 4816624
15:02:55 34476 8192000000 10276120592 10716897280 1320581152 13.882 98 20 40 0 1364155568 38593648 4980768
15:04:32 78692 8192000000 10277665936 10713538560 1345061744 14.103 99 37 74 0 1368243824 14094768 9087312
15:06:57 102187 8192000000 10274281856 10729558016 1329026720 13.958 98 39 78 0 1362006256 23399312 9580224
15:09:57 72576 8192000000 10270280352 10710605824 1325419296 13.926 97 35 69 2 1343169936 10267648 7482992
15:12:37 92229 8192000000 10263115808 10715058176 1337889632 14.038 98 13 26 2 1355220880 14774832 2556416
15:15:10 24825 8192000000 10258654352 10712285184 1363283424 14.267 100 9 18 1 1374633824 9436048 1914352
15:16:35 62314 8192000000 10273255568 10727333888 1376497632 14.385 100 16 33 0 1390181632 10261408 3422592
15:18:39 26285 8192000000 10276282048 10722451456 1356726256 14.208 98 44 87 0 1373749968 6549216 10474496
15:20:11 56059 8192000000 10276559472 10730479616 1371954416 14.345 99 16 32 1 1382374576 7121296 3298864
15:22:09 48439 8192000000 10326958608 10771898368 1405906304 14.648 100 26 52 2 1427429680 15046544 6476832
15:24:03 81939 8192000000 10297515936 10753605632 1394667520 14.547 100 9 18 1 1417612800 20803120 2142160
15:26:25 70696 8192000000 10316766944 10756591616 1362161888 14.257 97 16 32 0 1375960000 9949072 3849040
15:28:39 20543 8192000000 10326794624 10766667776 1401495216 14.608 100 48 96 0 1428343328 16146400 10701712
15:30:11 71562 8192000000 10301828448 10757468160 1369714352 14.324 98 6 12 0 1396808816 25573392 1521072
15:32:23 104030 8192000000 10278182576 10724880384 1347830160 14.128 97 10 20 0 1365916768 15732192 2354416
15:35:30 86671 8192000000 10289026064 10741596160 1367194176 14.302 98 25 50 1 1385504256 12389936 5920144
15:37:58 44991 8192000000 10280628624 10737586176 1367752160 14.307 99 32 64 2 1389048400 13713360 7582880
15:40:54 74181 8192000000 10275966160 10731528192 1376727136 14.387 100 15 30 0 1391322224 11411456 3183632
15:43:37 60550 8192000000 10260706656 10715541504 1296794000 13.666 94 12 24 0 1309955872 10146400 3015472
15:45:55 77670 8192000000 10273313664 10718736384 1308021840 13.768 96 16 32 1 1326980576 14927072 4031664
15:48:16 65568 8192000000 10270003040 10703540224 1312735152 13.811 97 27 54 2 1334223328 14879504 6608672
15:50:27 51330 8192000000 10260424880 10711212032 1317892080 13.858 98 24 48 0 1339518400 16557488 5068832
15:52:19 56502 8192000000 10271706352 10708082688 1313012912 13.813 97 14 28 1 1325910480 10152064 2745504
15:54:18 32481 8192000000 10257227120 10685153280 1303919728 13.731 97 29 58 1 1322960208 12444272 6596208
15:55:53 26147 8192000000 10279477472 10707763200 1355469504 14.197 100 43 85 1 1375506880 11166160 8871216
15:57:19 97682 8192000000 10257332192 10709929984 1336793472 14.028 98 28 56 3 1352062976 8477488 6792016
16:01:29 61790 8192000000 10274405584 10732544000 1360769840 14.244 99 10 20 0 1378004368 14904880 2329648
16:03:59 77236 8192000000 10279245616 10739978240 1353610400 14.180 98 11 22 0 1371185616 14570656 3004560
16:06:17 59063 8192000000 10288941648 10746191872 1380318816 14.419 100 12 25 0 1389760768 6853088 2588864
16:08:21 101260 8192000000 10270561568 10721693696 1313194848 13.815 94 17 34 0 1329042416 11832880 4014688
16:11:03 66226 8192000000 10300977648 10766364672 1370011920 14.327 99 14 28 2 1389255040 16153408 3089712
16:13:10 48559 8192000000 10315790832 10763980800 1392248512 14.526 100 22 44 0 1416535808 19511264 4776032
16:15:00 170216 8192000000 10323216592 10771488768 1381557088 14.430 98 24 48 1 1405807264 18834560 5415616
16:19:02 107787 8192000000 10305298352 10762375168 1399097584 14.587 100 29 59 1 1417090464 10912256 7080624
16:21:50 41487 8192000000 10307114800 10777268224 1390828112 14.513 99 20 40 0 1411898864 16637120 4433632
16:23:36 54399 8192000000 10253795936 10709106688 1306742912 13.757 94 19 37 1 1321150400 9886928 4520560
16:25:32 111335 8192000000 10273955280 10727522304 1351946896 14.165 98 16 32 2 1365868448 10339600 3581952
16:28:23 124311 8192000000 10273741744 10747113472 1349022336 14.139 98 10 20 0 1365326016 13935456 2368224
16:31:28 61684 8192000000 10261677936 10724388864 1336316544 14.024 97 14 28 0 1348403856 8842672 3244640
16:33:31 70191 8192000000 10275758336 10735706112 1342415424 14.079 97 16 33 1 1357194816 11359024 3420368
16:35:43 49676 8192000000 10297447888 10734141440 1353943216 14.183 98 34 68 2 1375468384 13789744 7735424
16:37:35 20000 8192000000 10294649120 10738204672 1364207536 14.275 99 40 80 0 1393561360 20178176 9175648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-06-2022 16:39:51 to 24-06-2022 16:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-06-2022 16:40:07 to 24-06-2022 16:40:09.

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          234007
MON$OLDEST_ACTIVE               238751
MON$OLDEST_SNAPSHOT             238751
MON$NEXT_TRANSACTION            238751
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                       2027424
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:358: 
:359: mscs-test-161.budzdorov.ru    Fri Jun 24 16:38:29 2022
:360:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:361: 
:362: 
:363: mscs-test-161.budzdorov.ru    Fri Jun 24 16:38:29 2022
:364:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:365: 
--- end of diff output ---

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

24.06.22 16:40:10. 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-06-24 16:40:12 No severe PSQL-related problems occured
24.06.22 16:40:13. End of report.