Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.10.2022 16:40
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 15360552 kB
MemAvailable 30440904 kB
Buffers 0 kB
Cached 16355580 kB
SwapCached 2040 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-10-27 16:38:31
DTS_END                         2022-10-27 16:38:31.3570
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/40478
TRN_ID                          253747
ATT_ID                          693
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: 9451.64 15654 1134197
customer order: creation 190.47 340 22856
customer order: refuse 15.58 324 1870
order to supplier: creation 50.45 1232 6054
order to supplier: removal 3.56 86145 427
invoice (draft): creation 49.37 3240 5924
invoice (draft): removal 51.43 742 6172
invoice accept: apply 45.23 17027 5428
invoice accept: cancel 46.38 808 5565
customer reserve: creation 4134.38 172 496125
customer reserve: removal 4546.99 53 545639
realization accept: apply 92.26 191 11071
realization accept: cancel 84.70 109 10164
payment from customer: creation 64.10 107 7692
payment from customer: removal 6.68 86 801
payment to supplier: creation 53.30 131 6396
payment to supplier: removal 3.74 83 449
service: total inventory turnovers 4.81 1084 577
service: total monetary turnovers 7.28 18 874
service: aggregate perf. data 0.91 19106 109
service: refresh index statistics 0.03 182083 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-10-2022 16:40:23 to 27-10-2022 16:40:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5721
TEST_TIME 2 4255
TEST_TIME 3 4436
TEST_TIME 4 4118
TEST_TIME 5 4090
TEST_TIME 6 4549
TEST_TIME 7 4332
TEST_TIME 8 4758
TEST_TIME 9 4736
TEST_TIME 10 4961
TEST_TIME 11 5575
TEST_TIME 12 5549
TEST_TIME 13 5745
TEST_TIME 14 6486
TEST_TIME 15 6714
TEST_TIME 16 6792
TEST_TIME 17 7011
TEST_TIME 18 7574
TEST_TIME 19 7544
TEST_TIME 20 7940
TEST_TIME 21 8146
TEST_TIME 22 8045
TEST_TIME 23 8396
TEST_TIME 24 8667
TEST_TIME 25 8874
TEST_TIME 26 9129
TEST_TIME 27 9328
TEST_TIME 28 9252
TEST_TIME 29 9923
TEST_TIME 30 10020
TEST_TIME 31 9891
TEST_TIME 32 9808
TEST_TIME 33 9746
TEST_TIME 34 9780
TEST_TIME 35 9865
TEST_TIME 36 9801
TEST_TIME 37 9768
TEST_TIME 38 9681
TEST_TIME 39 9807
TEST_TIME 40 9824
TEST_TIME 41 9929
TEST_TIME 42 9834
TEST_TIME 43 9716
TEST_TIME 44 9584
TEST_TIME 45 9556
TEST_TIME 46 9685
TEST_TIME 47 9582
TEST_TIME 48 9598
TEST_TIME 49 9543
TEST_TIME 50 9647
TEST_TIME 51 9613
TEST_TIME 52 9552
TEST_TIME 53 9465
TEST_TIME 54 9387
TEST_TIME 55 9286
TEST_TIME 56 9268
TEST_TIME 57 9308
TEST_TIME 58 9297
TEST_TIME 59 9315
TEST_TIME 60 9251
TEST_TIME 61 9287
TEST_TIME 62 9348
TEST_TIME 63 9390
TEST_TIME 64 9442
TEST_TIME 65 9444
TEST_TIME 66 9551
TEST_TIME 67 9612
TEST_TIME 68 9605
TEST_TIME 69 9589
TEST_TIME 70 9565
TEST_TIME 71 9568
TEST_TIME 72 9638
TEST_TIME 73 9606
TEST_TIME 74 9602
TEST_TIME 75 9545
TEST_TIME 76 9453
TEST_TIME 77 9462
TEST_TIME 78 9464
TEST_TIME 79 9399
TEST_TIME 80 9347
TEST_TIME 81 9350
TEST_TIME 82 9262
TEST_TIME 83 9351
TEST_TIME 84 9380
TEST_TIME 85 9375
TEST_TIME 86 9388
TEST_TIME 87 9452
TEST_TIME 88 9425
TEST_TIME 89 9491
TEST_TIME 90 9503
TEST_TIME 91 9517
TEST_TIME 92 9538
TEST_TIME 93 9523
TEST_TIME 94 9637
TEST_TIME 95 9648
TEST_TIME 96 9596
TEST_TIME 97 9561
TEST_TIME 98 9514
TEST_TIME 99 9494
TEST_TIME 100 9455
TEST_TIME 101 9488
TEST_TIME 102 9464
TEST_TIME 103 9433
TEST_TIME 104 9410
TEST_TIME 105 9369
TEST_TIME 106 9357
TEST_TIME 107 9322
TEST_TIME 108 9366
TEST_TIME 109 9371
TEST_TIME 110 9340
TEST_TIME 111 9393
TEST_TIME 112 9391
TEST_TIME 113 9434
TEST_TIME 114 9449
TEST_TIME 115 9464
TEST_TIME 116 9487
TEST_TIME 117 9514
TEST_TIME 118 9522
TEST_TIME 119 9520
TEST_TIME 120 9454

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-10-2022 16:40:27 to 27-10-2022 16:40:30.

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 1593797 1593797 0 0.00 0 15468 6 0 0
doc_list_biud 1593796 1593796 0 0.00 0 53916 24 0 0
sp_add_invoice_to_stock 5626 5428 198 3.51 36 165809 17027 0 198
sp_cancel_adding_invoice 5565 5565 0 0.00 4 69846 808 0 0
sp_cancel_client_order 1870 1870 0 0.00 4 12351 324 0 0
sp_cancel_customer_reserve 545639 545639 0 0.00 0 53917 53 0 0
sp_cancel_pay_from_customer 801 801 0 0.00 2 8175 86 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 4151 83 0 0
sp_cancel_supplier_invoice 6186 6172 14 0.22 1 15234 743 0 14
sp_cancel_supplier_order 427 427 0 0.00 6 849284 86146 0 0
sp_cancel_write_off 10164 10164 0 0.00 1 11954 110 0 0
sp_client_order 22856 22856 0 0.00 3 20754 340 0 0
sp_customer_reserve 496125 496125 0 0.00 0 74882 172 0 0
sp_fill_shopping_cart 34833 34833 0 0.00 1 14063 35 0 0
sp_fill_shopping_cart_clo_res 496123 496123 0 0.00 0 18355 12 0 0
sp_get_clo_for_invoice 5425 5425 0 0.00 0 35637 273 0 0
sp_kill_cost_storno 16979 16979 0 0.00 0 5840 47 0 0
sp_kill_qstorno_ret_qs2qd 1108041 1108041 0 0.00 0 53913 29 0 0
sp_kill_qty_storno 586333 586333 0 0.00 0 53915 64 0 0
sp_lock_selected_doc 22722 22722 0 0.00 0 9221 7 0 0
sp_make_cost_storno 30584 30584 0 0.00 0 12616 53 0 0
sp_multiply_rows_for_pdistr 30583 30583 0 0.00 0 7135 26 0 0
sp_multiply_rows_for_qdistr 22856 22856 0 0.00 0 20745 131 0 0
sp_pay_from_customer 7692 7692 0 0.00 4 17953 108 0 0
sp_pay_to_supplier 6396 6396 0 0.00 4 12348 131 0 0
sp_payment_common 14087 14087 0 0.00 1 17945 29 0 0
sp_qd_handle_on_cancel_clo 1870 1870 0 0.00 0 12340 291 0 0
sp_qd_handle_on_invoice_upd_sts 10990 10990 0 0.00 0 40350 311 0 0
sp_qd_handle_on_reserve_upd_sts 11071 11071 0 0.00 0 22226 67 0 0
sp_reserve_write_off 11071 11071 0 0.00 6 22513 192 0 0
sp_supplier_invoice 5924 5924 0 0.00 5 113103 3240 0 0
sp_supplier_order 6054 6054 0 0.00 4 27522 1232 0 0
srv_aggregate_perf_data 109 109 0 0.00 3002 262256 19107 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1636 149243 44068 0 0
srv_find_qd_qs_mism 1082397 1082397 0 0.00 0 15468 6 0 0
srv_make_invnt_saldo 577 577 0 0.00 30 94974 1085 0 0
srv_make_money_saldo 874 874 0 0.00 0 381 19 0 0
srv_recalc_idx_stat 4 4 0 0.00 113853 304180 182083 0 0
t$perf-abend:sp_add_invoice_to_stock 198 198 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5428 5428 0 0.00 0 20656 144 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 7017 132 0 0
t$perf-norm:sp_cancel_client_order 1870 1870 0 0.00 0 1572 11 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 515 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 801 801 0 0.00 0 1559 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 1967 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 446 446 0 0.00 0 1606 15 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 33359 1344 0 0
t$perf-norm:sp_cancel_write_off 1086 1086 0 0.00 0 6036 14 0 0
t$perf-norm:sp_client_order 22856 22856 0 0.00 0 9239 14 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 8184 25 0 0
t$perf-norm:sp_pay_from_customer 7692 7692 0 0.00 0 7580 10 0 0
t$perf-norm:sp_pay_to_supplier 6396 6396 0 0.00 0 5262 9 0 0
t$perf-norm:sp_reserve_write_off 11071 11071 0 0.00 0 6789 12 0 0
t$perf-norm:sp_supplier_invoice 5924 5924 0 0.00 0 4597 13 0 0
t$perf-norm:sp_supplier_order 6054 6054 0 0.00 0 4452 12 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 300 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 221 4 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 4033 24 0 0
t$perf-norm:srv_make_money_saldo 874 874 0 0.00 0 3263 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 7 0 0
v_add_invoice_to_stock 11246 10850 396 3.52 0 9421 17 0 396
v_all_customers 41352 41352 0 0.00 0 88 1 0 0
v_all_suppliers 32810 32810 0 0.00 0 24 1 0 0
v_all_wares 45712 45712 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2236 10 0 0
v_cancel_client_order 3740 3740 0 0.00 0 1336 4 0 0
v_cancel_customer_prepayment 1602 1602 0 0.00 0 842 6 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 3209 7 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 2541 6 0 0
v_cancel_supplier_invoice 920 892 28 3.04 0 985 13 0 28
v_cancel_supplier_order 854 854 0 0.00 0 1415 7 0 0
v_cancel_write_off 2172 2172 0 0.00 0 4964 17 0 0
v_max_id_clo_ord 6054 6054 0 0.00 0 1200 2 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 1871 8 0 0
v_max_id_ord_sup 5923 5923 0 0.00 0 7149 5 0 0
v_max_non_paid_invoice 1967 1967 0 0.00 0 980 3 0 0
v_max_non_paid_realizn 4143 4143 0 0.00 0 3659 5 0 0
v_min_id_clo_ord 6054 6054 0 0.00 0 7248 11 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 7466 19 0 0
v_min_id_ord_sup 5923 5923 0 0.00 0 7983 5 0 0
v_min_non_paid_invoice 6395 6395 0 0.00 0 10380 22 0 0
v_min_non_paid_realizn 7692 7692 0 0.00 0 17939 20 0 0
v_our_firm 11458 11458 0 0.00 0 15 1 0 0
v_reserve_write_off 22138 22138 0 0.00 0 6568 7 0 0
x_lock_depdocs_on_canc_invoice 11291 11291 0 0.00 0 22363 109 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 23548 360 0 0
x_make_qstorno_1000_1200 6054 6054 0 0.00 1 27512 1156 0 0
x_make_qstorno_1200_2000 5924 5924 0 0.00 2 112793 3115 0 0
x_make_qstorno_2100_3300 496125 496125 0 0.00 0 74880 157 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 27-10-2022 16:40:31 to 27-10-2022 16:40:36.

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:34 8 8192000000 8810650240 8826163200 14314688 0.174 6 2 3 0 14465840 76192 74960
14:09:36 31434 8192000000 9899226928 10204545024 979019712 10.675 100 86 172 0 1007534672 5927376 22587584
14:11:09 15970 8192000000 9933545472 10253783040 1019478704 11.067 100 79 158 0 1047731776 8190832 20062240
14:12:30 17036 8192000000 9979578704 10310119424 1058433376 11.441 100 83 166 0 1088852496 9882480 20536640
14:13:50 6940 8192000000 10029726208 10374094848 1118815904 12.016 100 84 168 0 1149456016 10962304 19677808
14:15:02 5835 8192000000 10074383040 10430750720 1161430864 12.417 100 74 148 1 1190501568 11836400 17234304
14:16:11 3427 8192000000 10116773168 10481963008 1206019936 12.832 100 79 158 1 1235738624 12071024 17647664
14:17:17 2948 8192000000 10159577760 10535030784 1247116672 13.212 100 84 164 0 1277091584 12016096 17958816
14:18:21 1075 8192000000 10187264064 10567213056 1273322176 13.452 100 93 186 0 1307136464 13757008 20057280
14:19:24 1261 8192000000 10212473424 10593607680 1294795040 13.648 100 89 176 1 1327862192 14300368 18766784
14:20:25 5243 8192000000 10201075600 10602426368 1299862320 13.694 100 57 110 3 1324305424 10874592 13568512
14:21:32 4676 8192000000 10203146512 10611650560 1311138736 13.796 100 36 70 1 1327561552 8853568 7569248
14:22:37 11741 8192000000 10216851552 10625798144 1319127968 13.869 100 24 48 1 1334822816 10069424 5625424
14:23:50 8733 8192000000 10212480944 10630705152 1326979728 13.940 100 21 42 2 1337706272 6128144 4598400
14:25:04 11344 8192000000 10221287616 10643058688 1341050464 14.067 100 26 52 0 1353708992 5867984 6790544
14:26:17 13665 8192000000 10233975040 10658455552 1342072816 14.076 100 26 52 0 1358672464 10578032 6021616
14:27:31 5540 8192000000 10217801328 10638761984 1323714128 13.910 99 24 48 0 1334717312 5998784 5004400
14:28:38 11455 8192000000 10206067632 10616434688 1283313328 13.543 97 21 43 1 1293419312 5166032 4939952
14:29:50 12532 8192000000 10147523296 10550538240 1200235824 12.779 93 23 44 1 1210857744 4707440 5914480
14:31:04 9631 8192000000 10127271296 10530922496 1179539408 12.586 93 29 57 2 1194374400 8114368 6720624
14:32:14 4483 8192000000 10123667056 10517618688 1176213296 12.555 94 27 56 0 1187737664 5537552 5986816
14:33:21 8922 8192000000 10161502672 10567499776 1268749056 13.410 100 16 32 0 1277782528 5742416 3291056
14:34:33 62666 8192000000 10178177024 10597302272 1293037664 13.632 100 25 48 1 1308026704 9607712 5381328
14:36:37 8800 8192000000 10196784304 10605879296 1302121104 13.715 100 37 74 0 1317861376 7291520 8448752
14:37:47 11696 8192000000 10204310736 10620907520 1313648704 13.819 100 15 30 0 1323046736 6098464 3299568
14:39:03 31798 8192000000 10247372224 10662141952 1344225056 14.095 100 47 94 0 1361750048 6591440 10933552
14:40:42 57205 8192000000 10265461648 10690031616 1361203344 14.248 100 52 105 0 1387274640 13660336 12410960
14:42:41 5904 8192000000 10284728272 10704547840 1372596480 14.350 100 54 106 1 1402496240 17455664 12444096
14:43:49 29520 8192000000 10286478688 10715922432 1381226560 14.428 100 37 72 0 1406961488 17287584 8447344
14:45:38 24135 8192000000 10318810528 10741653504 1398275744 14.580 100 50 98 0 1427028752 17279600 11473408
14:47:10 14536 8192000000 10323709440 10745044992 1402137888 14.614 100 56 110 1 1434795600 19211440 13446272
14:48:32 5197 8192000000 10333628048 10757324800 1409259456 14.677 100 66 129 0 1451343440 26533296 15550688
14:49:42 7325 8192000000 10332970096 10758520832 1388352592 14.491 98 56 110 4 1430074976 27926112 13796272
14:50:54 8124 8192000000 10349095104 10768723968 1409517808 14.680 100 72 144 0 1462136432 34825360 17793264
14:52:06 2678 8192000000 10337323600 10758406144 1391161632 14.516 99 60 121 0 1445862480 38829776 15871072
14:53:14 7672 8192000000 10338639040 10761355264 1396689472 14.566 99 60 120 2 1439986144 28523408 14773264
14:54:36 14124 8192000000 10325760992 10746159104 1376180240 14.382 98 47 94 1 1419488048 31357728 11950080
14:56:01 1635 8192000000 10335089328 10743390208 1380522144 14.421 100 82 165 1 1438641168 37933232 20185792
14:57:04 13982 8192000000 10272285680 10697617408 1339069088 14.049 99 25 50 0 1378040800 32267680 6704032
14:58:22 2523 8192000000 10294834144 10708398080 1341157184 14.068 99 69 135 0 1399671392 41378560 17135648
14:59:28 5970 8192000000 10323080368 10733813760 1368873712 14.317 100 56 108 1 1419628288 36137904 14616672
15:00:35 12982 8192000000 10313750144 10732052480 1359417312 14.232 99 36 72 0 1413647648 44445744 9784592
15:01:51 9589 8192000000 10315870592 10743410688 1357184096 14.212 99 37 72 0 1417108048 49450064 10473888
15:03:02 15574 8192000000 10308311824 10737844224 1372719376 14.351 100 26 52 0 1423332384 43164432 7448576
15:04:18 18529 8192000000 10307752704 10742439936 1379977504 14.416 100 29 58 1 1422675120 35112000 7585616
15:05:51 13933 8192000000 10285771136 10725765120 1354236400 14.186 100 28 56 0 1403999872 42148272 7615200
15:08:02 105780 8192000000 10274793136 10715799552 1317662768 13.856 96 10 19 0 1339362160 19872528 1826864
15:10:48 56149 8192000000 10303769984 10726580224 1367410128 14.304 99 27 53 1 1382233168 8772480 6050560
15:12:45 95848 8192000000 10296363648 10751225856 1342773040 14.082 97 10 20 1 1359198672 14248832 2176800
15:15:38 34145 8192000000 10314969600 10757324800 1394964672 14.550 100 37 75 0 1411505264 7374656 9165936
15:18:31 149243 8192000000 10311522912 10762403840 1368308944 14.312 97 10 20 0 1380944048 10424576 2210528
15:22:09 73442 8192000000 10336945504 10785652736 1419226736 14.766 100 36 68 0 1443189312 15508464 8454112
15:24:53 51572 8192000000 10337676336 10787364864 1415270544 14.731 99 33 66 0 1431311440 8630736 7410160
15:26:47 50522 8192000000 10335712816 10786922496 1418285120 14.757 100 24 48 0 1435903376 11629840 5988416
15:28:49 61730 8192000000 10304571840 10776276992 1374071712 14.364 97 8 16 0 1385782480 9902304 1808464
15:30:52 37186 8192000000 10285412080 10751918080 1377568368 14.395 98 9 18 0 1387742272 7823344 2350560
15:32:37 132049 8192000000 10318624864 10789572608 1394365008 14.545 98 16 32 0 1409781184 11509936 3906240
15:36:10 28013 8192000000 10306694864 10775777280 1412662624 14.708 100 19 38 0 1425616384 8781232 4172528
15:37:39 77082 8192000000 10285577872 10748243968 1279160304 13.505 91 8 16 0 1290121664 9194224 1767136
15:39:57 15075 8192000000 10254433120 10694770688 1334808480 14.011 99 41 82 0 1354048112 9846432 9393200
15:41:13 22167 8192000000 10274396976 10715127808 1350225168 14.150 99 39 78 0 1370633136 11054176 9353792
15:42:39 17471 8192000000 10281363632 10725003264 1368662464 14.315 100 35 70 0 1384202704 7876272 7663968
15:44:04 29015 8192000000 10285768608 10739118080 1368226528 14.311 100 24 48 0 1387031856 13188272 5617056
15:45:33 61709 8192000000 10243818192 10695421952 1297341824 13.671 95 10 20 0 1315146592 15486384 2318384
15:47:38 24114 8192000000 10277167984 10719141888 1350046288 14.148 99 32 64 0 1366143568 9285600 6811680
15:49:04 44611 8192000000 10275147840 10721964032 1338722448 14.046 98 39 78 0 1362349360 15411520 8215392
15:51:05 34271 8192000000 10292043216 10730848256 1354657328 14.189 99 38 76 1 1381075328 17851776 8566224
15:52:40 90398 8192000000 10254626672 10710089728 1340249872 14.060 98 25 49 0 1355531344 9484560 5796912
15:55:18 63863 8192000000 10267307200 10723840000 1356865712 14.209 99 30 59 0 1371978512 8921344 6191456
15:57:23 65727 8192000000 10261239920 10718765056 1339699728 14.055 98 8 16 0 1352289552 10772032 1817792
15:59:32 61668 8192000000 10262091744 10724843520 1335478144 14.017 98 10 20 0 1350391280 12373968 2539168
16:01:34 56876 8192000000 10267149072 10730303488 1365616640 14.288 100 14 28 0 1380753088 12096496 3039952
16:03:31 32802 8192000000 10264994208 10711965696 1338330992 14.042 98 33 66 0 1356613104 10533216 7748896
16:05:16 53731 8192000000 10280481728 10733109248 1372651232 14.351 100 16 32 0 1384699424 8684592 3363600
16:07:10 91378 8192000000 10302184144 10756243456 1387234672 14.481 100 22 44 0 1403955600 11746704 4974224
16:09:57 62698 8192000000 10306412144 10758549504 1358249056 14.222 97 20 40 0 1374523808 12732960 3541792
16:12:01 79355 8192000000 10299795136 10770980864 1379529008 14.412 99 18 35 2 1398548400 14108800 4910592
16:14:22 44034 8192000000 10300628496 10745303040 1365343168 14.285 98 39 77 1 1381328032 7939744 8045120
16:16:06 52972 8192000000 10290307216 10750693376 1392864784 14.531 100 13 26 0 1402307856 6572000 2871072
16:17:59 71049 8192000000 10288588848 10737807360 1343473616 14.089 97 41 83 0 1368358464 15261312 9623536
16:20:10 50860 8192000000 10261859392 10721935360 1343065360 14.085 97 19 38 0 1354352368 6909328 4377680
16:22:16 53579 8192000000 10280049360 10724282368 1336272640 14.024 97 20 40 0 1354372048 13832704 4266704
16:24:12 42214 8192000000 10271745888 10735329280 1362477616 14.260 99 18 34 0 1380582400 13641184 4463600
16:25:59 54329 8192000000 10281573024 10738012160 1359827152 14.236 99 19 38 0 1375295536 11047296 4421088
16:27:54 40526 8192000000 10255187168 10697687040 1304191440 13.733 96 17 34 1 1317985584 9947536 3846608
16:29:52 12748 8192000000 10238382320 10677075968 1300467600 13.699 97 31 63 0 1315065760 7771872 6826288
16:31:05 80367 8192000000 10259352192 10717560832 1336233456 14.023 99 16 30 0 1355528128 16353248 2941424
16:33:32 53108 8192000000 10267951616 10711547904 1318441856 13.863 97 16 32 0 1333400976 11207968 3751152
16:35:40 108200 8192000000 10248067712 10698293248 1289836528 13.603 95 14 28 0 1304245808 11367584 3041696
16:38:29 37747 8192000000 9618908656 9970114560 762026064 8.510 56 2 4 0 769983920 7671408 286448

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-10-2022 16:40:38 to 27-10-2022 16:40:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 198 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-10-2022 16:40:54 to 27-10-2022 16:40:56.

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          255497
MON$OLDEST_ACTIVE               255498
MON$OLDEST_SNAPSHOT             255498
MON$NEXT_TRANSACTION            255498
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                       2035232
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.33637 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33637 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33637 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33637 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 ---
:278: 
:279: mscs-test-161.budzdorov.ru    Thu Oct 27 16:39:24 2022
:280:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:281: 
:282: 
:283: mscs-test-161.budzdorov.ru    Thu Oct 27 16:39:24 2022
:284:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:285: 
:286: 
:287: mscs-test-161.budzdorov.ru    Thu Oct 27 16:39:24 2022
:288:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:289: 
--- end of diff output ---

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

27.10.22 16:40:57. 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-10-27 16:40:59 No severe PSQL-related problems occured
27.10.22 16:41:01. End of report.