Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.2023 22: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 15243508 kB
MemAvailable 30368460 kB
Buffers 0 kB
Cached 16452332 kB
SwapCached 4544 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 2023-01-05 22:38:38
DTS_END                         2023-01-05 22:38:38.4750
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51310
TRN_ID                          244116
ATT_ID                          629
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: 9441.78 19241 1133014
customer order: creation 177.69 336 21323
customer order: refuse 15.86 358 1903
order to supplier: creation 47.78 1264 5733
order to supplier: removal 3.34 108092 401
invoice (draft): creation 46.25 3139 5550
invoice (draft): removal 53.29 818 6395
invoice accept: apply 43.13 17966 5175
invoice accept: cancel 48.17 688 5780
customer reserve: creation 3975.40 179 477048
customer reserve: removal 4726.74 60 567209
realization accept: apply 86.37 190 10364
realization accept: cancel 84.48 115 10137
payment from customer: creation 60.59 112 7271
payment from customer: removal 5.97 78 716
payment to supplier: creation 51.20 125 6144
payment to supplier: removal 3.55 52 426
service: total inventory turnovers 4.91 1291 589
service: total monetary turnovers 6.17 34 740
service: aggregate perf. data 0.88 20112 106
service: refresh index statistics 0.03 229802 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-01-2023 22:39:45 to 05-01-2023 22:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2390
TEST_TIME 2 2483
TEST_TIME 3 3160
TEST_TIME 4 4583
TEST_TIME 5 5498
TEST_TIME 6 5446
TEST_TIME 7 5447
TEST_TIME 8 5779
TEST_TIME 9 5948
TEST_TIME 10 6007
TEST_TIME 11 6572
TEST_TIME 12 6466
TEST_TIME 13 6301
TEST_TIME 14 6301
TEST_TIME 15 6439
TEST_TIME 16 6530
TEST_TIME 17 6838
TEST_TIME 18 6945
TEST_TIME 19 7033
TEST_TIME 20 7381
TEST_TIME 21 7608
TEST_TIME 22 8115
TEST_TIME 23 8152
TEST_TIME 24 8511
TEST_TIME 25 8778
TEST_TIME 26 8845
TEST_TIME 27 8829
TEST_TIME 28 10174
TEST_TIME 29 10891
TEST_TIME 30 10939
TEST_TIME 31 10958
TEST_TIME 32 10759
TEST_TIME 33 10711
TEST_TIME 34 10539
TEST_TIME 35 10391
TEST_TIME 36 10487
TEST_TIME 37 10416
TEST_TIME 38 10348
TEST_TIME 39 10193
TEST_TIME 40 10163
TEST_TIME 41 10082
TEST_TIME 42 10129
TEST_TIME 43 10183
TEST_TIME 44 10199
TEST_TIME 45 10080
TEST_TIME 46 9989
TEST_TIME 47 9935
TEST_TIME 48 9888
TEST_TIME 49 9754
TEST_TIME 50 9867
TEST_TIME 51 9745
TEST_TIME 52 9773
TEST_TIME 53 9766
TEST_TIME 54 9753
TEST_TIME 55 9810
TEST_TIME 56 9823
TEST_TIME 57 9807
TEST_TIME 58 9851
TEST_TIME 59 9916
TEST_TIME 60 9908
TEST_TIME 61 9890
TEST_TIME 62 9844
TEST_TIME 63 9937
TEST_TIME 64 9967
TEST_TIME 65 9981
TEST_TIME 66 9854
TEST_TIME 67 9834
TEST_TIME 68 9749
TEST_TIME 69 9777
TEST_TIME 70 9723
TEST_TIME 71 9648
TEST_TIME 72 9652
TEST_TIME 73 9574
TEST_TIME 74 9542
TEST_TIME 75 9563
TEST_TIME 76 9571
TEST_TIME 77 9570
TEST_TIME 78 9577
TEST_TIME 79 9585
TEST_TIME 80 9563
TEST_TIME 81 9608
TEST_TIME 82 9652
TEST_TIME 83 9670
TEST_TIME 84 9702
TEST_TIME 85 9709
TEST_TIME 86 9794
TEST_TIME 87 9815
TEST_TIME 88 9747
TEST_TIME 89 9675
TEST_TIME 90 9624
TEST_TIME 91 9583
TEST_TIME 92 9544
TEST_TIME 93 9496
TEST_TIME 94 9507
TEST_TIME 95 9523
TEST_TIME 96 9541
TEST_TIME 97 9530
TEST_TIME 98 9503
TEST_TIME 99 9543
TEST_TIME 100 9556
TEST_TIME 101 9597
TEST_TIME 102 9646
TEST_TIME 103 9628
TEST_TIME 104 9655
TEST_TIME 105 9694
TEST_TIME 106 9667
TEST_TIME 107 9735
TEST_TIME 108 9724
TEST_TIME 109 9673
TEST_TIME 110 9606
TEST_TIME 111 9588
TEST_TIME 112 9558
TEST_TIME 113 9517
TEST_TIME 114 9461
TEST_TIME 115 9448
TEST_TIME 116 9457
TEST_TIME 117 9478
TEST_TIME 118 9455
TEST_TIME 119 9471
TEST_TIME 120 9448

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2023 22:39:49 to 05-01-2023 22:39:52.

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 1574499 1574499 0 0.00 0 19056 6 0 0
doc_list_biud 1574495 1574495 0 0.00 0 34911 28 0 0
sp_add_invoice_to_stock 5315 5175 140 2.63 32 143334 17967 0 140
sp_cancel_adding_invoice 5780 5780 0 0.00 3 57083 688 0 0
sp_cancel_client_order 1903 1903 0 0.00 4 13449 359 0 0
sp_cancel_customer_reserve 567209 567209 0 0.00 0 21763 61 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 2 5257 79 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 1184 53 0 0
sp_cancel_supplier_invoice 6404 6395 9 0.14 1 14010 818 0 9
sp_cancel_supplier_order 401 401 0 0.00 7 1145412 108093 0 0
sp_cancel_write_off 10137 10137 0 0.00 1 24241 115 0 0
sp_client_order 21323 21323 0 0.00 3 14262 337 0 0
sp_customer_reserve 477048 477048 0 0.00 0 114616 180 0 0
sp_fill_shopping_cart 32601 32601 0 0.00 1 30902 45 0 0
sp_fill_shopping_cart_clo_res 477042 477042 0 0.00 0 15507 14 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 27936 307 0 0
sp_kill_cost_storno 17059 17059 0 0.00 0 6442 49 0 0
sp_kill_qstorno_ret_qs2qd 1151351 1151351 0 0.00 0 26389 33 0 0
sp_kill_qty_storno 607353 607353 0 0.00 0 34910 71 0 0
sp_lock_selected_doc 21589 21589 0 0.00 0 6015 5 0 0
sp_make_cost_storno 28944 28944 0 0.00 0 19042 52 0 0
sp_multiply_rows_for_pdistr 28941 28941 0 0.00 0 4831 22 0 0
sp_multiply_rows_for_qdistr 21323 21323 0 0.00 0 14247 136 0 0
sp_pay_from_customer 7271 7271 0 0.00 4 15715 112 0 0
sp_pay_to_supplier 6144 6144 0 0.00 4 19078 126 0 0
sp_payment_common 13413 13413 0 0.00 1 15711 29 0 0
sp_qd_handle_on_cancel_clo 1903 1903 0 0.00 0 13438 330 0 0
sp_qd_handle_on_invoice_upd_sts 10945 10945 0 0.00 0 34910 292 0 0
sp_qd_handle_on_reserve_upd_sts 10363 10363 0 0.00 0 23059 74 0 0
sp_reserve_write_off 10364 10364 0 0.00 6 23075 191 0 0
sp_supplier_invoice 5550 5550 0 0.00 5 78767 3140 0 0
sp_supplier_order 5733 5733 0 0.00 5 31556 1265 0 0
srv_aggregate_perf_data 106 106 0 0.00 2963 273201 20112 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1417 150453 43823 0 0
srv_find_qd_qs_mism 1083651 1083651 0 0.00 0 16785 6 0 0
srv_make_invnt_saldo 589 589 0 0.00 20 44445 1291 0 0
srv_make_money_saldo 740 740 0 0.00 0 5355 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 149504 384115 229802 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5175 5175 0 0.00 0 11508 170 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 4843 108 0 0
t$perf-norm:sp_cancel_client_order 1903 1903 0 0.00 0 6427 18 0 0
t$perf-norm:sp_cancel_customer_reserve 736 736 0 0.00 0 3702 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 3008 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 3410 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1269 16 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 18080 1641 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 1827 4 0 0
t$perf-norm:sp_client_order 21323 21323 0 0.00 0 10411 14 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 712 3 0 0
t$perf-norm:sp_pay_from_customer 7271 7271 0 0.00 0 7202 10 0 0
t$perf-norm:sp_pay_to_supplier 6144 6144 0 0.00 0 3344 10 0 0
t$perf-norm:sp_reserve_write_off 10364 10364 0 0.00 0 7131 12 0 0
t$perf-norm:sp_supplier_invoice 5550 5550 0 0.00 0 7059 15 0 0
t$perf-norm:sp_supplier_order 5733 5733 0 0.00 0 4204 12 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 236 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 11 0 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 2433 29 0 0
t$perf-norm:srv_make_money_saldo 740 740 0 0.00 0 2924 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10610 10330 280 2.63 0 8015 19 0 280
v_all_customers 39056 39056 0 0.00 0 80 1 0 0
v_all_suppliers 31506 31506 0 0.00 0 80 1 0 0
v_all_wares 42646 42646 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 2218 9 0 0
v_cancel_client_order 3806 3806 0 0.00 0 5217 7 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 3471 13 0 0
v_cancel_customer_reserve 1472 1472 0 0.00 0 1809 8 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 478 3 0 0
v_cancel_supplier_invoice 834 816 18 2.15 0 7933 36 0 18
v_cancel_supplier_order 802 802 0 0.00 0 2284 8 0 0
v_cancel_write_off 2038 2038 0 0.00 0 6371 18 0 0
v_max_id_clo_ord 5732 5732 0 0.00 0 288 1 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 1642 11 0 0
v_max_id_ord_sup 5545 5545 0 0.00 0 6987 4 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 2600 4 0 0
v_max_non_paid_realizn 3755 3755 0 0.00 0 2423 5 0 0
v_min_id_clo_ord 5732 5732 0 0.00 0 6707 7 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 6132 26 0 0
v_min_id_ord_sup 5545 5545 0 0.00 0 29170 41 0 0
v_min_non_paid_invoice 6142 6142 0 0.00 0 11110 18 0 0
v_min_non_paid_realizn 7271 7271 0 0.00 0 15708 24 0 0
v_our_firm 10622 10622 0 0.00 0 80 1 0 0
v_reserve_write_off 20726 20726 0 0.00 0 11442 9 0 0
x_lock_depdocs_on_canc_invoice 11767 11767 0 0.00 0 13657 116 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 8908 354 0 0
x_make_qstorno_1000_1200 5733 5733 0 0.00 2 30240 1185 0 0
x_make_qstorno_1200_2000 5550 5550 0 0.00 2 78730 2960 0 0
x_make_qstorno_2100_3300 477048 477048 0 0.00 0 114614 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2023 22:39:54 to 05-01-2023 22:39:58.

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
20:08:42 36 8192000000 8816997840 8834555904 19981424 0.243 5 5 6 0 20226656 95488 149744
20:09:43 28217 8192000000 9889755952 10195156992 966574208 10.553 100 92 182 0 997094000 6282816 24236976
20:11:15 22539 8192000000 9944584976 10266001408 1027774288 11.147 100 84 168 0 1057643616 8325744 21543584
20:12:42 18756 8192000000 9973287280 10306818048 1060575824 11.462 100 82 164 0 1091053648 9653200 20824624
20:14:05 10306 8192000000 10034520352 10380840960 1118521600 12.013 100 76 152 0 1147465200 10172336 18771264
20:15:22 3894 8192000000 10085003920 10440847360 1168804416 12.486 100 81 163 0 1199791792 12343504 18643872
20:16:30 3369 8192000000 10121758176 10490953728 1215685104 12.922 100 83 166 1 1246559968 12230976 18643888
20:17:35 3031 8192000000 10169185376 10542145536 1251167200 13.249 100 90 178 1 1285283072 14096656 20019216
20:18:39 3403 8192000000 10181133856 10561810432 1271247568 13.433 100 80 158 1 1301420944 13077680 17095696
20:19:45 1349 8192000000 10207672432 10591010816 1294782336 13.648 100 93 185 1 1330498704 14662480 21053888
20:20:49 15937 8192000000 10207018240 10600771584 1301231264 13.706 100 69 137 1 1326094592 8703232 16160096
20:22:06 3923 8192000000 10215931056 10614353920 1313072016 13.814 100 58 116 1 1334500864 8598208 12830640
20:23:10 3472 8192000000 10209789200 10617925632 1319956992 13.876 100 41 81 0 1334960256 5683504 9319760
20:24:17 13531 8192000000 10216953296 10634653696 1332172384 13.987 100 15 30 1 1341256592 5968384 3115824
20:25:32 44081 8192000000 10244811536 10656071680 1337614976 14.036 100 47 93 1 1358702432 8809392 12278064
20:27:16 6421 8192000000 10244563088 10653233152 1327297472 13.943 99 29 56 0 1344707184 10335120 7074592
20:28:25 8537 8192000000 10223990784 10644324352 1324762960 13.920 99 17 34 0 1334314416 5836672 3714784
20:29:36 37841 8192000000 10174570000 10586251264 1261099664 13.340 96 22 42 0 1271093728 5170816 4823248
20:31:14 4221 8192000000 10167311888 10567598080 1253185664 13.267 98 39 78 0 1269521648 7901168 8434816
20:32:21 8652 8192000000 10140400336 10534203392 1225443600 13.012 98 21 42 2 1234720464 4586048 4690816
20:33:32 28694 8192000000 10138230976 10543738880 1197149168 12.750 94 24 47 1 1208337248 5254448 5933632
20:35:27 7857 8192000000 10185587152 10591039488 1286825152 13.575 100 28 56 0 1299797520 6935040 6037328
20:36:38 9776 8192000000 10193419552 10606637056 1300241040 13.697 100 16 31 0 1309597136 5243360 4112736
20:37:51 10163 8192000000 10208397872 10616324096 1308568304 13.773 100 21 39 0 1318235488 5183200 4483984
20:39:06 70410 8192000000 10237409040 10656686080 1341271744 14.069 100 47 94 1 1358843216 5900704 11670768
20:41:24 13207 8192000000 10266718640 10681044992 1357541280 14.215 100 48 96 0 1380457728 12248464 10667984
20:42:41 3815 8192000000 10278697584 10694275072 1370516592 14.332 100 58 114 0 1398364320 14237616 13610112
20:43:48 6747 8192000000 10286293824 10709319680 1376405280 14.384 100 53 105 0 1404230800 15723376 12102144
20:44:57 5465 8192000000 10294008768 10717327360 1382381312 14.438 100 48 96 1 1408455360 15033760 11040288
20:46:16 8330 8192000000 10309292144 10728443904 1394210864 14.543 100 52 103 0 1423634832 17214112 12209856
20:47:27 14483 8192000000 10314797360 10743644160 1387161680 14.481 99 49 96 0 1419611184 20766864 11682640
20:49:06 18493 8192000000 10327117536 10758594560 1404621296 14.636 100 49 96 2 1440611664 24222832 11767536
20:50:47 6547 8192000000 10339312832 10764455936 1411914272 14.701 100 49 98 1 1447166768 23613008 11639488
20:51:59 4101 8192000000 10352344416 10773823488 1420201728 14.774 100 64 126 0 1464358000 28992736 15163536
20:53:08 2204 8192000000 10370364096 10781396992 1416593840 14.742 100 80 161 1 1471264816 34735840 19935136
20:54:14 7481 8192000000 10335786368 10748948480 1383781632 14.450 99 63 124 0 1441115520 40498896 16834992
20:55:29 1609 8192000000 10360826816 10770055168 1400635168 14.601 100 67 130 2 1460897024 43767920 16493936
20:56:38 3697 8192000000 10356977808 10769727488 1384815120 14.460 99 61 120 0 1455513744 53982960 16715664
20:57:47 1838 8192000000 10371181424 10771357696 1391330208 14.518 100 88 174 1 1474866288 60997792 22538288
20:58:54 5989 8192000000 10370934912 10783834112 1387674832 14.485 99 59 118 0 1468737136 65389024 15673280
21:00:04 5636 8192000000 10358662480 10781265920 1390603568 14.511 100 49 98 1 1468526288 64469040 13453680
21:01:17 19645 8192000000 10339966144 10767728640 1381317024 14.428 100 36 71 0 1448393920 56909632 10167264
21:02:50 17913 8192000000 10330321744 10762330112 1342874544 14.083 98 28 56 0 1413625600 62395888 8355168
21:04:13 15297 8192000000 10336526800 10764800000 1359681984 14.235 99 33 66 1 1432682272 63855920 9144368
21:05:56 21687 8192000000 10318818512 10763186176 1348752880 14.136 100 24 48 0 1434904960 78221152 7930928
21:07:27 33478 8192000000 10279050192 10728058880 1369398832 14.322 100 9 18 0 1387074992 15451008 2225152
21:09:08 44965 8192000000 10285992464 10708123648 1332855120 13.993 97 39 79 0 1348805888 6954528 8996240
21:10:56 94243 8192000000 10277809216 10718580736 1327371856 13.943 96 28 56 0 1349704032 15415744 6916432
21:13:37 150453 8192000000 10297412608 10725490688 1324068784 13.914 95 29 58 0 1344570272 14058784 6442704
21:17:08 57799 8192000000 10284257376 10725998592 1377024688 14.390 100 24 48 0 1389875264 7193168 5657408
21:19:07 47442 8192000000 10303302672 10735349760 1386464512 14.474 100 28 52 1 1401430464 9004880 5961072
21:20:55 20096 8192000000 10307788176 10743676928 1387117904 14.480 100 45 88 0 1408759328 10833248 10808176
21:22:22 34528 8192000000 10289751120 10746191872 1388954928 14.497 100 15 29 0 1400520928 8232688 3333312
21:23:57 124587 8192000000 10302998704 10758279168 1372775776 14.352 98 17 35 1 1385900128 9824416 3299936
21:27:03 65576 8192000000 10295478912 10762309632 1390262832 14.508 99 33 66 0 1407235232 9291616 7680784
21:30:02 47663 8192000000 10303010096 10766442496 1386387648 14.474 99 4 8 0 1396724560 9690560 646352
21:31:51 47273 8192000000 10312851616 10771017728 1394241568 14.544 99 10 20 0 1405816384 9563856 2010960
21:33:38 66941 8192000000 10308739872 10765058048 1360709520 14.244 97 14 28 0 1382962976 19449552 2803904
21:35:46 12867 8192000000 10306846432 10745683968 1371867280 14.344 99 42 82 0 1389829104 8672176 9289648
21:37:00 33407 8192000000 10309964624 10760933376 1366868304 14.299 98 29 56 0 1387872064 14762560 6241200
21:38:34 39465 8192000000 10265504160 10710052864 1300166176 13.697 94 15 30 0 1311229024 8103968 2958880
21:40:14 21610 8192000000 10307508384 10743517184 1376868208 14.389 100 41 82 0 1399490976 13711840 8910928
21:42:23 75189 8192000000 10280389824 10718978048 1298126448 13.678 94 14 28 0 1318175248 17081104 2967696
21:44:40 65023 8192000000 10276940560 10713645056 1339182448 14.050 98 32 65 0 1353657904 6865888 7609568
21:46:48 1417 8192000000 10287398288 10720800768 1366554864 14.296 100 61 121 1 1391112160 10650192 13907104
21:48:13 87969 8192000000 10254207904 10720956416 1349632448 14.144 99 5 10 0 1360588160 10085616 870096
21:50:41 61554 8192000000 10264954336 10721521664 1349306272 14.141 98 26 52 1 1363555088 7724432 6524384
21:52:45 90727 8192000000 10265996672 10727448576 1342573200 14.081 98 16 31 0 1356638832 10646416 3419216
21:55:20 40104 8192000000 10265733152 10723110912 1356046848 14.202 99 20 40 0 1370150832 9251408 4852576
21:57:01 19729 8192000000 10286671072 10727321600 1356029584 14.202 99 46 93 0 1376282080 10075328 10177168
21:58:20 69579 8192000000 10296910352 10756247552 1362993808 14.264 99 17 34 0 1388051232 21349456 3707968
22:00:30 50312 8192000000 10280038992 10728775680 1300174384 13.697 94 27 54 1 1320490736 14203312 6113040
22:02:28 64239 8192000000 10310180208 10762203136 1362548464 14.260 99 21 42 1 1385647328 18236432 4862432
22:04:35 54996 8192000000 10292540608 10742730752 1343644688 14.090 97 28 54 0 1369580976 19487216 6449072
22:06:34 82218 8192000000 10289710672 10742243328 1380989408 14.425 100 22 44 0 1395617728 9524464 5103856
22:08:57 54169 8192000000 10302544736 10751496192 1374168688 14.364 98 35 70 0 1393196656 11038000 7989968
22:11:01 31314 8192000000 10281098432 10749440000 1383679520 14.449 99 11 22 1 1401058464 14643104 2735840
22:12:54 64610 8192000000 10292740096 10755911680 1366695856 14.297 98 28 57 0 1385509872 12349472 6464544
22:15:02 23704 8192000000 10310848720 10760642560 1385435712 14.465 99 26 51 0 1396952272 5860512 5656048
22:16:32 68231 8192000000 10307464544 10771742720 1380458192 14.421 98 20 40 0 1397417328 12779824 4179312
22:18:44 63575 8192000000 10304391280 10756661248 1368319488 14.312 98 29 58 0 1386590208 11784848 6485872
22:20:52 145595 8192000000 10283133200 10747297792 1308339216 13.771 94 12 24 0 1322967696 12159568 2468912
22:24:18 61050 8192000000 10303134112 10768076800 1367486384 14.305 99 18 37 0 1389446144 17912816 4046944
22:26:20 111603 8192000000 10288896768 10744860672 1370632256 14.333 99 16 32 0 1386467136 12138928 3695952
22:29:12 41472 8192000000 10284692560 10741923840 1372584928 14.350 99 26 51 0 1392604880 13881360 6138592
22:31:00 88336 8192000000 10260826944 10722148352 1323252768 13.906 96 10 20 0 1336401536 10924400 2224368
22:33:44 60058 8192000000 10267119712 10745987072 1358576224 14.225 99 6 12 0 1377917312 17594336 1746752
22:35:50 38268 8192000000 10266130576 10722258944 1352528800 14.170 99 16 33 0 1364278368 8491232 3258336
22:37:44 36306 8192000000 10283985152 10739789824 1354229680 14.186 98 14 28 1 1365773136 8408144 3135312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2023 22:40:00 to 05-01-2023 22:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-01-2023 22:40:16 to 05-01-2023 22:40:18.

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          243500
MON$OLDEST_ACTIVE               244701
MON$OLDEST_SNAPSHOT             244701
MON$NEXT_TRANSACTION            244701
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                       2034208
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:1706: 
:1707: mscs-test-161.budzdorov.ru    Thu Jan  5 22:38:39 2023
:1708:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1709: 
:1710: 
:1711: mscs-test-161.budzdorov.ru    Thu Jan  5 22:38:43 2023
:1712:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1713: 
:1714: 
:1715: mscs-test-161.budzdorov.ru    Thu Jan  5 22:38:44 2023
:1716:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1717: 
--- end of diff output ---

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

05.01.23 22:40:19. 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
2023-01-05 22:40:22 No severe PSQL-related problems occured
05.01.23 22:40:23. End of report.