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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15821808 kB
MemAvailable 31108432 kB
Buffers 0 kB
Cached 15850644 kB
SwapCached 2540 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-07-07 22:38:23
DTS_END                         2022-07-07 22:38:23.1730
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/39948
TRN_ID                          243851
ATT_ID                          691
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: 9739.73 22573 1168768
customer order: creation 176.67 353 21200
customer order: refuse 15.33 357 1839
order to supplier: creation 46.48 1292 5578
order to supplier: removal 3.59 98713 431
invoice (draft): creation 46.55 3231 5586
invoice (draft): removal 58.14 700 6977
invoice accept: apply 41.94 16752 5033
invoice accept: cancel 52.40 754 6288
customer reserve: creation 3862.37 166 463484
customer reserve: removal 5124.38 55 614925
realization accept: apply 87.12 156 10454
realization accept: cancel 91.73 98 11008
payment from customer: creation 59.48 107 7138
payment from customer: removal 5.78 63 694
payment to supplier: creation 51.98 133 6237
payment to supplier: removal 3.51 59 421
service: total inventory turnovers 4.88 998 586
service: total monetary turnovers 6.58 46 789
service: aggregate perf. data 0.80 24765 96
service: refresh index statistics 0.03 302664 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-07-2022 22:39:03 to 07-07-2022 22:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4073
TEST_TIME 2 4536
TEST_TIME 3 4139
TEST_TIME 4 3993
TEST_TIME 5 4337
TEST_TIME 6 5143
TEST_TIME 7 5844
TEST_TIME 8 5936
TEST_TIME 9 6280
TEST_TIME 10 6578
TEST_TIME 11 6589
TEST_TIME 12 7061
TEST_TIME 13 7590
TEST_TIME 14 7585
TEST_TIME 15 7783
TEST_TIME 16 7889
TEST_TIME 17 8091
TEST_TIME 18 8511
TEST_TIME 19 8751
TEST_TIME 20 8643
TEST_TIME 21 8732
TEST_TIME 22 8978
TEST_TIME 23 9182
TEST_TIME 24 9294
TEST_TIME 25 9285
TEST_TIME 26 9371
TEST_TIME 27 9374
TEST_TIME 28 10312
TEST_TIME 29 11194
TEST_TIME 30 10828
TEST_TIME 31 10760
TEST_TIME 32 10942
TEST_TIME 33 10941
TEST_TIME 34 10814
TEST_TIME 35 10778
TEST_TIME 36 10585
TEST_TIME 37 10500
TEST_TIME 38 10394
TEST_TIME 39 10301
TEST_TIME 40 10446
TEST_TIME 41 10634
TEST_TIME 42 10480
TEST_TIME 43 10399
TEST_TIME 44 10451
TEST_TIME 45 10368
TEST_TIME 46 10307
TEST_TIME 47 10337
TEST_TIME 48 10293
TEST_TIME 49 10131
TEST_TIME 50 10111
TEST_TIME 51 9997
TEST_TIME 52 9982
TEST_TIME 53 9907
TEST_TIME 54 9881
TEST_TIME 55 9868
TEST_TIME 56 9933
TEST_TIME 57 9869
TEST_TIME 58 9767
TEST_TIME 59 9710
TEST_TIME 60 9645
TEST_TIME 61 9617
TEST_TIME 62 9621
TEST_TIME 63 9600
TEST_TIME 64 9646
TEST_TIME 65 9637
TEST_TIME 66 9637
TEST_TIME 67 9621
TEST_TIME 68 9652
TEST_TIME 69 9644
TEST_TIME 70 9630
TEST_TIME 71 9795
TEST_TIME 72 9811
TEST_TIME 73 9923
TEST_TIME 74 9860
TEST_TIME 75 9915
TEST_TIME 76 9966
TEST_TIME 77 9979
TEST_TIME 78 9876
TEST_TIME 79 9818
TEST_TIME 80 9775
TEST_TIME 81 9735
TEST_TIME 82 9798
TEST_TIME 83 9718
TEST_TIME 84 9674
TEST_TIME 85 9662
TEST_TIME 86 9653
TEST_TIME 87 9632
TEST_TIME 88 9641
TEST_TIME 89 9674
TEST_TIME 90 9632
TEST_TIME 91 9693
TEST_TIME 92 9753
TEST_TIME 93 9778
TEST_TIME 94 9781
TEST_TIME 95 9821
TEST_TIME 96 9768
TEST_TIME 97 9770
TEST_TIME 98 9861
TEST_TIME 99 9898
TEST_TIME 100 9860
TEST_TIME 101 9832
TEST_TIME 102 9767
TEST_TIME 103 9781
TEST_TIME 104 9735
TEST_TIME 105 9692
TEST_TIME 106 9656
TEST_TIME 107 9608
TEST_TIME 108 9608
TEST_TIME 109 9600
TEST_TIME 110 9625
TEST_TIME 111 9628
TEST_TIME 112 9634
TEST_TIME 113 9626
TEST_TIME 114 9653
TEST_TIME 115 9647
TEST_TIME 116 9751
TEST_TIME 117 9773
TEST_TIME 118 9744
TEST_TIME 119 9819
TEST_TIME 120 9747

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-07-2022 22:39:06 to 07-07-2022 22:39:10.

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 1600354 1600354 0 0.00 0 19450 5 0 0
doc_list_biud 1600353 1600353 0 0.00 0 24597 27 0 0
sp_add_invoice_to_stock 5192 5033 159 3.06 24 170058 16753 0 159
sp_cancel_adding_invoice 6288 6288 0 0.00 4 77262 755 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 11979 358 0 0
sp_cancel_customer_reserve 614925 614925 0 0.00 0 22567 55 0 0
sp_cancel_pay_from_customer 694 694 0 0.00 3 5169 64 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 2 1644 60 0 0
sp_cancel_supplier_invoice 6988 6977 11 0.15 1 19703 700 0 11
sp_cancel_supplier_order 431 431 0 0.00 7 1101569 98714 0 0
sp_cancel_write_off 11008 11008 0 0.00 1 24608 98 0 0
sp_client_order 21200 21200 0 0.00 3 47029 354 0 0
sp_customer_reserve 463484 463484 0 0.00 0 77563 166 0 0
sp_fill_shopping_cart 32358 32358 0 0.00 1 22187 34 0 0
sp_fill_shopping_cart_clo_res 463481 463481 0 0.00 0 15154 13 0 0
sp_get_clo_for_invoice 5023 5023 0 0.00 0 14433 290 0 0
sp_kill_cost_storno 18411 18411 0 0.00 0 9544 50 0 0
sp_kill_qstorno_ret_qs2qd 1248266 1248266 0 0.00 0 24595 30 0 0
sp_kill_qty_storno 656944 656944 0 0.00 0 24596 64 0 0
sp_lock_selected_doc 21359 21359 0 0.00 0 9717 7 0 0
sp_make_cost_storno 28851 28851 0 0.00 0 13514 49 0 0
sp_multiply_rows_for_pdistr 28851 28851 0 0.00 0 8099 25 0 0
sp_multiply_rows_for_qdistr 21200 21200 0 0.00 0 47021 156 0 0
sp_pay_from_customer 7138 7138 0 0.00 4 22773 108 0 0
sp_pay_to_supplier 6237 6237 0 0.00 4 22782 134 0 0
sp_payment_common 13375 13375 0 0.00 1 9664 27 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 11948 325 0 0
sp_qd_handle_on_invoice_upd_sts 11310 11310 0 0.00 0 15789 304 0 0
sp_qd_handle_on_reserve_upd_sts 10454 10454 0 0.00 0 13607 53 0 0
sp_reserve_write_off 10454 10454 0 0.00 6 13625 157 0 0
sp_supplier_invoice 5586 5586 0 0.00 5 63316 3232 0 0
sp_supplier_order 5578 5578 0 0.00 4 27942 1293 0 0
srv_aggregate_perf_data 96 96 0 0.00 2664 516165 24765 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 926 124215 44597 0 0
srv_find_qd_qs_mism 1121408 1121408 0 0.00 0 19443 4 0 0
srv_make_invnt_saldo 586 586 0 0.00 19 26146 998 0 0
srv_make_money_saldo 789 789 0 0.00 1 10760 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 178805 530722 302664 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5033 5033 0 0.00 0 16683 191 0 0
t$perf-norm:sp_cancel_adding_invoice 379 379 0 0.00 1 11808 136 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 1863 10 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 1501 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 694 694 0 0.00 0 7131 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 1329 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 2481 19 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 20505 1647 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 7724 19 0 0
t$perf-norm:sp_client_order 21200 21200 0 0.00 0 8589 15 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 2902 8 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 6435 10 0 0
t$perf-norm:sp_pay_to_supplier 6237 6237 0 0.00 0 5271 9 0 0
t$perf-norm:sp_reserve_write_off 10454 10454 0 0.00 0 17493 14 0 0
t$perf-norm:sp_supplier_invoice 5586 5586 0 0.00 0 7243 24 0 0
t$perf-norm:sp_supplier_order 5578 5578 0 0.00 0 2974 14 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 126 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 3823 68 0 0
t$perf-norm:srv_make_invnt_saldo 586 586 0 0.00 0 1387 22 0 0
t$perf-norm:srv_make_money_saldo 789 789 0 0.00 0 3045 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 254 66 0 0
v_add_invoice_to_stock 10360 10042 318 3.06 0 3859 15 0 318
v_all_customers 38222 38222 0 0.00 0 22 1 0 0
v_all_suppliers 31710 31710 0 0.00 0 19 1 0 0
v_all_wares 42400 42400 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 758 758 0 0.00 0 1454 12 0 0
v_cancel_client_order 3678 3678 0 0.00 0 4011 5 0 0
v_cancel_customer_prepayment 1388 1388 0 0.00 0 757 3 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 2471 6 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 615 3 0 0
v_cancel_supplier_invoice 776 754 22 2.83 0 1027 15 0 22
v_cancel_supplier_order 862 862 0 0.00 0 2253 6 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2944 13 0 0
v_max_id_clo_ord 5576 5576 0 0.00 0 545 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 5635 18 0 0
v_max_id_ord_sup 5582 5582 0 0.00 0 5765 3 0 0
v_max_non_paid_invoice 1540 1540 0 0.00 0 934 3 0 0
v_max_non_paid_realizn 4014 4014 0 0.00 0 5760 6 0 0
v_min_id_clo_ord 5576 5576 0 0.00 0 2258 5 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 1328 6 0 0
v_min_id_ord_sup 5582 5582 0 0.00 0 4664 5 0 0
v_min_non_paid_invoice 6237 6237 0 0.00 0 7712 18 0 0
v_min_non_paid_realizn 7138 7138 0 0.00 0 8306 20 0 0
v_our_firm 10426 10426 0 0.00 0 17 1 0 0
v_reserve_write_off 20906 20906 0 0.00 0 3492 4 0 0
x_lock_depdocs_on_canc_invoice 12888 12888 0 0.00 0 16308 118 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 7841 321 0 0
x_make_qstorno_1000_1200 5578 5578 0 0.00 1 27882 1217 0 0
x_make_qstorno_1200_2000 5586 5586 0 0.00 2 63307 3115 0 0
x_make_qstorno_2100_3300 463484 463484 0 0.00 0 77561 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-07-2022 22:39:11 to 07-07-2022 22:39:15.

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:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:27 25179 8192000000 9917070448 10226884608 987842128 10.760 100 89 177 0 1017373328 6329152 23202048
20:10:58 16360 8192000000 9948311920 10267967488 1034064928 11.208 100 88 176 0 1065223392 8472064 22686400
20:12:20 14578 8192000000 9986857264 10319769600 1064867824 11.503 100 77 153 0 1092587024 8983232 18735968
20:13:40 13131 8192000000 10036763344 10384433152 1126922160 12.092 100 83 166 0 1156762080 10221584 19618336
20:14:56 6353 8192000000 10089851920 10449887232 1179255824 12.583 100 84 165 1 1210378592 12633072 18489696
20:16:06 4883 8192000000 10120871984 10489704448 1213818960 12.904 100 77 154 0 1243489456 12685280 16985216
20:17:12 1325 8192000000 10164415984 10537455616 1249240304 13.231 100 86 170 0 1281314032 14030032 18043696
20:18:16 1122 8192000000 10189972368 10573434880 1287158480 13.578 100 89 179 0 1319060800 12107552 19794768
20:19:18 13995 8192000000 10207659024 10599550976 1297263728 13.670 100 72 145 2 1324047808 9499936 17284144
20:20:32 4068 8192000000 10200755120 10608254976 1309658992 13.783 100 39 78 0 1326427088 7773456 8994640
20:21:37 9644 8192000000 10200318144 10617384960 1317970864 13.858 100 20 40 0 1328507552 5754080 4782608
20:22:47 13910 8192000000 10216806560 10637529088 1334628800 14.009 100 8 16 1 1341664704 5330352 1705552
20:24:06 22880 8192000000 10230250848 10649665536 1338350592 14.043 100 20 40 1 1347968096 5312016 4305488
20:25:34 6036 8192000000 10246741776 10665234432 1346502736 14.116 100 30 58 0 1363536272 10463360 6570176
20:26:40 7698 8192000000 10224301184 10649673728 1338020448 14.040 100 23 47 0 1348893536 6038048 4835040
20:27:50 18326 8192000000 10199912208 10613325824 1244528944 13.188 93 19 38 1 1254196704 4983392 4684368
20:29:09 18964 8192000000 10190702256 10600177664 1266964320 13.394 96 19 36 0 1276454528 5465472 4024736
20:30:29 5784 8192000000 10158377536 10551099392 1245596480 13.198 98 31 61 0 1259451584 7102256 6752848
20:31:38 8791 8192000000 10137544080 10535337984 1215669712 12.922 97 34 69 1 1229355824 5203456 8482656
20:32:47 77788 8192000000 10155387904 10569441280 1248677552 13.226 98 23 46 0 1258943296 5194256 5071488
20:35:05 8313 8192000000 10186171376 10592079872 1288801632 13.593 100 22 40 0 1298563936 5124512 4637792
20:36:15 5868 8192000000 10194510032 10606911488 1301290320 13.707 100 31 62 0 1314546800 5640144 7616336
20:37:24 52153 8192000000 10214273408 10628374528 1316793312 13.848 100 28 55 0 1328348096 5588352 5966432
20:39:24 31668 8192000000 10257165904 10672095232 1350909280 14.156 100 47 94 1 1369631392 7856384 10865728
20:41:01 17793 8192000000 10281303232 10700787712 1375665344 14.378 100 43 86 1 1395568800 10076032 9827424
20:42:27 14564 8192000000 10296795168 10717569024 1386916688 14.478 100 35 68 1 1407881600 13171680 7793232
20:43:52 6358 8192000000 10309191232 10729959424 1395520656 14.555 100 42 83 2 1421049488 15222624 10306208
20:45:08 10179 8192000000 10300768896 10728591360 1391506624 14.519 100 47 89 0 1417664560 15185632 10972304
20:46:29 11525 8192000000 10319055952 10748624896 1403993376 14.631 100 41 81 1 1429992192 16032336 9966480
20:47:44 7585 8192000000 10345533312 10768990208 1422506688 14.795 100 67 128 0 1454641184 17185360 14949136
20:48:55 2823 8192000000 10316303760 10736447488 1388725648 14.494 99 63 123 2 1421000032 16915936 15358448
20:50:12 5251 8192000000 10344629728 10763411456 1408593296 14.671 100 63 122 1 1451216864 28374608 14248960
20:51:22 3377 8192000000 10315614832 10732310528 1375099792 14.373 99 68 134 1 1415693312 24707232 15886288
20:52:30 1239 8192000000 10335107360 10741534720 1388375152 14.491 100 84 164 1 1439008624 30917616 19715856
20:53:38 6076 8192000000 10335568608 10748387328 1378102496 14.400 99 67 132 1 1434181728 37739472 18339760
20:55:03 5910 8192000000 10333246144 10748342272 1375674272 14.378 99 60 119 0 1433742000 42604448 15463280
20:56:13 2681 8192000000 10338613456 10746781696 1371819632 14.343 100 77 154 1 1438294784 47397072 19078080
20:57:21 924 8192000000 10332866512 10739212288 1369849136 14.326 100 77 153 0 1430686256 42083344 18753776
20:58:26 5640 8192000000 10315291440 10737397760 1370433968 14.331 100 44 83 0 1421064848 40029504 10601376
20:59:38 3553 8192000000 10320945680 10734772224 1352004656 14.166 99 67 132 2 1415217088 46557328 16655104
21:00:45 13790 8192000000 10309579248 10736865280 1363332912 14.267 100 34 68 0 1417113312 44812032 8968368
21:02:01 14208 8192000000 10318098288 10739580928 1357098320 14.211 99 36 72 1 1413073600 46263360 9711920
21:03:19 37252 8192000000 10326798384 10759143424 1381270592 14.428 100 33 66 0 1434250512 43686032 9293888
21:05:11 24535 8192000000 10317172096 10751852544 1349228064 14.141 99 27 54 0 1409805808 52469552 8108192
21:06:38 28169 8192000000 10307171888 10743169024 1327807504 13.947 97 24 48 0 1381450352 47144848 6498000
21:08:06 55095 8192000000 10289001744 10724376576 1367114992 14.301 100 32 61 2 1386373808 11075216 8183600
21:10:06 13731 8192000000 10302817728 10737569792 1378326848 14.402 100 39 76 0 1404791152 17327792 9136512
21:12:06 124215 8192000000 10287190288 10742603776 1354668368 14.189 98 17 32 0 1378001440 20483344 2849728
21:15:13 49645 8192000000 10296605568 10746376192 1387319440 14.482 100 48 98 0 1409667536 10447488 11900608
21:17:11 59548 8192000000 10319195680 10770853888 1401336032 14.607 100 18 36 0 1422750288 16475696 4938560
21:19:19 85806 8192000000 10301895344 10742710272 1367314576 14.303 98 15 31 0 1387822944 17068272 3440096
21:21:51 31524 8192000000 10327552016 10765565952 1401684688 14.610 100 34 70 0 1416110656 6252592 8173376
21:23:28 58817 8192000000 10315559600 10771845120 1410929072 14.692 100 18 36 0 1424868768 9847664 4092032
21:25:27 107826 8192000000 10312972016 10764251136 1373827728 14.361 97 13 26 1 1396020224 19260912 2931584
21:29:17 27275 8192000000 10325731968 10771066880 1389495328 14.501 98 36 70 0 1404892560 7948176 7449056
21:30:45 41051 8192000000 10286180352 10744549376 1370196448 14.329 98 18 35 0 1383585728 9497328 3891952
21:32:31 58544 8192000000 10319783648 10753306624 1370189856 14.329 97 46 90 1 1388875920 8097600 10588464
21:34:30 98753 8192000000 10273748064 10727567360 1305900608 13.749 94 15 28 1 1313740592 4849296 2990688
21:37:11 70131 8192000000 10280014848 10741911552 1362436176 14.259 98 8 16 0 1375398928 10811136 2151616
21:39:22 61706 8192000000 10275451840 10741719040 1382415936 14.438 100 13 26 0 1396841648 11778176 2647536
21:41:42 68849 8192000000 10264651280 10722971648 1298465808 13.681 94 6 12 0 1306621728 6820048 1335872
21:43:54 23103 8192000000 10256353840 10707726336 1313548144 13.818 96 13 26 0 1324253040 7218144 3486752
21:45:19 14079 8192000000 10251909904 10689277952 1302821584 13.721 97 38 75 0 1320493568 9848656 7823328
21:46:36 51915 8192000000 10258079040 10706681856 1330008160 13.967 99 18 36 0 1350910144 16781584 4120400
21:48:44 54324 8192000000 10267926640 10724175872 1326108224 13.932 98 33 66 0 1357179296 23383424 7687648
21:50:43 82676 8192000000 10279368512 10748346368 1333930960 14.003 98 8 16 0 1354371328 18928144 1512224
21:53:06 23223 8192000000 10277593328 10711269376 1328635776 13.955 98 37 74 1 1356926048 19503424 8786848
21:54:31 75422 8192000000 10258319152 10720137216 1325172032 13.924 98 4 8 0 1340044432 14162576 709824
21:56:47 69104 8192000000 10247211056 10691211264 1311930096 13.804 97 30 60 0 1328310928 9483600 6897232
21:59:05 53784 8192000000 10282736384 10726023168 1366649120 14.297 100 26 52 0 1381732944 9033904 6049920
22:00:59 57989 8192000000 10265461328 10721366016 1354293792 14.186 99 9 18 0 1365531936 9237120 2001024
22:02:57 42146 8192000000 10275535232 10727518208 1363341856 14.267 99 24 50 0 1379510208 10040816 6127536
22:04:47 101505 8192000000 10295416224 10766524416 1385087120 14.462 100 14 28 0 1407916336 18733872 4095344
22:07:32 61102 8192000000 10308205152 10760589312 1380537504 14.421 99 17 34 0 1399826064 15640864 3647696
22:09:43 106254 8192000000 10295447504 10766561280 1385630736 14.467 99 8 16 0 1404143392 16862624 1650032
22:12:30 51972 8192000000 10304856960 10769707008 1397923904 14.577 100 11 22 0 1411572560 11392512 2256144
22:14:47 23632 8192000000 10330672672 10779062272 1410420960 14.688 100 38 76 0 1434934528 15923168 8590400
22:16:17 37409 8192000000 10317383504 10783993856 1392821680 14.531 99 28 56 0 1421009648 21256128 6931840
22:17:55 57327 8192000000 10304814736 10747371520 1331806464 13.983 95 26 53 0 1345176288 7597632 5772192
22:19:58 117128 8192000000 10284362016 10737299456 1355495696 14.197 97 41 82 0 1375425456 10614848 9314912
22:24:09 48578 8192000000 10285528624 10755792896 1375179504 14.373 99 8 16 0 1388963552 11990304 1793744
22:25:58 95680 8192000000 10295937104 10760982528 1375719008 14.378 99 13 26 0 1395610608 16312032 3579568
22:28:48 55251 8192000000 10277805712 10742222848 1340850496 14.065 97 12 18 0 1352126592 9138320 2137776
22:30:45 71480 8192000000 10256419712 10712170496 1267934656 13.403 92 20 40 0 1282675568 10016416 4724496
22:32:59 25264 8192000000 10267601392 10707984384 1339612288 14.054 99 31 63 1 1358877744 12031840 7233616
22:34:25 112914 8192000000 10275395552 10731556864 1329225600 13.960 98 7 14 0 1347444160 16605600 1612960
22:37:18 72986 8192000000 9029584048 9235501056 183844320 2.194 14 1 2 0 185964272 2077600 42352

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-07-2022 22:39:17 to 07-07-2022 22:39:31.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-07-2022 22:39:32 to 07-07-2022 22:39:34.

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          244875
MON$OLDEST_ACTIVE               244876
MON$OLDEST_SNAPSHOT             244876
MON$NEXT_TRANSACTION            244876
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                       2063520
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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'

07.07.22 22:39:35. 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-07-07 22:39:38 No severe PSQL-related problems occured
07.07.22 22:39:39. End of report.