Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.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 16508268 kB
MemAvailable 31346320 kB
Buffers 0 kB
Cached 15189732 kB
SwapCached 1200 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.9
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-01-04 16:38:02
DTS_END                         2022-01-04 16:38:02.6930
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38382
TRN_ID                          235236
ATT_ID                          676
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: 9253.67 23962 1110441
customer order: creation 165.43 387 19852
customer order: refuse 14.08 369 1690
order to supplier: creation 43.53 1268 5223
order to supplier: removal 3.48 101019 418
invoice (draft): creation 44.41 3636 5329
invoice (draft): removal 55.40 795 6648
invoice accept: apply 39.97 16925 4796
invoice accept: cancel 49.43 740 5932
customer reserve: creation 3727.37 165 447284
customer reserve: removal 4823.44 56 578813
realization accept: apply 77.58 169 9309
realization accept: cancel 84.39 97 10127
payment from customer: creation 57.33 122 6879
payment from customer: removal 5.60 80 672
payment to supplier: creation 47.41 132 5689
payment to supplier: removal 3.18 48 382
service: total inventory turnovers 4.75 1117 570
service: total monetary turnovers 6.13 51 735
service: aggregate perf. data 0.74 20190 89
service: refresh index statistics 0.03 331868 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-01-2022 16:39:28 to 04-01-2022 16:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4146
TEST_TIME 2 4014
TEST_TIME 3 5129
TEST_TIME 4 5876
TEST_TIME 5 5584
TEST_TIME 6 5526
TEST_TIME 7 5308
TEST_TIME 8 5800
TEST_TIME 9 5698
TEST_TIME 10 5694
TEST_TIME 11 6012
TEST_TIME 12 5946
TEST_TIME 13 6197
TEST_TIME 14 6204
TEST_TIME 15 6250
TEST_TIME 16 6435
TEST_TIME 17 6995
TEST_TIME 18 7753
TEST_TIME 19 8141
TEST_TIME 20 8471
TEST_TIME 21 8744
TEST_TIME 22 8488
TEST_TIME 23 8604
TEST_TIME 24 8847
TEST_TIME 25 9961
TEST_TIME 26 10384
TEST_TIME 27 10255
TEST_TIME 28 10362
TEST_TIME 29 10289
TEST_TIME 30 10297
TEST_TIME 31 10303
TEST_TIME 32 10281
TEST_TIME 33 10030
TEST_TIME 34 9833
TEST_TIME 35 9924
TEST_TIME 36 9914
TEST_TIME 37 9859
TEST_TIME 38 9904
TEST_TIME 39 9836
TEST_TIME 40 9766
TEST_TIME 41 9645
TEST_TIME 42 9620
TEST_TIME 43 9641
TEST_TIME 44 9637
TEST_TIME 45 9443
TEST_TIME 46 9479
TEST_TIME 47 9492
TEST_TIME 48 9336
TEST_TIME 49 9348
TEST_TIME 50 9378
TEST_TIME 51 9451
TEST_TIME 52 9377
TEST_TIME 53 9401
TEST_TIME 54 9579
TEST_TIME 55 9541
TEST_TIME 56 9550
TEST_TIME 57 9615
TEST_TIME 58 9603
TEST_TIME 59 9725
TEST_TIME 60 9719
TEST_TIME 61 9735
TEST_TIME 62 9906
TEST_TIME 63 9797
TEST_TIME 64 9665
TEST_TIME 65 9596
TEST_TIME 66 9567
TEST_TIME 67 9495
TEST_TIME 68 9464
TEST_TIME 69 9376
TEST_TIME 70 9431
TEST_TIME 71 9423
TEST_TIME 72 9434
TEST_TIME 73 9383
TEST_TIME 74 9388
TEST_TIME 75 9329
TEST_TIME 76 9432
TEST_TIME 77 9465
TEST_TIME 78 9515
TEST_TIME 79 9541
TEST_TIME 80 9540
TEST_TIME 81 9511
TEST_TIME 82 9621
TEST_TIME 83 9582
TEST_TIME 84 9574
TEST_TIME 85 9506
TEST_TIME 86 9447
TEST_TIME 87 9414
TEST_TIME 88 9402
TEST_TIME 89 9361
TEST_TIME 90 9309
TEST_TIME 91 9276
TEST_TIME 92 9283
TEST_TIME 93 9364
TEST_TIME 94 9360
TEST_TIME 95 9389
TEST_TIME 96 9385
TEST_TIME 97 9441
TEST_TIME 98 9428
TEST_TIME 99 9481
TEST_TIME 100 9464
TEST_TIME 101 9503
TEST_TIME 102 9538
TEST_TIME 103 9516
TEST_TIME 104 9483
TEST_TIME 105 9593
TEST_TIME 106 9600
TEST_TIME 107 9522
TEST_TIME 108 9472
TEST_TIME 109 9435
TEST_TIME 110 9463
TEST_TIME 111 9429
TEST_TIME 112 9398
TEST_TIME 113 9356
TEST_TIME 114 9360
TEST_TIME 115 9308
TEST_TIME 116 9321
TEST_TIME 117 9338
TEST_TIME 118 9329
TEST_TIME 119 9334
TEST_TIME 120 9256

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2022 16:39:32 to 04-01-2022 16:39:35.

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 1524707 1524707 0 0.00 0 17541 6 0 0
doc_list_biud 1524706 1524706 0 0.00 0 40416 27 0 0
sp_add_invoice_to_stock 4907 4796 111 2.26 32 193040 16926 0 111
sp_cancel_adding_invoice 5932 5932 0 0.00 6 66299 740 0 0
sp_cancel_client_order 1690 1690 0 0.00 4 8993 370 0 0
sp_cancel_customer_reserve 578813 578813 0 0.00 1 30318 57 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 7010 81 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 2 1797 49 0 0
sp_cancel_supplier_invoice 6660 6648 12 0.18 2 18033 796 0 12
sp_cancel_supplier_order 418 418 0 0.00 4 1094555 101019 0 0
sp_cancel_write_off 10127 10127 0 0.00 1 14776 97 0 0
sp_client_order 19852 19852 0 0.00 3 26479 388 0 0
sp_customer_reserve 447284 447284 0 0.00 0 44927 165 0 0
sp_fill_shopping_cart 30399 30399 0 0.00 1 12065 36 0 0
sp_fill_shopping_cart_clo_res 447283 447283 0 0.00 0 32421 15 0 0
sp_get_clo_for_invoice 4787 4787 0 0.00 0 24627 309 0 0
sp_kill_cost_storno 17113 17113 0 0.00 0 7243 52 0 0
sp_kill_qstorno_ret_qs2qd 1174819 1174819 0 0.00 0 30311 31 0 0
sp_kill_qty_storno 617724 617724 0 0.00 0 40416 66 0 0
sp_lock_selected_doc 19639 19639 0 0.00 0 8250 7 0 0
sp_make_cost_storno 26664 26664 0 0.00 0 16798 60 0 0
sp_multiply_rows_for_pdistr 26663 26663 0 0.00 0 6993 30 0 0
sp_multiply_rows_for_qdistr 19852 19852 0 0.00 0 19538 193 0 0
sp_pay_from_customer 6879 6879 0 0.00 4 13268 122 0 0
sp_pay_to_supplier 5689 5689 0 0.00 5 12995 132 0 0
sp_payment_common 12567 12567 0 0.00 1 12909 29 0 0
sp_qd_handle_on_cancel_clo 1690 1690 0 0.00 0 8986 325 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 40414 312 0 0
sp_qd_handle_on_reserve_upd_sts 9309 9309 0 0.00 0 9894 46 0 0
sp_reserve_write_off 9309 9309 0 0.00 6 11779 170 0 0
sp_supplier_invoice 5329 5329 0 0.00 6 99213 3637 0 0
sp_supplier_order 5223 5223 0 0.00 4 51516 1268 0 0
srv_aggregate_perf_data 89 89 0 0.00 3061 202381 20190 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1172 163998 48757 0 0
srv_find_qd_qs_mism 1064407 1064407 0 0.00 0 17541 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 34 56771 1117 0 0
srv_make_money_saldo 735 735 0 0.00 1 11590 51 0 0
srv_recalc_idx_stat 4 4 0 0.00 265638 439283 331868 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4796 4796 0 0.00 0 13965 163 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 2350 76 0 0
t$perf-norm:sp_cancel_client_order 1690 1690 0 0.00 0 2842 16 0 0
t$perf-norm:sp_cancel_customer_reserve 632 632 0 0.00 0 3515 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 3483 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 108 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 3299 21 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 43609 1624 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 1128 4 0 0
t$perf-norm:sp_client_order 19852 19852 0 0.00 0 9426 20 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 2803 8 0 0
t$perf-norm:sp_pay_from_customer 6879 6879 0 0.00 0 10498 10 0 0
t$perf-norm:sp_pay_to_supplier 5689 5689 0 0.00 0 8216 10 0 0
t$perf-norm:sp_reserve_write_off 9309 9309 0 0.00 0 10562 14 0 0
t$perf-norm:sp_supplier_invoice 5329 5329 0 0.00 0 4110 14 0 0
t$perf-norm:sp_supplier_order 5223 5223 0 0.00 0 1479 12 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 218 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 22 1 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 2247 25 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 4312 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 28 11 0 0
v_add_invoice_to_stock 9796 9574 222 2.26 0 6640 21 0 222
v_all_customers 36590 36590 0 0.00 0 19 0 0 0
v_all_suppliers 29452 29452 0 0.00 0 66 1 0 0
v_all_wares 39704 39704 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2544 8 0 0
v_cancel_client_order 3380 3380 0 0.00 0 2943 7 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 3527 10 0 0
v_cancel_customer_reserve 1264 1264 0 0.00 0 1985 6 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 470 4 0 0
v_cancel_supplier_invoice 840 816 24 2.85 0 960 15 0 24
v_cancel_supplier_order 836 836 0 0.00 0 993 8 0 0
v_cancel_write_off 1880 1880 0 0.00 0 2580 16 0 0
v_max_id_clo_ord 5223 5223 0 0.00 0 1121 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 2335 15 0 0
v_max_id_ord_sup 5324 5324 0 0.00 0 4516 4 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 1709 4 0 0
v_max_non_paid_realizn 3486 3486 0 0.00 0 8765 8 0 0
v_min_id_clo_ord 5223 5223 0 0.00 0 3811 5 0 0
v_min_id_clo_res 962 962 0 0.00 0 4302 8 0 0
v_min_id_ord_sup 5324 5324 0 0.00 0 5287 9 0 0
v_min_non_paid_invoice 5689 5689 0 0.00 0 6106 14 0 0
v_min_non_paid_realizn 6878 6878 0 0.00 0 12906 25 0 0
v_our_firm 9898 9898 0 0.00 0 13 1 0 0
v_reserve_write_off 18616 18616 0 0.00 0 6380 8 0 0
x_lock_depdocs_on_canc_invoice 12172 12172 0 0.00 0 26134 123 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 12055 389 0 0
x_make_qstorno_1000_1200 5223 5223 0 0.00 2 51018 1194 0 0
x_make_qstorno_1200_2000 5329 5329 0 0.00 3 99168 3505 0 0
x_make_qstorno_2100_3300 447284 447284 0 0.00 0 44926 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-01-2022 16:39:36 to 04-01-2022 16:39:40.

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:06 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:07 25259 8192000000 9897187312 10209648640 983560432 10.719 100 85 169 0 1012179952 5887952 22731568
14:10:36 22173 8192000000 9945640640 10271158272 1038862224 11.254 100 85 170 0 1068482544 7496336 22123984
14:12:05 21635 8192000000 9984695200 10317488128 1068927008 11.542 100 86 172 0 1099768432 9536864 21304560
14:13:35 13136 8192000000 10033977648 10385125376 1125633648 12.080 100 74 148 1 1154031328 10279536 18118144
14:14:54 7719 8192000000 10093474128 10457460736 1183477392 12.623 100 80 161 0 1214243472 12246528 18519552
14:16:05 7556 8192000000 10144311280 10516439040 1229397072 13.048 100 84 168 0 1261970240 13827232 18745936
14:17:14 2888 8192000000 10185786224 10567897088 1267766608 13.401 100 82 159 2 1297493984 12458160 17269216
14:18:18 1930 8192000000 10202133344 10586300416 1288639424 13.592 100 92 184 1 1323299744 13784240 20876080
14:19:21 26552 8192000000 10203080912 10607382528 1302560048 13.719 100 56 112 0 1333994592 19018256 12416288
14:20:50 4225 8192000000 10206834480 10609352704 1309785472 13.784 100 48 94 2 1326294800 6825456 9683872
14:21:56 4031 8192000000 10203314096 10620416000 1319064896 13.868 100 22 45 0 1331530368 7520768 4944704
14:23:01 6850 8192000000 10209875264 10631524352 1327383280 13.944 100 22 44 1 1338741232 6469856 4888096
14:24:10 50622 8192000000 10226763392 10647265280 1334063072 14.004 100 25 50 1 1346784784 5486000 7235712
14:26:06 5152 8192000000 10226977776 10634129408 1322515408 13.899 99 34 70 1 1336550112 5686848 8347856
14:27:12 13516 8192000000 10221905264 10646454272 1323648000 13.910 99 22 44 1 1335130128 6180336 5301792
14:28:28 9783 8192000000 10194607184 10602020864 1263078176 13.358 97 12 24 0 1271526544 5681376 2766992
14:29:39 24768 8192000000 10213499456 10601074688 1267985728 13.403 97 70 141 1 1290627184 5941040 16700416
14:31:03 6751 8192000000 10133606448 10540343296 1221650176 12.977 97 32 65 0 1239579632 10849120 7080336
14:32:12 8049 8192000000 10134298400 10527903744 1233244944 13.084 99 32 64 0 1247807936 6074256 8488736
14:33:21 56551 8192000000 10156074000 10567213056 1262468752 13.353 99 40 80 1 1277776032 5026960 10280320
14:35:18 4588 8192000000 10168412512 10580729856 1280633136 13.519 100 31 62 0 1293480336 5707392 7139808
14:36:22 7437 8192000000 10185450720 10602835968 1298358224 13.680 100 24 48 0 1309350608 5509600 5482784
14:37:30 14848 8192000000 10200353568 10617196544 1308614336 13.773 100 14 26 0 1317056416 5083920 3358160
14:38:50 17768 8192000000 10247496224 10654875648 1340293552 14.060 100 44 88 3 1358138896 7615072 10230272
14:40:11 89982 8192000000 10254901856 10679078912 1349223040 14.140 100 31 62 1 1367434928 11114624 7097264
14:42:44 37734 8192000000 10284023376 10703667200 1370580416 14.332 100 42 84 2 1392636288 11676240 10379632
14:44:27 22730 8192000000 10301982368 10729414656 1393721488 14.539 100 43 86 0 1418180656 13876528 10582640
14:46:05 24832 8192000000 10319387872 10745323520 1400154320 14.596 100 56 110 1 1433959888 20952208 12853360
14:47:39 23404 8192000000 10327897952 10760962048 1410939888 14.692 100 37 74 1 1442395712 21477408 9978416
14:49:09 11378 8192000000 10341434544 10768117760 1416145536 14.739 100 54 108 0 1455403024 25945888 13311600
14:50:28 3364 8192000000 10342314800 10765082624 1411119376 14.694 100 51 100 0 1450887088 27083776 12683936
14:51:36 2499 8192000000 10345906208 10760478720 1402686992 14.619 99 68 136 1 1447946800 29086896 16172912
14:52:50 3061 8192000000 10344595552 10749415424 1391599584 14.520 99 81 162 3 1448236000 36872368 19764048
14:53:57 6014 8192000000 10352674048 10771218432 1403201856 14.623 100 63 126 3 1460939216 40646048 17091312
14:55:11 7160 8192000000 10351761728 10764812288 1381361024 14.429 99 67 130 1 1443818048 45455984 17001040
14:56:25 1170 8192000000 10347491680 10747953152 1370818576 14.334 99 93 188 1 1441062912 47049568 23194768
14:57:34 3439 8192000000 10316333120 10725404672 1352904304 14.174 100 66 130 1 1416431360 46021568 17505488
14:58:42 3984 8192000000 10335720800 10749390848 1378710160 14.405 100 66 131 3 1433361472 37061168 17590144
14:59:48 13768 8192000000 10326946272 10761613312 1387140160 14.480 100 33 66 1 1434755056 38548528 9066368
15:01:18 18214 8192000000 10319780192 10762752000 1384101024 14.453 100 20 40 1 1427986032 38567712 5317296
15:02:40 41156 8192000000 10313699536 10756227072 1355404464 14.196 100 24 48 0 1410016368 47709280 6902624
15:04:25 47202 8192000000 10270998784 10727809024 1351282032 14.159 99 10 20 0 1369587344 15752464 2552848
15:07:09 69758 8192000000 10300613280 10738741248 1370758464 14.334 100 26 50 1 1399137936 22556032 5823440
15:09:58 55828 8192000000 10313826288 10761404416 1389722784 14.503 100 26 52 1 1411178320 15310656 6144880
15:11:55 60299 8192000000 10297218128 10738946048 1361376240 14.250 98 53 106 1 1386966096 12713856 12876000
15:14:21 56244 8192000000 10297137632 10742083584 1374451536 14.367 99 30 59 1 1391317712 9721568 7144608
15:16:19 31712 8192000000 10286456048 10741665792 1380783120 14.424 100 19 38 1 1396152832 10722112 4647600
15:17:59 28009 8192000000 10286721920 10728140800 1371166640 14.337 100 42 83 1 1390847472 10113520 9567312
15:20:23 46298 8192000000 10291061136 10752806912 1384552864 14.457 100 13 26 0 1400899056 13243280 3102912
15:22:10 50338 8192000000 10299875808 10762022912 1396200080 14.561 100 16 32 0 1411784992 11624736 3960176
15:24:03 54050 8192000000 10296393024 10764251136 1388190576 14.490 100 22 44 1 1413349456 20002544 5156336
15:25:59 35034 8192000000 10309079888 10766909440 1406993136 14.657 100 33 66 0 1422569392 7921520 7654736
15:29:25 52152 8192000000 10322827056 10780880896 1409734640 14.682 100 25 50 2 1430838688 15157584 5946464
15:31:20 54485 8192000000 10310054336 10770579456 1362700576 14.262 97 20 40 0 1382839472 14824032 5314864
15:33:15 42812 8192000000 10313285952 10763644928 1383085824 14.444 99 22 44 2 1401068576 12854080 5128672
15:34:58 45254 8192000000 10297536976 10758365184 1380587712 14.422 99 20 40 0 1400434032 15464336 4381984
15:36:50 50973 8192000000 10294240528 10750287872 1354328048 14.186 97 31 62 1 1379258768 18086384 6844336
15:39:34 33986 8192000000 10299096096 10744176640 1344434928 14.097 97 29 58 2 1363518688 12852128 6231632
15:41:08 81066 8192000000 10278262800 10736488448 1346020464 14.112 97 15 30 1 1365346640 15723216 3602960
15:44:04 104977 8192000000 10271856688 10728169472 1317939136 13.858 95 13 26 0 1338992752 18044512 3009104
15:46:49 70759 8192000000 10279143184 10734743552 1359203552 14.230 99 20 40 2 1384605664 20219520 5182592
15:49:10 71558 8192000000 10273916608 10738794496 1340501040 14.062 98 13 26 1 1363001120 19368672 3131408
15:51:29 64299 8192000000 10273214352 10724212736 1321027568 13.886 97 11 22 1 1338009552 14330160 2651824
15:53:43 67747 8192000000 10254766528 10707812352 1329854672 13.966 98 24 46 3 1351365088 16009536 5500880
15:57:03 33192 8192000000 10280659440 10716225536 1359900048 14.236 100 36 72 1 1377273872 9386080 7987744
15:58:38 57223 8192000000 10281724800 10718220288 1347858576 14.128 99 34 68 0 1369739024 14657440 7223008
16:00:39 74046 8192000000 10275342768 10716393472 1349854336 14.146 99 8 16 0 1365427328 13901248 1671744
16:02:53 133694 8192000000 10261643232 10713804800 1364117872 14.274 100 23 46 0 1376635856 6945264 5572720
16:06:07 43496 8192000000 10270302128 10729312256 1379172320 14.409 100 18 34 1 1390748944 7842096 3734528
16:08:05 59187 8192000000 10271069632 10721722368 1358570368 14.225 98 14 28 0 1374519472 12482704 3466400
16:10:06 74056 8192000000 10272300176 10747805696 1342895696 14.084 97 13 26 0 1361602288 15887360 2819232
16:12:26 41263 8192000000 10269535648 10722439168 1346066944 14.112 98 14 27 0 1358302304 9519136 2716224
16:14:07 67506 8192000000 10295901296 10750689280 1388326208 14.491 100 12 24 0 1404956976 13618784 3011984
16:16:17 68093 8192000000 10302163824 10762334208 1393427808 14.536 100 17 33 0 1409254608 12343024 3483776
16:18:46 114377 8192000000 10300557680 10769444864 1346702672 14.118 97 14 26 1 1368541376 18679488 3159216
16:21:41 37451 8192000000 10324541648 10763509760 1392711488 14.530 100 42 81 3 1411418720 9508400 9198832
16:23:24 163998 8192000000 10299359056 10767609856 1346406384 14.115 96 10 20 0 1368360368 19813152 2140832
16:27:25 67147 8192000000 10284096528 10744680448 1354125744 14.185 97 21 41 0 1367953936 8999792 4828400
16:29:35 54591 8192000000 10249618304 10700849152 1338557600 14.044 97 11 22 0 1347558032 6298640 2701792
16:32:14 48985 8192000000 10253999344 10714882048 1349521264 14.143 99 17 34 0 1365982800 11997280 4464256
16:34:22 62720 8192000000 10268754080 10731700224 1347490832 14.125 98 15 30 1 1367847184 16760928 3595424
16:36:29 69025 8192000000 10284859216 10735513600 1346340720 14.115 97 20 40 0 1363779120 12764208 4674192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-01-2022 16:39:42 to 04-01-2022 16:39:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 04-01-2022 16:39:58 to 04-01-2022 16:39:59.

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          232565
MON$OLDEST_ACTIVE               235727
MON$OLDEST_SNAPSHOT             235727
MON$NEXT_TRANSACTION            235727
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                       2032160
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:402: 
:403: mscs-test-161.budzdorov.ru    Tue Jan  4 16:38:03 2022
:404:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:405: 
:406: 
:407: mscs-test-161.budzdorov.ru    Tue Jan  4 16:38:05 2022
:408:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:409: 
:410: 
:411: mscs-test-161.budzdorov.ru    Tue Jan  4 16:38:05 2022
:412:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:413: 
:414: 
:415: mscs-test-161.budzdorov.ru    Tue Jan  4 16:38:09 2022
:416:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:417: 
:418: 
:419: mscs-test-161.budzdorov.ru    Tue Jan  4 16:38:11 2022
:420:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:421: 
--- end of diff output ---

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

04.01.22 16:40:01. 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-01-04 16:40:03 No severe PSQL-related problems occured
04.01.22 16:40:04. End of report.