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

Hardware and OS info

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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-23 16:37:56
DTS_END                         2021-11-23 16:37:56.8250
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/58596
TRN_ID                          243688
ATT_ID                          666
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: 9465.91 24993 1135909
customer order: creation 170.45 373 20454
customer order: refuse 14.75 350 1770
order to supplier: creation 46.32 1358 5558
order to supplier: removal 3.36 96663 403
invoice (draft): creation 45.80 3346 5496
invoice (draft): removal 54.33 700 6520
invoice accept: apply 42.30 16852 5076
invoice accept: cancel 49.22 819 5906
customer reserve: creation 3883.02 166 465962
customer reserve: removal 4853.05 52 582366
realization accept: apply 81.40 216 9768
realization accept: cancel 93.53 106 11223
payment from customer: creation 58.60 138 7032
payment from customer: removal 5.83 86 699
payment to supplier: creation 48.24 145 5789
payment to supplier: removal 3.29 107 395
service: total inventory turnovers 4.55 1291 546
service: total monetary turnovers 7.03 45 844
service: aggregate perf. data 0.82 19572 98
service: refresh index statistics 0.03 357466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-11-2021 16:40:38 to 23-11-2021 16:40:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4380
TEST_TIME 2 3496
TEST_TIME 3 3009
TEST_TIME 4 3229
TEST_TIME 5 3840
TEST_TIME 6 3902
TEST_TIME 7 3771
TEST_TIME 8 3947
TEST_TIME 9 4352
TEST_TIME 10 4910
TEST_TIME 11 4864
TEST_TIME 12 5168
TEST_TIME 13 5420
TEST_TIME 14 5615
TEST_TIME 15 6161
TEST_TIME 16 6445
TEST_TIME 17 6804
TEST_TIME 18 7905
TEST_TIME 19 8110
TEST_TIME 20 8294
TEST_TIME 21 8598
TEST_TIME 22 8712
TEST_TIME 23 8957
TEST_TIME 24 9311
TEST_TIME 25 9331
TEST_TIME 26 9290
TEST_TIME 27 9557
TEST_TIME 28 10103
TEST_TIME 29 10454
TEST_TIME 30 10382
TEST_TIME 31 10562
TEST_TIME 32 10440
TEST_TIME 33 10267
TEST_TIME 34 10068
TEST_TIME 35 10175
TEST_TIME 36 10097
TEST_TIME 37 9953
TEST_TIME 38 9905
TEST_TIME 39 9770
TEST_TIME 40 9651
TEST_TIME 41 9647
TEST_TIME 42 9815
TEST_TIME 43 9873
TEST_TIME 44 9823
TEST_TIME 45 9745
TEST_TIME 46 9716
TEST_TIME 47 9711
TEST_TIME 48 9615
TEST_TIME 49 9703
TEST_TIME 50 9637
TEST_TIME 51 9563
TEST_TIME 52 9498
TEST_TIME 53 9449
TEST_TIME 54 9440
TEST_TIME 55 9371
TEST_TIME 56 9315
TEST_TIME 57 9311
TEST_TIME 58 9277
TEST_TIME 59 9448
TEST_TIME 60 9519
TEST_TIME 61 9510
TEST_TIME 62 9604
TEST_TIME 63 9614
TEST_TIME 64 9650
TEST_TIME 65 9690
TEST_TIME 66 9810
TEST_TIME 67 9810
TEST_TIME 68 9778
TEST_TIME 69 9790
TEST_TIME 70 9775
TEST_TIME 71 9697
TEST_TIME 72 9702
TEST_TIME 73 9619
TEST_TIME 74 9606
TEST_TIME 75 9547
TEST_TIME 76 9485
TEST_TIME 77 9485
TEST_TIME 78 9459
TEST_TIME 79 9413
TEST_TIME 80 9412
TEST_TIME 81 9402
TEST_TIME 82 9403
TEST_TIME 83 9370
TEST_TIME 84 9442
TEST_TIME 85 9412
TEST_TIME 86 9456
TEST_TIME 87 9443
TEST_TIME 88 9496
TEST_TIME 89 9503
TEST_TIME 90 9526
TEST_TIME 91 9538
TEST_TIME 92 9555
TEST_TIME 93 9494
TEST_TIME 94 9471
TEST_TIME 95 9425
TEST_TIME 96 9378
TEST_TIME 97 9397
TEST_TIME 98 9380
TEST_TIME 99 9336
TEST_TIME 100 9391
TEST_TIME 101 9349
TEST_TIME 102 9386
TEST_TIME 103 9542
TEST_TIME 104 9549
TEST_TIME 105 9546
TEST_TIME 106 9556
TEST_TIME 107 9546
TEST_TIME 108 9552
TEST_TIME 109 9603
TEST_TIME 110 9673
TEST_TIME 111 9652
TEST_TIME 112 9639
TEST_TIME 113 9595
TEST_TIME 114 9582
TEST_TIME 115 9556
TEST_TIME 116 9550
TEST_TIME 117 9503
TEST_TIME 118 9499
TEST_TIME 119 9508
TEST_TIME 120 9468

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-11-2021 16:40:41 to 23-11-2021 16:40:45.

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 1567553 1567553 0 0.00 0 38575 6 0 0
doc_list_biud 1567553 1567553 0 0.00 0 37948 25 0 0
sp_add_invoice_to_stock 5188 5076 112 2.15 32 166339 16853 0 112
sp_cancel_adding_invoice 5906 5906 0 0.00 5 65086 820 0 0
sp_cancel_client_order 1770 1770 0 0.00 4 22749 350 0 0
sp_cancel_customer_reserve 582366 582366 0 0.00 1 25296 53 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 6845 87 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 2 17673 107 0 0
sp_cancel_supplier_invoice 6526 6520 6 0.09 2 11207 701 0 6
sp_cancel_supplier_order 403 403 0 0.00 4 1094279 96664 0 0
sp_cancel_write_off 11223 11223 0 0.00 1 17306 107 0 0
sp_client_order 20454 20454 0 0.00 3 19761 374 0 0
sp_customer_reserve 465962 465962 0 0.00 0 85262 167 0 0
sp_fill_shopping_cart 31493 31493 0 0.00 1 16625 40 0 0
sp_fill_shopping_cart_clo_res 465955 465955 0 0.00 0 24138 14 0 0
sp_get_clo_for_invoice 5068 5068 0 0.00 0 26135 313 0 0
sp_kill_cost_storno 18223 18223 0 0.00 0 17738 60 0 0
sp_kill_qstorno_ret_qs2qd 1182878 1182878 0 0.00 0 25154 28 0 0
sp_kill_qty_storno 623023 623023 0 0.00 0 37946 62 0 0
sp_lock_selected_doc 20667 20667 0 0.00 0 10583 6 0 0
sp_make_cost_storno 27656 27656 0 0.00 0 38567 70 0 0
sp_multiply_rows_for_pdistr 27656 27656 0 0.00 0 14450 35 0 0
sp_multiply_rows_for_qdistr 20454 20454 0 0.00 0 10682 183 0 0
sp_pay_from_customer 7032 7032 0 0.00 4 15581 138 0 0
sp_pay_to_supplier 5789 5789 0 0.00 4 21925 145 0 0
sp_payment_common 12821 12821 0 0.00 1 15598 37 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 0 22741 319 0 0
sp_qd_handle_on_invoice_upd_sts 10973 10973 0 0.00 0 37942 335 0 0
sp_qd_handle_on_reserve_upd_sts 9768 9768 0 0.00 0 16206 64 0 0
sp_reserve_write_off 9768 9768 0 0.00 6 19528 217 0 0
sp_supplier_invoice 5496 5496 0 0.00 6 113073 3347 0 0
sp_supplier_order 5558 5558 0 0.00 5 68025 1358 0 0
srv_aggregate_perf_data 98 98 0 0.00 2775 185659 19572 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2400 153435 49712 0 0
srv_find_qd_qs_mism 1088339 1088339 0 0.00 0 25701 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 54 57927 1292 0 0
srv_make_money_saldo 844 844 0 0.00 1 16186 45 0 0
srv_recalc_idx_stat 4 4 0 0.00 257770 445789 357467 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5076 5076 0 0.00 0 23548 206 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 9249 134 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 5390 17 0 0
t$perf-norm:sp_cancel_customer_reserve 730 730 0 0.00 0 3813 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 1401 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 1265 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 704 10 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 28627 1849 0 0
t$perf-norm:sp_cancel_write_off 1007 1007 0 0.00 0 3853 11 0 0
t$perf-norm:sp_client_order 20454 20454 0 0.00 0 9058 19 0 0
t$perf-norm:sp_customer_reserve 1009 1009 0 0.00 0 20269 30 0 0
t$perf-norm:sp_pay_from_customer 7032 7032 0 0.00 0 9301 11 0 0
t$perf-norm:sp_pay_to_supplier 5789 5789 0 0.00 0 6357 12 0 0
t$perf-norm:sp_reserve_write_off 9769 9769 0 0.00 0 6040 11 0 0
t$perf-norm:sp_supplier_invoice 5496 5496 0 0.00 0 6216 21 0 0
t$perf-norm:sp_supplier_order 5558 5558 0 0.00 0 7625 22 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 104 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 898 27 0 0
t$perf-norm:srv_make_money_saldo 844 844 0 0.00 0 5689 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 10358 10134 224 2.16 0 8398 19 0 224
v_all_customers 36970 36970 0 0.00 0 23 1 0 0
v_all_suppliers 30232 30232 0 0.00 0 23 1 0 0
v_all_wares 40908 40908 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1660 9 0 0
v_cancel_client_order 3540 3540 0 0.00 0 3037 7 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 836 4 0 0
v_cancel_customer_reserve 1460 1460 0 0.00 0 2738 12 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 691 5 0 0
v_cancel_supplier_invoice 856 844 12 1.40 0 2564 22 0 12
v_cancel_supplier_order 806 806 0 0.00 0 6394 16 0 0
v_cancel_write_off 2014 2014 0 0.00 0 2671 15 0 0
v_max_id_clo_ord 5548 5548 0 0.00 0 2621 2 0 0
v_max_id_clo_res 1009 1009 0 0.00 0 6478 31 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 5294 4 0 0
v_max_non_paid_invoice 1712 1712 0 0.00 0 3714 6 0 0
v_max_non_paid_realizn 3913 3913 0 0.00 0 11169 8 0 0
v_min_id_clo_ord 5548 5548 0 0.00 0 3363 7 0 0
v_min_id_clo_res 1009 1009 0 0.00 0 3735 16 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 2854 5 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 10623 25 0 0
v_min_non_paid_realizn 7032 7032 0 0.00 0 8027 27 0 0
v_our_firm 10176 10176 0 0.00 0 16 1 0 0
v_reserve_write_off 19536 19536 0 0.00 0 6965 8 0 0
x_lock_depdocs_on_canc_invoice 12004 12004 0 0.00 0 15793 112 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 9215 414 0 0
x_make_qstorno_1000_1200 5558 5558 0 0.00 2 68008 1265 0 0
x_make_qstorno_1200_2000 5496 5496 0 0.00 3 110608 3210 0 0
x_make_qstorno_2100_3300 465962 465962 0 0.00 0 85262 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-11-2021 16:40:46 to 23-11-2021 16:40:50.

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:00 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:01 30640 8192000000 9901851152 10213502976 981698464 10.701 100 85 170 0 1010615024 6092032 22824528
14:10:35 21169 8192000000 9941625216 10260422656 1021111984 11.083 100 90 180 0 1053824560 8943632 23768944
14:12:01 16795 8192000000 9990023488 10323173376 1068115824 11.534 100 81 162 0 1097954016 9613168 20225024
14:13:20 15989 8192000000 10033393520 10383831040 1118225488 12.010 100 78 155 0 1147546992 10600512 18720992
14:14:39 11318 8192000000 10078906784 10444849152 1169289056 12.490 100 73 147 0 1197405568 11517296 16599216
14:15:54 4489 8192000000 10137275344 10508935168 1224993344 13.008 100 82 163 2 1256125168 13170864 17960960
14:17:01 4558 8192000000 10174883440 10553839616 1259484384 13.325 100 80 160 0 1290598832 13014928 18099520
14:18:07 1865 8192000000 10203489024 10583977984 1283486336 13.545 100 88 172 2 1316720672 13373632 19860704
14:19:10 27407 8192000000 10215645600 10610601984 1299571952 13.691 100 70 140 1 1325546352 10130080 15844320
14:20:38 5038 8192000000 10208292832 10617524224 1312739552 13.811 100 31 62 1 1326425248 6374144 7311552
14:21:47 12316 8192000000 10205197056 10624278528 1319400016 13.871 100 19 38 1 1330398432 7186096 3812320
14:23:00 17407 8192000000 10225713056 10637594624 1324619856 13.919 100 33 65 0 1338076384 5472160 7984368
14:25:00 3412 8192000000 10227170384 10645987328 1332829120 13.993 100 49 97 1 1350864112 5815136 12219856
14:26:06 12058 8192000000 10227235360 10643210240 1319203600 13.869 99 21 42 3 1329830800 5778704 4848496
14:27:18 5116 8192000000 10192282080 10607935488 1291210368 13.615 98 26 52 1 1302891792 5776592 5904832
14:28:23 42828 8192000000 10162112144 10570452992 1177974592 12.571 90 32 64 0 1190060800 4396800 7689408
14:30:07 8191 8192000000 10152660656 10554093568 1218867136 12.951 96 29 58 1 1231512032 5836128 6808768
14:31:18 6184 8192000000 10141596656 10536787968 1233911568 13.090 98 38 76 0 1250073008 6052576 10108864
14:32:26 16690 8192000000 10163151248 10563756032 1266884896 13.393 100 23 46 1 1277893408 5991840 5016672
14:33:43 38434 8192000000 10182305584 10586734592 1277718752 13.492 100 29 58 3 1291209520 6781040 6709728
14:35:24 8292 8192000000 10196297040 10607157248 1302802416 13.721 100 25 49 2 1314936304 5156032 6977856
14:36:34 9938 8192000000 10223273040 10632196096 1311405280 13.799 100 24 48 2 1322504400 5392080 5707040
14:37:45 10707 8192000000 10213239008 10622218240 1304088672 13.732 99 18 35 3 1314191920 5083600 5019648
14:39:07 9306 8192000000 10262325728 10667057152 1345716624 14.109 100 53 104 1 1367451280 8810992 12923664
14:40:20 51172 8192000000 10264380688 10682777600 1359383168 14.232 100 49 98 0 1379860304 9551040 10926096
14:42:18 3790 8192000000 10295116112 10712633344 1375559920 14.377 100 60 120 0 1407351440 17617648 14173872
14:43:28 28167 8192000000 10302138384 10732605440 1389741392 14.504 100 30 59 0 1416595344 19420640 7433312
14:45:02 15293 8192000000 10322062384 10749124608 1402699296 14.619 100 46 90 2 1435602960 21923472 10980192
14:46:21 13945 8192000000 10316500528 10738274304 1388873584 14.496 100 54 108 1 1427073872 25071904 13128384
14:47:40 22776 8192000000 10305817504 10737389568 1371121264 14.337 98 41 82 1 1408451424 26970704 10359456
14:49:09 6415 8192000000 10347405440 10763243520 1410599760 14.689 100 64 128 1 1451198800 25611072 14987968
14:50:19 3858 8192000000 10343375488 10755989504 1401483424 14.608 100 75 148 1 1448268640 28628576 18156640
14:51:28 3886 8192000000 10348140768 10755883008 1399877264 14.594 100 87 174 2 1453169232 32128960 21163008
14:52:35 2717 8192000000 10339296032 10746007552 1389160080 14.498 100 87 174 0 1444282336 32499632 22622624
14:53:42 6729 8192000000 10307673552 10709164032 1348851200 14.137 99 80 159 0 1409448448 39831568 20765680
14:54:53 2400 8192000000 10323889904 10732630016 1357101824 14.211 99 78 154 1 1419630688 42923936 19604928
14:56:01 6302 8192000000 10311548592 10736672768 1364748624 14.280 99 47 94 2 1410707456 32865648 13093184
14:57:16 4443 8192000000 10318395904 10747219968 1377613024 14.395 100 41 80 0 1422913360 34309104 10991232
14:58:32 10314 8192000000 10296340720 10719395840 1348885152 14.137 99 36 71 1 1392755344 34752144 9118048
14:59:59 3320 8192000000 10311189712 10725609472 1362379088 14.259 100 69 138 2 1415875952 35931088 17565776
15:01:08 9690 8192000000 10296986656 10724757504 1347338240 14.124 99 45 90 1 1393952608 35147248 11467120
15:02:24 21003 8192000000 10302782848 10739298304 1366595136 14.297 100 24 48 2 1413006944 40036816 6374992
15:03:53 24045 8192000000 10292470784 10726461440 1338201776 14.041 98 26 52 0 1384900240 39053360 7645104
15:05:23 44145 8192000000 10275837056 10716606464 1318537648 13.863 97 14 28 1 1348898960 26943712 3417600
15:07:21 43721 8192000000 10274771376 10727825408 1358044016 14.220 100 10 20 0 1379217024 18565024 2607984
15:09:07 18822 8192000000 10286904352 10724745216 1371197136 14.338 100 45 95 2 1392128464 9479984 11451344
15:10:37 98511 8192000000 10285863424 10726612992 1359040192 14.229 98 32 65 2 1377360112 11097888 7222032
15:13:16 113582 8192000000 10302129552 10742747136 1351956000 14.165 98 20 40 0 1375638240 19832576 3849664
15:16:10 50160 8192000000 10313851696 10755330048 1387640032 14.485 99 27 54 2 1411220192 16510112 7070048
15:18:07 31873 8192000000 10329240016 10782662656 1411541280 14.698 100 30 60 0 1442628752 23590048 7497424
15:19:44 96355 8192000000 10304622672 10763624448 1384377552 14.456 99 12 24 0 1407103616 20193152 2532912
15:22:30 20055 8192000000 10314867856 10763894784 1404548176 14.635 100 35 70 1 1426375424 13023744 8803504
15:23:52 59159 8192000000 10289896016 10757709824 1373518256 14.359 98 9 18 0 1386480240 10824576 2137408
15:26:17 46567 8192000000 10289036720 10753171456 1376146128 14.382 99 24 47 1 1398662496 17065024 5451344
15:28:06 73017 8192000000 10288002336 10759675904 1391406640 14.518 100 7 14 1 1405848160 13018016 1423504
15:30:20 90478 8192000000 10282251200 10746613760 1352459168 14.170 97 21 42 0 1367143568 9566448 5117952
15:32:54 65369 8192000000 10278350992 10737897472 1314136688 13.824 95 9 18 1 1327311840 11325136 1850016
15:35:22 101081 8192000000 10301725536 10770866176 1364750096 14.280 98 19 38 3 1395896256 26798240 4347920
15:38:04 51603 8192000000 10297862656 10743992320 1347421728 14.124 97 31 62 2 1364622640 10664192 6536720
15:40:02 57172 8192000000 10274821664 10724593664 1301209920 13.706 94 11 22 1 1313491536 10101520 2180096
15:41:59 88248 8192000000 10256748432 10721067008 1284417744 13.553 94 8 16 0 1304704768 18505552 1781472
15:44:35 72871 8192000000 10297108784 10739707904 1321444624 13.890 96 14 28 1 1336920816 12044352 3431840
15:46:49 41285 8192000000 10285511040 10728353792 1355910432 14.201 99 23 45 1 1377986432 16619440 5456560
15:48:35 79825 8192000000 10267987712 10718695424 1342202112 14.077 98 39 78 0 1366371072 15352832 8816128
15:51:19 66787 8192000000 10247042240 10700726272 1331767280 13.983 98 13 25 1 1345440176 10748608 2924288
15:53:26 58932 8192000000 10243021312 10695663616 1307414400 13.763 96 8 15 0 1321057376 11905728 1737248
15:55:27 77374 8192000000 10246319344 10699476992 1326566304 13.936 97 14 27 1 1340410832 11008144 2836384
15:58:15 28194 8192000000 10261008896 10702032896 1322574624 13.900 97 24 48 1 1335798896 6946384 6277888
15:59:44 106617 8192000000 10266677120 10727948288 1330844464 13.975 97 14 28 0 1343032768 8887808 3300496
16:02:32 42501 8192000000 10281804448 10731151360 1360237792 14.239 99 37 74 2 1382341936 12876784 9227360
16:04:15 37817 8192000000 10299046144 10739752960 1360041632 14.238 99 36 72 0 1377907456 10131168 7734656
16:05:54 55732 8192000000 10287219072 10734903296 1355048704 14.193 99 26 52 0 1372609584 12151472 5409408
16:07:59 82093 8192000000 10287932800 10741125120 1345574896 14.108 97 19 38 0 1361750528 11659760 4515872
16:10:23 153435 8192000000 10288910032 10750238720 1389196592 14.499 100 28 56 0 1407297856 11742144 6359120
16:13:56 71672 8192000000 10310991104 10778263552 1397049600 14.569 100 9 18 0 1409956720 10876816 2030304
16:16:08 51028 8192000000 10325966800 10784944128 1408928960 14.674 100 18 36 0 1424815264 11648864 4237440
16:18:26 126825 8192000000 10321037520 10796548096 1374772880 14.370 98 14 28 0 1406498448 28514544 3211024
16:21:48 62645 8192000000 10308680480 10764079104 1370182096 14.329 98 19 38 0 1386685664 12574128 3929440
16:25:08 53068 8192000000 10321400992 10780512256 1386162800 14.472 99 13 26 0 1409089072 19793984 3132288
16:27:01 122749 8192000000 10294143328 10743566336 1265733696 13.383 91 6 12 0 1282303536 15640336 929504
16:30:09 84135 8192000000 10278466544 10723340288 1309795168 13.784 95 18 36 0 1323234416 9710672 3728576
16:32:34 23044 8192000000 10254297472 10710294528 1334949552 14.012 98 26 52 1 1350422976 10013216 5460208
16:34:01 59503 8192000000 10254157184 10711867392 1337458176 14.034 98 5 10 1 1347404144 8741248 1204720
16:36:02 74676 8192000000 10257986928 10715480064 1355629424 14.198 100 15 30 0 1366756304 7837232 3289648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-11-2021 16:40:52 to 23-11-2021 16:41:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-11-2021 16:41:07 to 23-11-2021 16:41:09.

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          242056
MON$OLDEST_ACTIVE               244044
MON$OLDEST_SNAPSHOT             244044
MON$NEXT_TRANSACTION            244044
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                       2052768
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
:230: 
:231: mscs-test-161.budzdorov.ru    Tue Nov 23 16:38:26 2021
:232:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:233: 
:234: 
:235: mscs-test-161.budzdorov.ru    Tue Nov 23 16:38:30 2021
:236:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:237: 
--- end of diff output ---

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

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