Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.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 15302580 kB
MemAvailable 30386220 kB
Buffers 0 kB
Cached 16388484 kB
SwapCached 10732 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-31 16:38:34
DTS_END                         2022-12-31 16:38:34.3580
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54972
TRN_ID                          252564
ATT_ID                          692
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: 9275.08 18312 1113009
customer order: creation 182.14 341 21857
customer order: refuse 15.45 330 1854
order to supplier: creation 48.68 1183 5842
order to supplier: removal 3.54 95739 425
invoice (draft): creation 49.88 3401 5986
invoice (draft): removal 51.82 757 6218
invoice accept: apply 44.85 16254 5382
invoice accept: cancel 46.48 759 5578
customer reserve: creation 4017.75 164 482130
customer reserve: removal 4505.87 59 540704
realization accept: apply 88.64 183 10637
realization accept: cancel 83.68 107 10042
payment from customer: creation 62.93 137 7552
payment from customer: removal 5.80 83 696
payment to supplier: creation 51.80 145 6216
payment to supplier: removal 3.50 93 420
service: total inventory turnovers 4.81 1233 577
service: total monetary turnovers 6.53 48 784
service: aggregate perf. data 0.88 20306 105
service: refresh index statistics 0.03 224926 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-12-2022 16:39:39 to 31-12-2022 16:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5728
TEST_TIME 2 3021
TEST_TIME 3 3068
TEST_TIME 4 3133
TEST_TIME 5 4433
TEST_TIME 6 4181
TEST_TIME 7 4638
TEST_TIME 8 4962
TEST_TIME 9 4948
TEST_TIME 10 5172
TEST_TIME 11 5467
TEST_TIME 12 5873
TEST_TIME 13 6213
TEST_TIME 14 6284
TEST_TIME 15 6471
TEST_TIME 16 6687
TEST_TIME 17 7058
TEST_TIME 18 7033
TEST_TIME 19 7230
TEST_TIME 20 7466
TEST_TIME 21 7654
TEST_TIME 22 8282
TEST_TIME 23 8645
TEST_TIME 24 9027
TEST_TIME 25 9101
TEST_TIME 26 9209
TEST_TIME 27 9397
TEST_TIME 28 9881
TEST_TIME 29 10668
TEST_TIME 30 10538
TEST_TIME 31 10342
TEST_TIME 32 10275
TEST_TIME 33 10049
TEST_TIME 34 9918
TEST_TIME 35 9843
TEST_TIME 36 9818
TEST_TIME 37 9776
TEST_TIME 38 9628
TEST_TIME 39 9703
TEST_TIME 40 9550
TEST_TIME 41 9492
TEST_TIME 42 9393
TEST_TIME 43 9504
TEST_TIME 44 9504
TEST_TIME 45 9650
TEST_TIME 46 9607
TEST_TIME 47 9602
TEST_TIME 48 9592
TEST_TIME 49 9654
TEST_TIME 50 9740
TEST_TIME 51 9623
TEST_TIME 52 9695
TEST_TIME 53 9798
TEST_TIME 54 9760
TEST_TIME 55 9779
TEST_TIME 56 9690
TEST_TIME 57 9809
TEST_TIME 58 9878
TEST_TIME 59 9875
TEST_TIME 60 9888
TEST_TIME 61 9772
TEST_TIME 62 9687
TEST_TIME 63 9644
TEST_TIME 64 9545
TEST_TIME 65 9607
TEST_TIME 66 9544
TEST_TIME 67 9475
TEST_TIME 68 9465
TEST_TIME 69 9416
TEST_TIME 70 9431
TEST_TIME 71 9410
TEST_TIME 72 9353
TEST_TIME 73 9404
TEST_TIME 74 9401
TEST_TIME 75 9445
TEST_TIME 76 9422
TEST_TIME 77 9442
TEST_TIME 78 9475
TEST_TIME 79 9453
TEST_TIME 80 9477
TEST_TIME 81 9518
TEST_TIME 82 9527
TEST_TIME 83 9568
TEST_TIME 84 9589
TEST_TIME 85 9572
TEST_TIME 86 9469
TEST_TIME 87 9450
TEST_TIME 88 9442
TEST_TIME 89 9415
TEST_TIME 90 9374
TEST_TIME 91 9359
TEST_TIME 92 9346
TEST_TIME 93 9320
TEST_TIME 94 9342
TEST_TIME 95 9334
TEST_TIME 96 9352
TEST_TIME 97 9404
TEST_TIME 98 9408
TEST_TIME 99 9392
TEST_TIME 100 9431
TEST_TIME 101 9418
TEST_TIME 102 9437
TEST_TIME 103 9451
TEST_TIME 104 9426
TEST_TIME 105 9443
TEST_TIME 106 9487
TEST_TIME 107 9474
TEST_TIME 108 9409
TEST_TIME 109 9392
TEST_TIME 110 9368
TEST_TIME 111 9354
TEST_TIME 112 9310
TEST_TIME 113 9282
TEST_TIME 114 9309
TEST_TIME 115 9260
TEST_TIME 116 9272
TEST_TIME 117 9255
TEST_TIME 118 9272
TEST_TIME 119 9251
TEST_TIME 120 9277

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-12-2022 16:39:43 to 31-12-2022 16:39:46.

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 1559856 1559856 0 0.00 0 26662 6 0 0
doc_list_biud 1559855 1559855 0 0.00 0 27242 26 0 0
sp_add_invoice_to_stock 5509 5382 127 2.30 34 208355 16255 0 127
sp_cancel_adding_invoice 5578 5578 0 0.00 4 46059 759 0 0
sp_cancel_client_order 1854 1854 0 0.00 3 11551 330 0 0
sp_cancel_customer_reserve 540704 540704 0 0.00 0 19367 59 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 2 9153 84 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 6736 94 0 0
sp_cancel_supplier_invoice 6225 6218 7 0.11 1 11702 758 0 7
sp_cancel_supplier_order 425 425 0 0.00 7 1197774 95739 0 0
sp_cancel_write_off 10042 10042 0 0.00 1 16107 107 0 0
sp_client_order 21857 21857 0 0.00 3 35607 342 0 0
sp_customer_reserve 482130 482130 0 0.00 0 44127 165 0 0
sp_fill_shopping_cart 33674 33674 0 0.00 1 25824 39 0 0
sp_fill_shopping_cart_clo_res 482127 482127 0 0.00 0 31464 14 0 0
sp_get_clo_for_invoice 5379 5379 0 0.00 0 32726 329 0 0
sp_kill_cost_storno 16736 16736 0 0.00 0 7381 54 0 0
sp_kill_qstorno_ret_qs2qd 1098093 1098093 0 0.00 0 19160 32 0 0
sp_kill_qty_storno 580836 580836 0 0.00 0 27242 70 0 0
sp_lock_selected_doc 22095 22095 0 0.00 0 9138 7 0 0
sp_make_cost_storno 29783 29783 0 0.00 0 18812 60 0 0
sp_multiply_rows_for_pdistr 29783 29783 0 0.00 0 17661 29 0 0
sp_multiply_rows_for_qdistr 21857 21857 0 0.00 0 11087 162 0 0
sp_pay_from_customer 7552 7552 0 0.00 4 20941 138 0 0
sp_pay_to_supplier 6216 6216 0 0.00 5 21627 145 0 0
sp_payment_common 13768 13768 0 0.00 1 20737 40 0 0
sp_qd_handle_on_cancel_clo 1854 1854 0 0.00 0 11519 296 0 0
sp_qd_handle_on_invoice_upd_sts 10956 10956 0 0.00 0 27241 325 0 0
sp_qd_handle_on_reserve_upd_sts 10637 10637 0 0.00 0 23429 55 0 0
sp_reserve_write_off 10637 10637 0 0.00 6 23480 184 0 0
sp_supplier_invoice 5986 5986 0 0.00 5 77488 3402 0 0
sp_supplier_order 5842 5842 0 0.00 5 33683 1184 0 0
srv_aggregate_perf_data 105 105 0 0.00 2511 294374 20306 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1374 124109 42836 0 0
srv_find_qd_qs_mism 1062911 1062911 0 0.00 0 26662 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 38 106740 1233 0 0
srv_make_money_saldo 784 784 0 0.00 1 9751 48 0 0
srv_recalc_idx_stat 4 4 0 0.00 110577 339304 224927 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 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 5382 5382 0 0.00 0 17261 156 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 5889 81 0 0
t$perf-norm:sp_cancel_client_order 1854 1854 0 0.00 0 3177 12 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 554 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 1188 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 1095 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 448 448 0 0.00 0 2599 25 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 28103 1531 0 0
t$perf-norm:sp_cancel_write_off 1072 1072 0 0.00 0 775 4 0 0
t$perf-norm:sp_client_order 21857 21857 0 0.00 0 16849 18 0 0
t$perf-norm:sp_customer_reserve 1073 1073 0 0.00 0 2565 6 0 0
t$perf-norm:sp_pay_from_customer 7552 7552 0 0.00 0 17244 14 0 0
t$perf-norm:sp_pay_to_supplier 6216 6216 0 0.00 0 4530 10 0 0
t$perf-norm:sp_reserve_write_off 10637 10637 0 0.00 0 11821 11 0 0
t$perf-norm:sp_supplier_invoice 5986 5986 0 0.00 0 5926 14 0 0
t$perf-norm:sp_supplier_order 5842 5842 0 0.00 0 7681 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 405 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1996 31 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1509 29 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 1063 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 11008 10754 254 2.30 0 18702 24 0 254
v_all_customers 40034 40034 0 0.00 0 47 1 0 0
v_all_suppliers 32172 32172 0 0.00 0 24 1 0 0
v_all_wares 43712 43712 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 4271 18 0 0
v_cancel_client_order 3708 3708 0 0.00 0 3711 6 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 2732 5 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 1789 9 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2424 9 0 0
v_cancel_supplier_invoice 910 896 14 1.53 0 1219 15 0 14
v_cancel_supplier_order 850 850 0 0.00 0 815 5 0 0
v_cancel_write_off 2144 2144 0 0.00 0 2019 15 0 0
v_max_id_clo_ord 5841 5841 0 0.00 0 114 1 0 0
v_max_id_clo_res 1073 1073 0 0.00 0 1542 13 0 0
v_max_id_ord_sup 5976 5976 0 0.00 0 4242 4 0 0
v_max_non_paid_invoice 1947 1947 0 0.00 0 2989 5 0 0
v_max_non_paid_realizn 4000 4000 0 0.00 0 4752 7 0 0
v_min_id_clo_ord 5841 5841 0 0.00 0 3617 5 0 0
v_min_id_clo_res 1073 1073 0 0.00 0 4120 15 0 0
v_min_id_ord_sup 5976 5976 0 0.00 0 5313 7 0 0
v_min_non_paid_invoice 6216 6216 0 0.00 0 20731 33 0 0
v_min_non_paid_realizn 7552 7552 0 0.00 0 17284 30 0 0
v_our_firm 10782 10782 0 0.00 0 75 1 0 0
v_reserve_write_off 21274 21274 0 0.00 0 18709 10 0 0
x_lock_depdocs_on_canc_invoice 11348 11348 0 0.00 0 12390 116 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 6800 326 0 0
x_make_qstorno_1000_1200 5842 5842 0 0.00 1 33671 1114 0 0
x_make_qstorno_1200_2000 5986 5986 0 0.00 2 77459 3252 0 0
x_make_qstorno_2100_3300 482130 482130 0 0.00 0 35373 147 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 31-12-2022 16:39:47 to 31-12-2022 16:39:52.

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:38 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:39 27290 8192000000 9892326096 10200371200 970015360 10.587 100 88 176 0 999873984 6136160 23722464
14:11:17 19345 8192000000 9959419680 10284765184 1042684576 11.290 100 88 175 0 1073747472 8831280 22231616
14:12:44 5544 8192000000 10005820992 10342903808 1091057120 11.753 100 87 174 0 1122166448 10171680 20937648
14:13:54 9508 8192000000 10052271600 10399899648 1125586320 12.080 100 82 165 0 1156611104 11719296 19305488
14:15:10 4054 8192000000 10072086224 10430644224 1166254528 12.462 100 75 147 0 1195133136 12428144 16450464
14:16:19 3481 8192000000 10122931024 10489499648 1206168368 12.834 100 72 144 0 1235992096 13627216 16196512
14:17:24 6828 8192000000 10154792912 10529271808 1241454448 13.160 100 77 153 1 1271118000 12954672 16708880
14:18:33 1723 8192000000 10181246048 10565828608 1272604080 13.445 100 75 151 0 1301853216 12944368 16304768
14:19:36 1212 8192000000 10207512368 10591416320 1293330160 13.635 100 87 171 0 1326871760 14561808 18979792
14:20:39 10075 8192000000 10205243152 10605240320 1307101808 13.760 100 60 119 1 1327719648 7042496 13575344
14:21:52 10981 8192000000 10186362080 10604081152 1308965504 13.777 100 32 62 1 1323077136 6448992 7662640
14:23:08 5942 8192000000 10210023072 10621562880 1320799680 13.884 100 30 60 1 1334972496 7619840 6552976
14:24:16 5068 8192000000 10213502944 10628419584 1326381904 13.934 100 28 56 0 1338994944 6210352 6402688
14:25:23 64452 8192000000 10228155696 10642948096 1322376640 13.898 99 28 56 0 1333823024 5283264 6163120
14:27:28 8441 8192000000 10219221696 10635800576 1324933040 13.921 99 23 42 0 1334881024 5721024 4226960
14:28:38 5242 8192000000 10212937168 10626068480 1298342672 13.680 98 24 49 1 1309127520 5171136 5613712
14:29:44 11480 8192000000 10154396112 10564071424 1250064480 13.239 98 51 101 1 1267424864 6274656 11085728
14:31:28 2998 8192000000 10152638560 10551308288 1238612672 13.133 97 37 74 1 1254152160 6820768 8718720
14:32:33 6136 8192000000 10178066944 10579734528 1274927040 13.467 100 38 77 2 1295361344 11629920 8804384
14:33:42 9691 8192000000 10166860624 10568859648 1262646320 13.354 99 20 39 2 1272685248 5623776 4415152
14:34:54 34362 8192000000 10202543600 10597896192 1292419776 13.626 100 54 109 1 1311317360 5520336 13377248
14:36:30 5857 8192000000 10196300688 10607706112 1304617872 13.737 100 29 56 0 1317039664 6439840 5981952
14:37:40 8430 8192000000 10216082768 10626326528 1319029136 13.868 100 36 70 0 1333079344 5752560 8297648
14:38:51 19598 8192000000 10243499152 10653765632 1340015296 14.058 100 44 85 1 1356000048 6846320 9138432
14:40:15 106902 8192000000 10261227648 10686967808 1356038512 14.202 100 31 62 0 1372110480 8569232 7502736
14:43:15 17955 8192000000 10280862912 10705956864 1372170224 14.346 100 34 70 0 1397427216 17007744 8249248
14:44:35 30338 8192000000 10299831328 10725416960 1387079344 14.480 100 50 100 0 1418069824 19666560 11323920
14:46:11 2043 8192000000 10313382800 10731925504 1388448320 14.492 100 65 130 1 1421431680 17872496 15110864
14:47:19 11664 8192000000 10298237616 10720669696 1373826528 14.361 99 45 87 1 1402720896 18482000 10412368
14:48:37 2227 8192000000 10320168720 10734342144 1386231872 14.472 100 65 130 1 1428944704 27109232 15603600
14:49:45 1994 8192000000 10320948976 10732965888 1384857008 14.460 100 77 149 1 1432644400 28985968 18801424
14:50:52 3948 8192000000 10346657840 10765660160 1399274544 14.589 100 69 131 1 1450296192 35907808 15113840
14:52:05 3778 8192000000 10327400480 10743332864 1386539776 14.475 100 76 151 4 1439158880 33820800 18798304
14:53:13 4915 8192000000 10321955712 10735001600 1380842192 14.424 100 78 154 0 1434632192 35164688 18625312
14:54:22 2680 8192000000 10320236736 10728136704 1365160208 14.284 100 73 142 1 1423806704 40887072 17759424
14:55:31 2945 8192000000 10316929136 10722844672 1369508928 14.323 100 70 138 0 1422731824 36050112 17172784
14:56:42 2598 8192000000 10341901984 10748473344 1380741984 14.423 100 77 154 0 1445879872 44855168 20282720
14:57:50 1373 8192000000 10350785872 10756923392 1382213328 14.436 100 85 167 1 1453007072 50019088 20774656
14:59:00 3458 8192000000 10339761696 10749423616 1364575568 14.278 99 64 126 1 1431551296 50589952 16385776
15:00:07 10828 8192000000 10323809744 10747179008 1363003056 14.264 99 37 74 0 1426447120 53020784 10423280
15:01:28 7467 8192000000 10342974624 10765537280 1370122592 14.328 99 44 87 0 1438214896 56715024 11377280
15:02:40 13669 8192000000 10313733888 10748538880 1368659408 14.315 99 24 48 1 1417688896 42108304 6921184
15:03:58 10271 8192000000 10334516976 10767130624 1376717232 14.387 99 35 68 0 1432840576 47091440 9031904
15:05:18 14584 8192000000 10314989648 10755031040 1367751200 14.307 99 23 46 0 1416184848 41775104 6658544
15:06:38 33167 8192000000 10310004608 10776010752 1375058592 14.372 100 14 28 0 1426763248 47286560 4418096
15:08:12 54864 8192000000 10269627504 10707062784 1336858336 14.029 97 16 30 0 1349072176 8714848 3498992
15:10:09 47566 8192000000 10272378304 10708807680 1340354576 14.061 97 41 80 1 1356468832 7624368 8489888
15:11:57 70227 8192000000 10282151616 10725285888 1351566144 14.162 98 17 32 0 1363898784 8783616 3549024
15:14:13 78007 8192000000 10294545440 10743455744 1367255040 14.302 99 22 42 0 1388761728 15994048 5512640
15:17:14 3737 8192000000 10318387776 10746798080 1384475712 14.457 99 65 125 3 1409829264 11372240 13981312
15:18:18 10318 8192000000 10324033792 10772058112 1411489504 14.697 100 27 54 1 1434601424 16589408 6522512
15:19:35 35707 8192000000 10347678992 10789445632 1420351840 14.776 100 49 93 1 1451902608 19148224 12402544
15:21:12 28162 8192000000 10323798848 10796879872 1413283664 14.713 100 14 28 0 1442485280 25488672 3712944
15:23:12 34970 8192000000 10312104640 10770661376 1395539136 14.555 100 17 34 0 1426278512 26504896 4234480
15:25:01 15770 8192000000 10290204896 10746175488 1379161232 14.409 99 15 30 0 1394638352 11592992 3884128
15:26:18 40928 8192000000 10306224784 10756005888 1366863488 14.299 98 23 46 0 1379312576 7396288 5052800
15:28:01 85691 8192000000 10312066816 10775126016 1393958880 14.541 100 8 16 0 1415713584 19816096 1938608
15:30:39 49502 8192000000 10288592128 10750787584 1351851104 14.164 97 8 16 0 1370707472 16556896 2299472
15:32:29 27412 8192000000 10286035120 10729615360 1345305424 14.105 97 29 56 0 1360186496 8874736 6006336
15:33:57 37291 8192000000 10299228752 10739134464 1365874736 14.290 99 26 51 0 1385020464 13350560 5795168
15:35:34 62431 8192000000 10290971008 10737836032 1340469344 14.062 97 24 48 0 1359953360 13691072 5792944
15:37:44 70112 8192000000 10304588320 10751041536 1357274528 14.213 98 27 54 0 1382111472 18901360 5935584
15:39:57 82801 8192000000 10251119920 10692157440 1303583440 13.728 95 18 37 2 1312950704 5524352 3842912
15:42:20 20835 8192000000 10267525952 10713612288 1365977504 14.291 100 15 30 0 1378114352 8848480 3288368
15:43:46 55416 8192000000 10271546368 10735992832 1356699760 14.208 99 13 26 0 1371438304 11687248 3051296
15:45:42 60901 8192000000 10290555200 10747330560 1374043232 14.363 99 24 48 0 1394969344 14604400 6321712
15:47:43 38476 8192000000 10284068752 10755989504 1381175840 14.427 100 9 18 0 1404893808 21786272 1931696
15:49:21 54062 8192000000 10276181088 10734702592 1357275536 14.213 98 10 20 0 1366310240 6774512 2260192
15:51:16 46320 8192000000 10294986608 10760015872 1381112064 14.426 99 30 60 1 1405060224 16844864 7103296
15:53:06 65264 8192000000 10266096368 10727649280 1344034512 14.094 97 20 40 0 1358879568 10149776 4695280
15:55:18 73326 8192000000 10264809008 10724118528 1327386464 13.944 96 12 24 0 1341140352 10567840 3186048
15:57:45 61894 8192000000 10288165984 10743459840 1359299536 14.231 98 19 38 1 1377285488 13807344 4178608
15:59:52 35585 8192000000 10268157536 10722357248 1337277840 14.033 97 20 40 0 1357396160 15731696 4386624
16:01:27 124109 8192000000 10271209440 10722156544 1311091856 13.796 96 11 22 0 1324703072 11230672 2380544
16:04:32 94716 8192000000 10288021344 10734145536 1345452272 14.107 98 42 84 0 1367918960 12983600 9483088
16:08:47 37000 8192000000 10271380912 10729795584 1365454144 14.286 99 10 18 0 1376893984 9706944 1732896
16:10:27 65510 8192000000 10270055552 10733207552 1362407456 14.259 99 10 20 0 1370194624 6065200 1721968
16:12:33 73681 8192000000 10291562208 10741665792 1333624848 14.000 96 18 36 0 1346602704 8837952 4139904
16:14:48 65826 8192000000 10276679264 10739179520 1339860960 14.056 97 26 52 0 1356177040 10543072 5773008
16:17:22 55767 8192000000 10262749712 10711154688 1324995840 13.922 97 40 79 0 1343401216 9792528 8612848
16:19:19 93588 8192000000 10279059664 10714865664 1330634576 13.973 97 27 54 1 1344957184 8532016 5790592
16:21:55 70235 8192000000 10270720224 10710253568 1297552464 13.673 95 18 36 0 1310271312 8911056 3807792
16:24:09 38679 8192000000 10268269392 10711498752 1318189264 13.860 97 34 68 0 1340805504 15347040 7269200
16:25:55 105506 8192000000 10258638784 10709725184 1338806112 14.047 98 29 58 0 1352145728 6926464 6413152
16:28:44 41197 8192000000 10263520304 10717454336 1342362208 14.079 98 18 36 1 1353445568 7073504 4009856
16:30:31 70627 8192000000 10274544304 10727026688 1356968912 14.210 98 20 40 0 1371295696 9480416 4846368
16:32:42 93692 8192000000 10265163056 10729041920 1358391856 14.223 99 15 30 0 1369760448 7247088 4121504
16:35:16 81138 8192000000 10273180176 10729263104 1345928912 14.111 98 13 26 1 1357663056 8956592 2777552
16:37:37 59898 8192000000 9463596624 9838837760 563527072 6.436 41 10 20 0 570501568 4525104 2449392

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-12-2022 16:39:54 to 31-12-2022 16:40:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 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 31-12-2022 16:40:10 to 31-12-2022 16:40:12.

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          252821
MON$OLDEST_ACTIVE               252822
MON$OLDEST_SNAPSHOT             252822
MON$NEXT_TRANSACTION            252822
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                       2031904
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 ---
--- end of diff output ---

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

31.12.22 16:40:13. 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-31 16:40:15 No severe PSQL-related problems occured
31.12.22 16:40:17. End of report.