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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-12-20 16:38:29
DTS_END                         2022-12-20 16:38:29.2780
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/55498
TRN_ID                          241172
ATT_ID                          634
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: 9554.66 22592 1146559
customer order: creation 177.14 383 21257
customer order: refuse 15.62 350 1874
order to supplier: creation 47.32 1233 5678
order to supplier: removal 3.53 112014 424
invoice (draft): creation 47.13 2895 5656
invoice (draft): removal 55.99 798 6719
invoice accept: apply 41.42 16594 4970
invoice accept: cancel 50.04 784 6005
customer reserve: creation 3908.43 160 469011
customer reserve: removal 4905.02 64 588603
realization accept: apply 85.72 171 10286
realization accept: cancel 83.83 108 10060
payment from customer: creation 61.04 97 7325
payment from customer: removal 5.60 64 672
payment to supplier: creation 51.35 117 6162
payment to supplier: removal 3.75 85 450
service: total inventory turnovers 4.76 1174 571
service: total monetary turnovers 6.14 24 737
service: aggregate perf. data 0.79 23203 95
service: refresh index statistics 0.03 291531 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2022 16:39:41 to 20-12-2022 16:39:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5755
TEST_TIME 2 5851
TEST_TIME 3 5298
TEST_TIME 4 6151
TEST_TIME 5 6217
TEST_TIME 6 5780
TEST_TIME 7 5453
TEST_TIME 8 5254
TEST_TIME 9 5416
TEST_TIME 10 5544
TEST_TIME 11 5737
TEST_TIME 12 6306
TEST_TIME 13 6197
TEST_TIME 14 6522
TEST_TIME 15 6436
TEST_TIME 16 6817
TEST_TIME 17 7066
TEST_TIME 18 6957
TEST_TIME 19 7381
TEST_TIME 20 7558
TEST_TIME 21 7696
TEST_TIME 22 7902
TEST_TIME 23 7989
TEST_TIME 24 8725
TEST_TIME 25 8812
TEST_TIME 26 9851
TEST_TIME 27 9813
TEST_TIME 28 11189
TEST_TIME 29 11087
TEST_TIME 30 11020
TEST_TIME 31 10967
TEST_TIME 32 10662
TEST_TIME 33 10775
TEST_TIME 34 10764
TEST_TIME 35 10631
TEST_TIME 36 10454
TEST_TIME 37 10332
TEST_TIME 38 10270
TEST_TIME 39 10231
TEST_TIME 40 10335
TEST_TIME 41 10284
TEST_TIME 42 10168
TEST_TIME 43 10058
TEST_TIME 44 9974
TEST_TIME 45 9958
TEST_TIME 46 9874
TEST_TIME 47 9829
TEST_TIME 48 9786
TEST_TIME 49 9816
TEST_TIME 50 9768
TEST_TIME 51 9746
TEST_TIME 52 9842
TEST_TIME 53 9830
TEST_TIME 54 9818
TEST_TIME 55 9845
TEST_TIME 56 9910
TEST_TIME 57 9855
TEST_TIME 58 9933
TEST_TIME 59 9929
TEST_TIME 60 9913
TEST_TIME 61 9986
TEST_TIME 62 9859
TEST_TIME 63 9793
TEST_TIME 64 9692
TEST_TIME 65 9649
TEST_TIME 66 9578
TEST_TIME 67 9637
TEST_TIME 68 9626
TEST_TIME 69 9574
TEST_TIME 70 9645
TEST_TIME 71 9613
TEST_TIME 72 9551
TEST_TIME 73 9705
TEST_TIME 74 9649
TEST_TIME 75 9727
TEST_TIME 76 9701
TEST_TIME 77 9778
TEST_TIME 78 9914
TEST_TIME 79 9894
TEST_TIME 80 9854
TEST_TIME 81 9756
TEST_TIME 82 9720
TEST_TIME 83 9715
TEST_TIME 84 9669
TEST_TIME 85 9655
TEST_TIME 86 9651
TEST_TIME 87 9632
TEST_TIME 88 9601
TEST_TIME 89 9638
TEST_TIME 90 9620
TEST_TIME 91 9657
TEST_TIME 92 9623
TEST_TIME 93 9668
TEST_TIME 94 9632
TEST_TIME 95 9691
TEST_TIME 96 9670
TEST_TIME 97 9700
TEST_TIME 98 9664
TEST_TIME 99 9700
TEST_TIME 100 9697
TEST_TIME 101 9759
TEST_TIME 102 9796
TEST_TIME 103 9784
TEST_TIME 104 9714
TEST_TIME 105 9653
TEST_TIME 106 9685
TEST_TIME 107 9632
TEST_TIME 108 9659
TEST_TIME 109 9619
TEST_TIME 110 9581
TEST_TIME 111 9575
TEST_TIME 112 9532
TEST_TIME 113 9512
TEST_TIME 114 9543
TEST_TIME 115 9552
TEST_TIME 116 9533
TEST_TIME 117 9573
TEST_TIME 118 9559
TEST_TIME 119 9570
TEST_TIME 120 9555

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-12-2022 16:39:45 to 20-12-2022 16:39:48.

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 1582800 1582800 0 0.00 0 34257 6 0 0
doc_list_biud 1582800 1582800 0 0.00 0 23843 30 0 0
sp_add_invoice_to_stock 5106 4970 136 2.66 15 201937 16595 0 136
sp_cancel_adding_invoice 6005 6005 0 0.00 5 74678 785 0 0
sp_cancel_client_order 1874 1874 0 0.00 3 18194 351 0 0
sp_cancel_customer_reserve 588603 588603 0 0.00 0 23845 65 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 4634 64 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 2 6886 86 0 0
sp_cancel_supplier_invoice 6730 6719 11 0.16 1 23579 798 0 11
sp_cancel_supplier_order 424 424 0 0.00 6 1165039 112014 0 0
sp_cancel_write_off 10060 10060 0 0.00 1 15400 109 0 0
sp_client_order 21257 21257 0 0.00 3 25271 383 0 0
sp_customer_reserve 469011 469011 0 0.00 0 126789 161 0 0
sp_fill_shopping_cart 32585 32585 0 0.00 1 9003 34 0 0
sp_fill_shopping_cart_clo_res 469007 469007 0 0.00 0 12949 14 0 0
sp_get_clo_for_invoice 4966 4966 0 0.00 0 19680 321 0 0
sp_kill_cost_storno 17187 17187 0 0.00 0 6879 53 0 0
sp_kill_qstorno_ret_qs2qd 1194409 1194409 0 0.00 0 23841 35 0 0
sp_kill_qty_storno 628937 628937 0 0.00 0 23843 74 0 0
sp_lock_selected_doc 21274 21274 0 0.00 0 6789 6 0 0
sp_make_cost_storno 28739 28739 0 0.00 0 34254 49 0 0
sp_multiply_rows_for_pdistr 28739 28739 0 0.00 0 6405 25 0 0
sp_multiply_rows_for_qdistr 21257 21257 0 0.00 0 18997 172 0 0
sp_pay_from_customer 7325 7325 0 0.00 4 11918 98 0 0
sp_pay_to_supplier 6162 6162 0 0.00 4 12660 117 0 0
sp_payment_common 13487 13487 0 0.00 1 6495 24 0 0
sp_qd_handle_on_cancel_clo 1874 1874 0 0.00 0 18140 318 0 0
sp_qd_handle_on_invoice_upd_sts 10971 10971 0 0.00 0 23393 311 0 0
sp_qd_handle_on_reserve_upd_sts 10286 10286 0 0.00 0 21075 55 0 0
sp_reserve_write_off 10286 10286 0 0.00 6 24195 172 0 0
sp_supplier_invoice 5656 5656 0 0.00 6 72120 2895 0 0
sp_supplier_order 5678 5678 0 0.00 4 27065 1234 0 0
srv_aggregate_perf_data 95 95 0 0.00 2559 714381 23203 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2234 205769 50311 0 0
srv_find_qd_qs_mism 1098567 1098567 0 0.00 0 14483 5 0 0
srv_make_invnt_saldo 571 571 0 0.00 35 151623 1174 0 0
srv_make_money_saldo 737 737 0 0.00 0 663 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 133542 464952 291532 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4970 4970 0 0.00 0 12058 150 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 4288 109 0 0
t$perf-norm:sp_cancel_client_order 1874 1874 0 0.00 0 3043 10 0 0
t$perf-norm:sp_cancel_customer_reserve 742 742 0 0.00 0 10332 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 3500 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 1340 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 438 438 0 0.00 0 735 9 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 45978 1789 0 0
t$perf-norm:sp_cancel_write_off 1005 1005 0 0.00 0 1603 5 0 0
t$perf-norm:sp_client_order 21257 21257 0 0.00 0 13294 16 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 5593 10 0 0
t$perf-norm:sp_pay_from_customer 7325 7325 0 0.00 0 9277 12 0 0
t$perf-norm:sp_pay_to_supplier 6162 6162 0 0.00 0 5689 11 0 0
t$perf-norm:sp_reserve_write_off 10286 10286 0 0.00 0 11825 16 0 0
t$perf-norm:sp_supplier_invoice 5656 5656 0 0.00 0 5161 17 0 0
t$perf-norm:sp_supplier_order 5678 5678 0 0.00 0 9083 15 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 327 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 568 15 0 0
t$perf-norm:srv_make_invnt_saldo 571 571 0 0.00 0 5074 31 0 0
t$perf-norm:srv_make_money_saldo 737 737 0 0.00 0 787 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10204 9932 272 2.66 0 10008 18 0 272
v_all_customers 38678 38678 0 0.00 0 94 0 0 0
v_all_suppliers 32228 32228 0 0.00 0 29 1 0 0
v_all_wares 42514 42514 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 2322 14 0 0
v_cancel_client_order 3748 3748 0 0.00 0 2739 6 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 2050 7 0 0
v_cancel_customer_reserve 1484 1484 0 0.00 0 2752 7 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1209 7 0 0
v_cancel_supplier_invoice 898 876 22 2.44 0 2081 14 0 22
v_cancel_supplier_order 848 848 0 0.00 0 4476 9 0 0
v_cancel_write_off 2010 2010 0 0.00 0 3750 13 0 0
v_max_id_clo_ord 5678 5678 0 0.00 0 2086 2 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 2427 16 0 0
v_max_id_ord_sup 5650 5650 0 0.00 0 3334 3 0 0
v_max_non_paid_invoice 1376 1376 0 0.00 0 146 2 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 2092 3 0 0
v_min_id_clo_ord 5678 5678 0 0.00 0 6756 10 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 5127 7 0 0
v_min_id_ord_sup 5650 5650 0 0.00 0 5818 6 0 0
v_min_non_paid_invoice 6162 6162 0 0.00 0 6481 20 0 0
v_min_non_paid_realizn 7325 7325 0 0.00 0 5685 16 0 0
v_our_firm 10830 10830 0 0.00 0 18 1 0 0
v_reserve_write_off 20572 20572 0 0.00 0 4881 6 0 0
x_lock_depdocs_on_canc_invoice 12286 12286 0 0.00 0 16234 113 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 9391 323 0 0
x_make_qstorno_1000_1200 5678 5678 0 0.00 1 27042 1161 0 0
x_make_qstorno_1200_2000 5656 5656 0 0.00 2 72109 2769 0 0
x_make_qstorno_2100_3300 469011 469011 0 0.00 0 126788 145 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2022 16:39:49 to 20-12-2022 16:39:53.

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:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:33 13920 8192000000 9900904448 10212491264 992910112 10.810 100 89 178 0 1021147680 5438160 22799408
14:10:55 26305 8192000000 9950418000 10271346688 1031299120 11.181 100 94 188 0 1063554512 7795792 24459600
14:12:25 12387 8192000000 9982799536 10313875456 1064681536 11.501 100 84 168 0 1094547072 8832240 21033296
14:13:46 11895 8192000000 10048072160 10394877952 1125562976 12.080 100 76 152 0 1153648848 10269744 17816128
14:15:00 6750 8192000000 10089940752 10449768448 1176533504 12.558 100 84 168 0 1208083568 12366144 19183920
14:16:09 3362 8192000000 10120677840 10488049664 1215608288 12.921 100 83 167 0 1247438096 13800416 18029392
14:17:16 3479 8192000000 10154281552 10530795520 1243276640 13.176 100 79 158 0 1274639920 13899216 17464064
14:18:24 2290 8192000000 10179131280 10567024640 1274788448 13.465 100 71 140 0 1301623600 11035040 15800112
14:19:28 1788 8192000000 10212065824 10596376576 1300263312 13.698 100 90 174 1 1331559248 12694864 18601072
14:20:31 5613 8192000000 10220072640 10613379072 1306280256 13.752 100 72 144 0 1334096304 11876624 15939424
14:21:41 3217 8192000000 10212541792 10617417728 1313496736 13.818 100 59 117 0 1337676640 10555504 13624400
14:22:47 7992 8192000000 10196885136 10616442880 1317214528 13.851 100 28 56 3 1330117168 6324064 6578576
14:23:56 47227 8192000000 10207711808 10632404992 1322051216 13.895 100 27 54 0 1334323760 5492224 6780320
14:25:46 5522 8192000000 10222733568 10644262912 1329456576 13.962 100 29 58 1 1344042992 8520400 6066016
14:26:53 5069 8192000000 10226745008 10646675456 1332803392 13.992 100 25 50 0 1345734144 7452320 5478432
14:27:59 9227 8192000000 10215191136 10636603392 1327354560 13.943 100 15 30 0 1336559520 5538912 3666048
14:29:08 26572 8192000000 10206511968 10625236992 1286290768 13.570 97 27 54 0 1297553312 5110320 6152224
14:30:36 5662 8192000000 10188828768 10597851136 1287396672 13.580 99 26 50 1 1299608016 6017280 6194064
14:31:43 8482 8192000000 10142934944 10544562176 1203494144 12.809 95 20 40 0 1213903824 5771840 4637840
14:32:53 3106 8192000000 10152108800 10542739456 1244399520 13.187 99 58 117 2 1264980528 5405776 15175232
14:33:58 24317 8192000000 10151271328 10550435840 1220959040 12.971 97 35 68 0 1234994992 5817728 8218224
14:35:23 8047 8192000000 10178923264 10585231360 1267400768 13.398 99 23 45 0 1282169504 9773312 4995424
14:36:33 5986 8192000000 10187959952 10600751104 1295086240 13.651 100 29 58 1 1308123840 6201584 6836016
14:37:40 13377 8192000000 10200727856 10608304128 1303219184 13.725 100 15 31 0 1312445440 5502976 3723280
14:38:59 15977 8192000000 10225802448 10639593472 1326264480 13.933 100 36 69 0 1339422400 5695184 7462736
14:40:19 6647 8192000000 10251522048 10665881600 1344777824 14.100 100 51 102 2 1365323136 8795168 11750144
14:41:34 12084 8192000000 10279816288 10697469952 1359883200 14.236 100 54 108 1 1392178064 20048288 12246576
14:42:53 12911 8192000000 10288481344 10711535616 1376744000 14.387 100 41 82 2 1403917488 16693472 10480016
14:44:11 12627 8192000000 10300827200 10723905536 1389583840 14.502 100 44 86 4 1417654160 17866496 10203824
14:45:33 23308 8192000000 10316938464 10744463360 1400475280 14.599 100 45 90 0 1434197712 23004208 10718224
14:46:59 12576 8192000000 10337496768 10763816960 1414077296 14.720 100 52 103 0 1453076016 25947536 13051184
14:48:20 5444 8192000000 10362771936 10787147776 1423385440 14.803 100 72 142 1 1474714624 34300240 17028944
14:49:32 15171 8192000000 10358763536 10778910720 1405770960 14.646 99 64 125 1 1456215120 35042656 15401504
14:50:55 3400 8192000000 10357029648 10787082240 1421972208 14.790 100 46 90 1 1469374880 35435088 11967584
14:52:06 4575 8192000000 10371899168 10798526464 1424296032 14.811 100 57 112 0 1485203120 46683264 14223824
14:53:17 2529 8192000000 10392354992 10813411328 1432875888 14.887 100 69 136 1 1499615664 49653712 17086064
14:54:32 4567 8192000000 10412075632 10831605760 1444319296 14.988 100 75 150 0 1519055776 55567552 19168928
14:55:42 4070 8192000000 10418071168 10842361856 1453094960 15.065 100 65 130 1 1526473968 56111408 17267600
14:56:55 2233 8192000000 10407635584 10818138112 1426445936 14.830 100 86 172 1 1508187184 59686944 22054304
14:58:03 11926 8192000000 10392746608 10813485056 1399116080 14.587 98 62 120 1 1478999568 63719664 16163824
14:59:18 8905 8192000000 10401631920 10823503872 1423820048 14.807 100 48 96 1 1504702640 67893584 12989008
15:00:33 15968 8192000000 10359736048 10787958784 1372878288 14.353 97 44 88 0 1446119920 61008992 12232640
15:01:58 14843 8192000000 10364513600 10795900928 1371069568 14.337 99 35 70 0 1461040992 79422992 10548432
15:03:26 31099 8192000000 10335017488 10764521472 1365856736 14.290 100 27 54 0 1440712560 66587104 8268720
15:05:16 34380 8192000000 10300015952 10735656960 1333313216 13.997 99 15 30 0 1390766512 52690608 4762688
15:06:52 54954 8192000000 10267777696 10723696640 1343708032 14.091 99 17 34 0 1364954560 17713664 3532864
15:08:48 112476 8192000000 10269228640 10705752064 1338388464 14.043 98 17 34 0 1353170912 10636976 4145472
15:11:41 80208 8192000000 10280900960 10708631552 1301307024 13.707 95 25 50 0 1320194608 13145072 5742512
15:14:02 168924 8192000000 10279177600 10712272896 1286599696 13.573 93 21 42 0 1302780448 11133040 5047712
15:18:05 205769 8192000000 10277709984 10730958848 1340493712 14.062 98 15 30 0 1354768768 11002896 3272160
15:22:31 77045 8192000000 10280382144 10746593280 1368163072 14.311 99 12 24 0 1384252128 13084960 3004096
15:25:04 37871 8192000000 10294739744 10744184832 1386194496 14.472 100 40 80 0 1404625024 9613648 8816880
15:26:48 72062 8192000000 10282872848 10743783424 1358879184 14.227 98 13 26 0 1372152352 10760224 2512944
15:29:18 45410 8192000000 10299938768 10754977792 1397901840 14.576 100 16 32 0 1413188416 11746832 3539744
15:31:05 42320 8192000000 10301012128 10747768832 1370481552 14.331 98 23 44 0 1382940016 6862720 5595744
15:32:51 48120 8192000000 10309958848 10766946304 1381567472 14.431 98 29 59 0 1399190576 11072688 6550416
15:34:40 72362 8192000000 10337260768 10781577216 1411383888 14.696 100 25 50 1 1427428912 10158656 5886368
15:36:57 79044 8192000000 10309012496 10763026432 1367561136 14.305 97 13 24 0 1380569552 9960096 3048320
15:39:16 155793 8192000000 10292297616 10744659968 1324048880 13.913 94 23 43 0 1339165840 10805968 4310992
15:42:55 40076 8192000000 10285303392 10740973568 1374954688 14.371 98 33 66 0 1394499952 12247136 7298128
15:44:44 49995 8192000000 10282124544 10757099520 1376693888 14.387 99 12 24 0 1393030096 13755568 2580640
15:46:34 54510 8192000000 10282777216 10733514752 1325253248 13.924 95 14 28 0 1340706992 11838064 3615680
15:48:30 63105 8192000000 10257197136 10701410304 1316334336 13.844 95 23 46 0 1336329920 14951632 5043952
15:50:42 43129 8192000000 10309243696 10744381440 1365020096 14.282 99 44 88 2 1394083328 18781472 10281760
15:52:32 27135 8192000000 10262875456 10694389760 1309790272 13.784 96 54 107 0 1336633520 14406912 12436336
15:54:01 47615 8192000000 10275528432 10709110784 1340759968 14.064 99 34 68 0 1362301792 14158592 7383232
15:55:52 106992 8192000000 10263101824 10725404672 1315153456 13.833 97 8 16 0 1344500544 27337776 2009312
15:58:41 82492 8192000000 10248501808 10695491584 1321567456 13.891 97 20 40 0 1333282592 6805408 4909728
16:01:15 65048 8192000000 10233802528 10671255552 1285655968 13.565 95 28 55 0 1309790832 17653120 6481744
16:03:22 41621 8192000000 10253993552 10706386944 1343759920 14.091 99 19 35 1 1360055232 12215104 4080208
16:05:14 25199 8192000000 10261561824 10713591808 1359117824 14.229 100 22 45 0 1374698384 9525824 6054736
16:06:39 71585 8192000000 10260278080 10727096320 1350911312 14.156 99 12 24 0 1364276576 10346016 3019248
16:08:53 99938 8192000000 10274903920 10728112128 1345046960 14.103 97 14 25 0 1357336144 9689712 2599472
16:11:33 43769 8192000000 10283684848 10736893952 1368383776 14.313 99 14 28 0 1379535632 8173632 2978224
16:13:24 70222 8192000000 10286056608 10734481408 1347974080 14.129 97 16 33 1 1367234592 15627312 3633200
16:15:42 65476 8192000000 10305688800 10760978432 1392807584 14.531 100 17 34 0 1409584080 13194368 3582128
16:17:48 23855 8192000000 10301587664 10752368640 1381820048 14.433 99 14 26 0 1392182416 7274224 3088144
16:19:50 66892 8192000000 10292683264 10759110656 1339990640 14.057 96 5 10 1 1355367152 14472128 904384
16:21:57 91927 8192000000 10306759456 10753875968 1378234400 14.401 99 25 50 0 1390382176 6041904 6105872
16:24:47 66317 8192000000 10301728176 10764754944 1390717344 14.512 99 22 44 0 1405953968 10103552 5133072
16:26:53 45995 8192000000 10249055792 10713071616 1325285728 13.925 96 15 30 0 1337479920 8791312 3402880
16:30:15 56589 8192000000 10264175248 10720796672 1366688544 14.297 100 12 25 0 1379593072 10296608 2607920
16:32:12 78418 8192000000 10266306544 10743857152 1367524080 14.305 100 8 14 0 1380611632 11616848 1470704
16:34:35 54336 8192000000 10271315904 10728218624 1359401696 14.232 99 15 31 0 1371618736 8762864 3454176
16:36:30 79113 8192000000 10274128480 10726244352 1325186496 13.924 97 20 40 1 1341073568 11183008 4704064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2022 16:39:55 to 20-12-2022 16:40:10.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2022 16:40:11 to 20-12-2022 16:40:13.

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          239735
MON$OLDEST_ACTIVE               241343
MON$OLDEST_SNAPSHOT             241343
MON$NEXT_TRANSACTION            241343
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                       2017600
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.33648 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33648 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33648 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33648 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 ---
:916: 
:917: mscs-test-161.budzdorov.ru    Tue Dec 20 16:38:42 2022
:918:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:919: 
--- end of diff output ---

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

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