Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2021 01:38
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 16355692 kB
MemAvailable 31428368 kB
Buffers 0 kB
Cached 15287408 kB
SwapCached 0 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.9
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 2021-12-12 01:38:01
DTS_END                         2021-12-12 01:38:01.8430
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/35838
TRN_ID                          244173
ATT_ID                          703
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: 9603.53 25070 1152424
customer order: creation 173.43 322 20811
customer order: refuse 15.64 323 1877
order to supplier: creation 46.69 1300 5603
order to supplier: removal 3.75 103339 450
invoice (draft): creation 47.13 3664 5655
invoice (draft): removal 57.55 865 6906
invoice accept: apply 41.67 17704 5000
invoice accept: cancel 52.02 802 6242
customer reserve: creation 3812.10 177 457452
customer reserve: removal 5047.10 59 605652
realization accept: apply 85.54 183 10265
realization accept: cancel 90.35 120 10842
payment from customer: creation 60.19 130 7223
payment from customer: removal 5.77 80 692
payment to supplier: creation 49.18 142 5902
payment to supplier: removal 3.50 75 420
service: total inventory turnovers 4.78 1134 574
service: total monetary turnovers 6.43 38 772
service: aggregate perf. data 0.68 27081 82
service: refresh index statistics 0.03 343864 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2021 01:39:07 to 12-12-2021 01:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7988
TEST_TIME 2 4360
TEST_TIME 3 4282
TEST_TIME 4 4814
TEST_TIME 5 4690
TEST_TIME 6 4976
TEST_TIME 7 4905
TEST_TIME 8 5535
TEST_TIME 9 5650
TEST_TIME 10 5870
TEST_TIME 11 6169
TEST_TIME 12 6507
TEST_TIME 13 7044
TEST_TIME 14 7249
TEST_TIME 15 7632
TEST_TIME 16 7881
TEST_TIME 17 7879
TEST_TIME 18 8015
TEST_TIME 19 8147
TEST_TIME 20 8033
TEST_TIME 21 8596
TEST_TIME 22 8709
TEST_TIME 23 8901
TEST_TIME 24 8889
TEST_TIME 25 9001
TEST_TIME 26 9682
TEST_TIME 27 10266
TEST_TIME 28 9948
TEST_TIME 29 9869
TEST_TIME 30 11143
TEST_TIME 31 11446
TEST_TIME 32 11424
TEST_TIME 33 11207
TEST_TIME 34 11016
TEST_TIME 35 11222
TEST_TIME 36 11035
TEST_TIME 37 10959
TEST_TIME 38 10844
TEST_TIME 39 10815
TEST_TIME 40 10803
TEST_TIME 41 10656
TEST_TIME 42 10616
TEST_TIME 43 10982
TEST_TIME 44 10784
TEST_TIME 45 10574
TEST_TIME 46 10464
TEST_TIME 47 10537
TEST_TIME 48 10427
TEST_TIME 49 10318
TEST_TIME 50 10288
TEST_TIME 51 10253
TEST_TIME 52 10252
TEST_TIME 53 10391
TEST_TIME 54 10377
TEST_TIME 55 10321
TEST_TIME 56 10230
TEST_TIME 57 10149
TEST_TIME 58 10039
TEST_TIME 59 9972
TEST_TIME 60 9930
TEST_TIME 61 9903
TEST_TIME 62 9868
TEST_TIME 63 9854
TEST_TIME 64 9899
TEST_TIME 65 9882
TEST_TIME 66 9868
TEST_TIME 67 9905
TEST_TIME 68 9977
TEST_TIME 69 9948
TEST_TIME 70 9916
TEST_TIME 71 10070
TEST_TIME 72 10021
TEST_TIME 73 10024
TEST_TIME 74 10051
TEST_TIME 75 10106
TEST_TIME 76 10102
TEST_TIME 77 10010
TEST_TIME 78 9958
TEST_TIME 79 9919
TEST_TIME 80 9914
TEST_TIME 81 9855
TEST_TIME 82 9805
TEST_TIME 83 9741
TEST_TIME 84 9670
TEST_TIME 85 9673
TEST_TIME 86 9655
TEST_TIME 87 9632
TEST_TIME 88 9611
TEST_TIME 89 9602
TEST_TIME 90 9660
TEST_TIME 91 9715
TEST_TIME 92 9761
TEST_TIME 93 9730
TEST_TIME 94 9782
TEST_TIME 95 9779
TEST_TIME 96 9736
TEST_TIME 97 9855
TEST_TIME 98 9859
TEST_TIME 99 9904
TEST_TIME 100 9904
TEST_TIME 101 9832
TEST_TIME 102 9779
TEST_TIME 103 9808
TEST_TIME 104 9824
TEST_TIME 105 9790
TEST_TIME 106 9762
TEST_TIME 107 9735
TEST_TIME 108 9721
TEST_TIME 109 9669
TEST_TIME 110 9682
TEST_TIME 111 9659
TEST_TIME 112 9692
TEST_TIME 113 9675
TEST_TIME 114 9697
TEST_TIME 115 9687
TEST_TIME 116 9654
TEST_TIME 117 9740
TEST_TIME 118 9686
TEST_TIME 119 9672
TEST_TIME 120 9608

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-12-2021 01:39:11 to 12-12-2021 01:39:14.

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 1577899 1577899 0 0.00 0 21299 6 0 0
doc_list_biud 1577899 1577899 0 0.00 0 60102 29 0 0
sp_add_invoice_to_stock 5082 5000 82 1.61 51 184789 17704 0 82
sp_cancel_adding_invoice 6242 6242 0 0.00 5 65195 803 0 0
sp_cancel_client_order 1877 1877 0 0.00 4 7948 323 0 0
sp_cancel_customer_reserve 605652 605652 0 0.00 1 47328 59 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 2 5216 81 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 4120 75 0 0
sp_cancel_supplier_invoice 6913 6906 7 0.10 1 60109 865 0 7
sp_cancel_supplier_order 450 450 0 0.00 5 994122 103340 0 0
sp_cancel_write_off 10842 10842 0 0.00 1 30695 120 0 0
sp_client_order 20811 20811 0 0.00 3 16167 322 0 0
sp_customer_reserve 457452 457452 0 0.00 0 91726 178 0 0
sp_fill_shopping_cart 32059 32059 0 0.00 1 16420 36 0 0
sp_fill_shopping_cart_clo_res 457447 457447 0 0.00 0 28843 15 0 0
sp_get_clo_for_invoice 4994 4994 0 0.00 0 26796 277 0 0
sp_kill_cost_storno 18196 18196 0 0.00 0 21295 64 0 0
sp_kill_qstorno_ret_qs2qd 1229502 1229502 0 0.00 0 60099 32 0 0
sp_kill_qty_storno 647228 647228 0 0.00 0 60100 70 0 0
sp_lock_selected_doc 21207 21207 0 0.00 0 7350 4 0 0
sp_make_cost_storno 28384 28384 0 0.00 0 16492 58 0 0
sp_multiply_rows_for_pdistr 28384 28384 0 0.00 0 9965 30 0 0
sp_multiply_rows_for_qdistr 20811 20811 0 0.00 0 14433 136 0 0
sp_pay_from_customer 7223 7223 0 0.00 4 15425 131 0 0
sp_pay_to_supplier 5902 5902 0 0.00 5 15202 143 0 0
sp_payment_common 13125 13125 0 0.00 1 13171 30 0 0
sp_qd_handle_on_cancel_clo 1877 1877 0 0.00 0 7943 290 0 0
sp_qd_handle_on_invoice_upd_sts 11236 11236 0 0.00 0 25954 348 0 0
sp_qd_handle_on_reserve_upd_sts 10265 10265 0 0.00 0 15951 56 0 0
sp_reserve_write_off 10265 10265 0 0.00 6 15967 183 0 0
sp_supplier_invoice 5655 5655 0 0.00 6 98596 3665 0 0
sp_supplier_order 5603 5603 0 0.00 4 29860 1300 0 0
srv_aggregate_perf_data 82 82 0 0.00 2672 273130 27082 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2075 140288 47809 0 0
srv_find_qd_qs_mism 1105452 1105452 0 0.00 0 16039 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 48 47315 1134 0 0
srv_make_money_saldo 772 772 0 0.00 1 3623 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 237835 393394 343864 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5002 5002 0 0.00 0 16744 167 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 1 6725 130 0 0
t$perf-norm:sp_cancel_client_order 1877 1877 0 0.00 0 3600 15 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 3251 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 880 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 4639 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 4467 25 0 0
t$perf-norm:sp_cancel_supplier_order 450 450 0 0.00 0 22024 1669 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 5480 17 0 0
t$perf-norm:sp_client_order 20811 20811 0 0.00 0 6445 20 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1614 6 0 0
t$perf-norm:sp_pay_from_customer 7223 7223 0 0.00 0 7777 15 0 0
t$perf-norm:sp_pay_to_supplier 5902 5902 0 0.00 0 13200 23 0 0
t$perf-norm:sp_reserve_write_off 10265 10265 0 0.00 0 8926 15 0 0
t$perf-norm:sp_supplier_invoice 5655 5655 0 0.00 0 3779 17 0 0
t$perf-norm:sp_supplier_order 5603 5603 0 0.00 0 5266 17 0 0
t$perf-norm:srv_aggregate_perf_data 82 82 0 0.00 0 575 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 4453 78 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 4771 32 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 4961 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 10152 9988 164 1.61 0 7950 21 0 164
v_all_customers 38142 38142 0 0.00 0 40 1 0 0
v_all_suppliers 31120 31120 0 0.00 0 39 1 0 0
v_all_wares 41622 41622 0 0.00 0 135 1 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 3545 20 0 0
v_cancel_client_order 3754 3754 0 0.00 0 4281 8 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 5165 10 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 2485 9 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4008 10 0 0
v_cancel_supplier_invoice 828 814 14 1.69 0 7816 43 0 14
v_cancel_supplier_order 900 900 0 0.00 0 1337 6 0 0
v_cancel_write_off 1952 1952 0 0.00 0 4056 17 0 0
v_max_id_clo_ord 5602 5602 0 0.00 0 176 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2152 8 0 0
v_max_id_ord_sup 5646 5646 0 0.00 0 3588 5 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 2498 5 0 0
v_max_non_paid_realizn 3859 3859 0 0.00 0 6661 5 0 0
v_min_id_clo_ord 5602 5602 0 0.00 0 4836 8 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 3324 19 0 0
v_min_id_ord_sup 5646 5646 0 0.00 0 5124 6 0 0
v_min_non_paid_invoice 5902 5902 0 0.00 0 13166 24 0 0
v_min_non_paid_realizn 7223 7223 0 0.00 0 5434 19 0 0
v_our_firm 10208 10208 0 0.00 0 12 1 0 0
v_reserve_write_off 20530 20530 0 0.00 0 6842 10 0 0
x_lock_depdocs_on_canc_invoice 12741 12741 0 0.00 0 14106 114 0 0
x_lock_depdocs_on_canc_sup_ord 450 450 0 0.00 0 9074 420 0 0
x_make_qstorno_1000_1200 5603 5603 0 0.00 2 29850 1215 0 0
x_make_qstorno_1200_2000 5655 5655 0 0.00 3 98434 3545 0 0
x_make_qstorno_2100_3300 457452 457452 0 0.00 0 91723 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2021 01:39:15 to 12-12-2021 01:39:19.

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
23:08:04 3 8192000000 8806216864 8819806208 11560656 0.140 3 3 4 0 11744576 76800 107120
23:09:05 21131 8192000000 9904627824 10220158976 990635120 10.788 100 91 182 2 1021278608 6139152 24504336
23:10:28 17912 8192000000 9966529648 10294042624 1046878400 11.331 100 91 182 0 1078698064 8612000 23207664
23:11:49 21478 8192000000 9993015856 10330513408 1070860752 11.560 100 88 176 0 1102791152 10214384 21716016
23:13:18 14328 8192000000 10033382560 10382536704 1118983360 12.017 100 83 166 1 1150663600 12459648 19220592
23:14:37 9320 8192000000 10074019088 10434240512 1163457408 12.436 100 81 162 0 1195231808 13213168 18561232
23:15:48 5103 8192000000 10114167824 10486288384 1205774768 12.830 100 78 156 1 1235971104 12323920 17872416
23:16:55 5380 8192000000 10152823600 10532700160 1241170832 13.157 100 70 140 0 1269311696 13102688 15038176
23:18:02 1407 8192000000 10200519952 10580910080 1280747744 13.520 100 92 185 1 1316081072 14232016 21101312
23:19:06 9374 8192000000 10187325072 10597187584 1298500912 13.682 100 61 123 0 1322037040 9434816 14101312
23:20:23 4532 8192000000 10189543232 10603155456 1306544704 13.755 100 25 50 0 1319183648 6613520 6025424
23:21:31 9293 8192000000 10195572480 10614083584 1318068224 13.859 100 25 48 3 1330319136 5389200 6861712
23:22:42 12046 8192000000 10215708496 10635132928 1325968752 13.931 100 12 24 0 1338210288 9439552 2801984
23:23:55 63019 8192000000 10222200416 10643533824 1334998496 14.012 100 23 46 3 1349825280 9525152 5301632
23:26:02 5685 8192000000 10232021216 10651656192 1334270736 14.006 100 33 66 1 1349021872 7531248 7219888
23:27:08 11634 8192000000 10215612256 10633920512 1302857888 13.721 98 23 47 0 1313771472 5951056 4962528
23:28:20 21974 8192000000 10145816928 10550538240 1165095520 12.451 90 28 56 2 1176859328 5689408 6074400
23:29:47 11290 8192000000 10176453856 10583203840 1244679376 13.189 97 26 49 0 1268221440 18809840 4732224
23:31:04 3772 8192000000 10151098032 10552004608 1257001536 13.303 100 31 62 0 1271446656 7441648 7003472
23:32:09 4934 8192000000 10148282944 10546597888 1243223664 13.176 99 26 52 0 1255042496 5186768 6632064
23:33:16 11058 8192000000 10161375088 10571509760 1267490192 13.399 99 24 48 1 1278757392 5356752 5910448
23:34:27 37430 8192000000 10172104176 10591350784 1288424992 13.590 100 13 26 2 1296096240 5133232 2538016
23:36:08 6524 8192000000 10202242720 10612932608 1306914944 13.758 100 35 71 0 1321279088 6396480 7967664
23:37:18 8396 8192000000 10213176512 10623275008 1316860112 13.848 100 22 44 1 1326793872 4423360 5510400
23:38:33 34209 8192000000 10253246896 10660302848 1336804144 14.029 100 44 88 1 1356192320 8913152 10475024
23:40:13 133163 8192000000 10273770880 10696921088 1363579584 14.269 100 36 73 1 1386547344 14372192 8595568
23:43:39 59780 8192000000 10292265888 10719432704 1380380944 14.420 100 30 60 0 1400323120 13277920 6664256
23:45:44 4611 8192000000 10329979632 10748887040 1402551424 14.618 100 58 117 1 1437706240 21074384 14080432
23:46:54 17903 8192000000 10323665376 10742784000 1376004048 14.381 98 55 108 0 1411797232 23408096 12385088
23:48:17 3592 8192000000 10328921904 10751176704 1399461936 14.590 100 55 109 0 1438440304 25439520 13538848
23:49:30 14902 8192000000 10314137648 10733535232 1343212352 14.086 96 52 104 2 1388252000 32301200 12738448
23:50:47 2851 8192000000 10330843536 10744778752 1382293808 14.437 100 64 128 0 1436637408 38799680 15543920
23:51:55 2534 8192000000 10316043808 10729635840 1353475552 14.179 98 68 136 1 1406749472 35999760 17274160
23:53:02 2074 8192000000 10351894336 10757332992 1391468944 14.519 100 77 153 0 1448656672 38882864 18304864
23:54:08 3589 8192000000 10342023040 10747109376 1388092240 14.489 100 91 179 3 1447974576 37478704 22403632
23:55:14 2512 8192000000 10315331568 10713706496 1358611152 14.225 99 78 156 1 1415663648 37694304 19358192
23:56:23 2598 8192000000 10350010032 10759839744 1389519520 14.502 100 77 153 0 1453798640 44737088 19542032
23:57:30 7818 8192000000 10357322336 10769481728 1380040992 14.417 99 66 132 1 1451327504 54086128 17200384
23:59:04 2228 8192000000 10398628272 10813435904 1419776288 14.771 100 74 148 0 1500048752 60250832 20021632
00:00:11 8348 8192000000 10388221008 10812530688 1425099008 14.818 100 48 94 0 1492647120 54999232 12548880
00:01:26 16656 8192000000 10368446176 10795122688 1398439296 14.581 99 47 93 0 1470094576 58409008 13246272
00:02:45 6442 8192000000 10371712832 10801356800 1404351472 14.634 100 49 98 0 1482639408 65290080 12997856
00:04:08 33483 8192000000 10361570480 10797178880 1397216128 14.570 100 31 62 0 1470562256 64261680 9084448
00:06:04 34917 8192000000 10347972016 10787102720 1394147456 14.543 100 21 42 1 1445250448 44426240 6676752
00:08:09 26564 8192000000 10353657424 10802982912 1388747488 14.495 100 21 42 0 1457143248 62292944 6102816
00:09:37 118353 8192000000 10314114816 10746081280 1346480464 14.116 97 16 32 0 1366013824 15673408 3859952
00:12:37 62114 8192000000 10290790176 10752266240 1360190976 14.239 98 9 18 0 1381156688 18837520 2128192
00:14:39 40320 8192000000 10309437600 10748706816 1389766336 14.504 100 34 69 0 1408100128 10227376 8106416
00:16:24 46334 8192000000 10316640240 10771472384 1402453216 14.617 100 29 58 1 1414405776 6098656 5853904
00:19:00 40796 8192000000 10319458240 10764423168 1389300224 14.500 99 30 60 3 1402801952 6562608 6939120
00:20:43 69363 8192000000 10331123248 10804588544 1407516608 14.662 100 12 24 0 1436593968 25857264 3220096
00:22:54 81750 8192000000 10316635280 10762375168 1386425536 14.474 99 37 74 1 1407602592 12669744 8507312
00:25:27 49969 8192000000 10306074544 10766049280 1379786944 14.415 98 30 59 1 1396398736 9341984 7269808
00:27:20 91416 8192000000 10295704496 10746347520 1317613216 13.855 93 36 73 1 1334745856 8938896 8193744
00:29:53 44428 8192000000 10285922352 10734600192 1330269456 13.970 96 25 48 2 1348989120 12567280 6152384
00:31:47 68411 8192000000 10251405264 10709319680 1308294112 13.771 96 22 44 0 1323741232 10553648 4893472
00:33:56 64837 8192000000 10250324288 10693328896 1293729456 13.638 95 8 16 0 1302112784 6698064 1685264
00:36:06 21464 8192000000 10269974848 10734018560 1377359408 14.393 100 5 10 0 1387297776 8721488 1216880
00:37:30 72177 8192000000 10262557136 10718998528 1331111776 13.977 97 15 30 0 1341928432 7334768 3481888
00:39:44 8496 8192000000 10270962304 10727133184 1373338784 14.357 100 24 48 2 1386480064 7662848 5478432
00:40:52 70325 8192000000 10272292288 10744528896 1362160128 14.257 99 8 16 0 1376356768 12359984 1836656
00:43:28 35959 8192000000 10266477776 10725502976 1340013408 14.058 97 19 38 0 1353919808 9569216 4337184
00:45:07 106193 8192000000 10275870896 10739167232 1351282672 14.159 98 21 42 0 1368439904 12429632 4727600
00:48:25 54293 8192000000 10307454000 10760962048 1388679136 14.494 100 33 66 0 1412340256 16175040 7486080
00:50:19 25716 8192000000 10301297984 10744893440 1372259632 14.347 99 34 68 0 1390853104 11491072 7102400
00:51:49 12497 8192000000 10306793760 10745896960 1367274400 14.303 99 60 118 2 1396727328 16527024 12925904
00:53:03 97849 8192000000 10293659248 10750234624 1340431904 14.061 97 20 39 0 1358138752 13569936 4136912
00:55:47 99203 8192000000 10264980720 10719211520 1343012944 14.085 97 28 56 1 1357438688 8210208 6215536
00:58:30 43663 8192000000 10256510048 10711470080 1341818480 14.074 98 16 32 0 1354940928 9538224 3584224
01:00:24 55430 8192000000 10261723904 10721013760 1355334640 14.195 99 12 24 0 1366081632 8001712 2745280
01:02:19 108310 8192000000 10259595472 10731364352 1354235744 14.186 99 17 34 0 1370020400 11256944 4527712
01:05:12 47446 8192000000 10273785440 10720759808 1343758896 14.091 98 20 40 1 1356396048 7818000 4819152
01:07:01 45067 8192000000 10288518608 10733850624 1348450272 14.134 98 38 76 2 1369843488 12479264 8913952
01:08:47 79104 8192000000 10283012048 10748538880 1350596304 14.153 98 20 40 3 1375660416 20711856 4352256
01:11:25 23598 8192000000 10276914064 10722324480 1346658128 14.117 98 25 50 1 1360626624 8634064 5334432
01:12:52 50129 8192000000 10264493152 10719522816 1337364112 14.034 98 14 28 0 1348783408 8308384 3110912
01:14:42 36346 8192000000 10284533440 10737356800 1348403296 14.133 98 36 72 1 1370188656 13369984 8415376
01:16:23 111751 8192000000 10272722032 10735800320 1305322096 13.744 95 14 28 0 1324631184 16180064 3129024
01:19:16 140288 8192000000 10261185600 10712981504 1315487776 13.836 96 34 68 0 1345128128 21290336 8350016
01:22:37 29879 8192000000 10260005088 10737074176 1343048048 14.085 99 11 20 3 1366516336 21385952 2082336
01:24:15 85760 8192000000 10253956576 10714411008 1339032128 14.049 98 19 38 0 1350395600 6821040 4542432
01:27:10 66338 8192000000 10257617968 10721488896 1349052880 14.139 98 15 30 1 1365483136 12759600 3670656
01:29:16 102744 8192000000 10270305408 10741780480 1350340240 14.151 98 12 24 0 1367691200 14620192 2730768
01:32:01 49485 8192000000 10261190736 10727329792 1352777072 14.172 99 16 32 1 1366542320 10048896 3716352
01:33:58 40007 8192000000 10291599008 10734612480 1345354768 14.106 98 40 79 0 1367583600 13380976 8847856
01:35:38 60514 8192000000 10276934848 10729000960 1341888960 14.074 98 12 23 0 1356895008 12642432 2363616
01:37:44 33019 8192000000 9122509456 9371082752 282471728 3.333 21 6 11 0 289765984 5865168 1429088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-12-2021 01:39:21 to 12-12-2021 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-12-2021 01:39:37 to 12-12-2021 01:39:39.

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          246413
MON$OLDEST_ACTIVE               246860
MON$OLDEST_SNAPSHOT             246860
MON$NEXT_TRANSACTION            246860
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                       2059040
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Sun Dec 12 01:38:28 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
--- end of diff output ---

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

12.12.21 01:39:40. 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
2021-12-12 01:39:42 No severe PSQL-related problems occured
12.12.21 01:39:43. End of report.