Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.04.2022 22: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 16643796 kB
MemAvailable 31593440 kB
Buffers 0 kB
Cached 15104220 kB
SwapCached 2512 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.10
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-04-20 22:38:13
DTS_END                         2022-04-20 22:38:13.5460
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/35252
TRN_ID                          236036
ATT_ID                          682
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: 9218.69 25013 1106243
customer order: creation 164.74 371 19769
customer order: refuse 14.30 342 1716
order to supplier: creation 44.63 1316 5355
order to supplier: removal 3.58 100794 429
invoice (draft): creation 45.05 3493 5406
invoice (draft): removal 55.12 790 6614
invoice accept: apply 40.23 18519 4828
invoice accept: cancel 49.71 857 5965
customer reserve: creation 3741.51 183 448981
customer reserve: removal 4763.45 60 571614
realization accept: apply 82.39 169 9887
realization accept: cancel 87.73 98 10527
payment from customer: creation 58.23 118 6987
payment from customer: removal 5.68 108 681
payment to supplier: creation 47.32 167 5678
payment to supplier: removal 3.37 134 404
service: total inventory turnovers 4.42 1075 530
service: total monetary turnovers 6.33 28 759
service: aggregate perf. data 0.91 15913 109
service: refresh index statistics 0.03 355728 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-04-2022 22:40:49 to 20-04-2022 22:40:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3761
TEST_TIME 2 4801
TEST_TIME 3 5986
TEST_TIME 4 6348
TEST_TIME 5 5893
TEST_TIME 6 5739
TEST_TIME 7 5523
TEST_TIME 8 5752
TEST_TIME 9 5740
TEST_TIME 10 5976
TEST_TIME 11 6197
TEST_TIME 12 6399
TEST_TIME 13 6481
TEST_TIME 14 7134
TEST_TIME 15 7541
TEST_TIME 16 7900
TEST_TIME 17 7950
TEST_TIME 18 8471
TEST_TIME 19 8664
TEST_TIME 20 9082
TEST_TIME 21 9187
TEST_TIME 22 9543
TEST_TIME 23 9515
TEST_TIME 24 9517
TEST_TIME 25 9866
TEST_TIME 26 10293
TEST_TIME 27 10761
TEST_TIME 28 11327
TEST_TIME 29 10959
TEST_TIME 30 10694
TEST_TIME 31 10464
TEST_TIME 32 10441
TEST_TIME 33 10510
TEST_TIME 34 10383
TEST_TIME 35 10345
TEST_TIME 36 10370
TEST_TIME 37 10244
TEST_TIME 38 10075
TEST_TIME 39 9953
TEST_TIME 40 9844
TEST_TIME 41 9681
TEST_TIME 42 9780
TEST_TIME 43 9773
TEST_TIME 44 9672
TEST_TIME 45 9582
TEST_TIME 46 9532
TEST_TIME 47 9503
TEST_TIME 48 9485
TEST_TIME 49 9326
TEST_TIME 50 9280
TEST_TIME 51 9489
TEST_TIME 52 9462
TEST_TIME 53 9439
TEST_TIME 54 9538
TEST_TIME 55 9525
TEST_TIME 56 9548
TEST_TIME 57 9554
TEST_TIME 58 9497
TEST_TIME 59 9647
TEST_TIME 60 9848
TEST_TIME 61 9887
TEST_TIME 62 9943
TEST_TIME 63 9962
TEST_TIME 64 9974
TEST_TIME 65 9829
TEST_TIME 66 9747
TEST_TIME 67 9761
TEST_TIME 68 9706
TEST_TIME 69 9642
TEST_TIME 70 9618
TEST_TIME 71 9589
TEST_TIME 72 9537
TEST_TIME 73 9479
TEST_TIME 74 9467
TEST_TIME 75 9490
TEST_TIME 76 9482
TEST_TIME 77 9426
TEST_TIME 78 9514
TEST_TIME 79 9615
TEST_TIME 80 9632
TEST_TIME 81 9641
TEST_TIME 82 9575
TEST_TIME 83 9645
TEST_TIME 84 9566
TEST_TIME 85 9514
TEST_TIME 86 9478
TEST_TIME 87 9468
TEST_TIME 88 9447
TEST_TIME 89 9408
TEST_TIME 90 9376
TEST_TIME 91 9350
TEST_TIME 92 9409
TEST_TIME 93 9369
TEST_TIME 94 9332
TEST_TIME 95 9345
TEST_TIME 96 9304
TEST_TIME 97 9325
TEST_TIME 98 9337
TEST_TIME 99 9333
TEST_TIME 100 9381
TEST_TIME 101 9421
TEST_TIME 102 9407
TEST_TIME 103 9415
TEST_TIME 104 9416
TEST_TIME 105 9457
TEST_TIME 106 9493
TEST_TIME 107 9494
TEST_TIME 108 9483
TEST_TIME 109 9591
TEST_TIME 110 9528
TEST_TIME 111 9471
TEST_TIME 112 9467
TEST_TIME 113 9413
TEST_TIME 114 9401
TEST_TIME 115 9406
TEST_TIME 116 9379
TEST_TIME 117 9345
TEST_TIME 118 9308
TEST_TIME 119 9293
TEST_TIME 120 9223

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-04-2022 22:40:53 to 20-04-2022 22:40:56.

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 1521195 1521195 0 0.00 0 31591 6 0 0
doc_list_biud 1521193 1521193 0 0.00 0 32364 28 0 0
sp_add_invoice_to_stock 4935 4828 107 2.16 18 169582 18519 0 107
sp_cancel_adding_invoice 5965 5965 0 0.00 5 66670 858 0 0
sp_cancel_client_order 1716 1716 0 0.00 4 10892 342 0 0
sp_cancel_customer_reserve 571614 571614 0 0.00 1 32369 60 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 12144 109 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 3 20118 134 0 0
sp_cancel_supplier_invoice 6622 6614 8 0.12 2 29037 790 0 8
sp_cancel_supplier_order 429 429 0 0.00 7 1200405 100794 0 0
sp_cancel_write_off 10527 10527 0 0.00 1 9536 99 0 0
sp_client_order 19769 19769 0 0.00 4 24379 371 0 0
sp_customer_reserve 448981 448981 0 0.00 0 91538 183 0 0
sp_fill_shopping_cart 30518 30518 0 0.00 1 8870 37 0 0
sp_fill_shopping_cart_clo_res 448972 448972 0 0.00 0 14955 13 0 0
sp_get_clo_for_invoice 4818 4818 0 0.00 0 17747 305 0 0
sp_kill_cost_storno 17577 17577 0 0.00 0 19888 56 0 0
sp_kill_qstorno_ret_qs2qd 1160798 1160798 0 0.00 0 29024 32 0 0
sp_kill_qty_storno 611570 611570 0 0.00 0 32363 70 0 0
sp_lock_selected_doc 20446 20446 0 0.00 0 7531 5 0 0
sp_make_cost_storno 27370 27370 0 0.00 0 16739 55 0 0
sp_multiply_rows_for_pdistr 27369 27369 0 0.00 0 31583 35 0 0
sp_multiply_rows_for_qdistr 19769 19769 0 0.00 0 15100 157 0 0
sp_pay_from_customer 6987 6987 0 0.00 4 17158 118 0 0
sp_pay_to_supplier 5678 5678 0 0.00 5 31595 167 0 0
sp_payment_common 12664 12664 0 0.00 1 18780 32 0 0
sp_qd_handle_on_cancel_clo 1716 1716 0 0.00 0 10885 317 0 0
sp_qd_handle_on_invoice_upd_sts 10783 10783 0 0.00 0 22497 318 0 0
sp_qd_handle_on_reserve_upd_sts 9887 9887 0 0.00 0 26768 60 0 0
sp_reserve_write_off 9887 9887 0 0.00 6 26900 169 0 0
sp_supplier_invoice 5406 5406 0 0.00 7 91379 3493 0 0
sp_supplier_order 5355 5355 0 0.00 5 47884 1317 0 0
srv_aggregate_perf_data 109 109 0 0.00 2906 136779 15914 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2320 141990 46708 0 0
srv_find_qd_qs_mism 1059515 1059515 0 0.00 0 16175 5 0 0
srv_make_invnt_saldo 530 530 0 0.00 38 34028 1076 0 0
srv_make_money_saldo 759 759 0 0.00 0 2287 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 157682 552285 355728 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 0 11758 171 0 0
t$perf-norm:sp_cancel_adding_invoice 445 445 0 0.00 0 6810 113 0 0
t$perf-norm:sp_cancel_client_order 1716 1716 0 0.00 0 4331 15 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 868 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 2331 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 440 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 914 20 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 20216 1328 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 5767 13 0 0
t$perf-norm:sp_client_order 19769 19769 0 0.00 0 8177 18 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 4933 10 0 0
t$perf-norm:sp_pay_from_customer 6987 6987 0 0.00 0 7426 9 0 0
t$perf-norm:sp_pay_to_supplier 5678 5678 0 0.00 0 5345 9 0 0
t$perf-norm:sp_reserve_write_off 9887 9887 0 0.00 0 9555 12 0 0
t$perf-norm:sp_supplier_invoice 5406 5406 0 0.00 0 6468 21 0 0
t$perf-norm:sp_supplier_order 5355 5355 0 0.00 0 2591 16 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 229 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2717 47 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 3010 27 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 1047 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9848 9634 214 2.17 0 7095 20 0 214
v_all_customers 36296 36296 0 0.00 0 76 1 0 0
v_all_suppliers 29808 29808 0 0.00 0 15 1 0 0
v_all_wares 39538 39538 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 890 890 0 0.00 0 3391 11 0 0
v_cancel_client_order 3432 3432 0 0.00 0 2980 5 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 3555 7 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 4787 7 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 5638 11 0 0
v_cancel_supplier_invoice 798 782 16 2.00 0 925 13 0 16
v_cancel_supplier_order 858 858 0 0.00 0 4786 9 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2388 13 0 0
v_max_id_clo_ord 5354 5354 0 0.00 0 2678 2 0 0
v_max_id_clo_res 929 929 0 0.00 0 1883 14 0 0
v_max_id_ord_sup 5394 5394 0 0.00 0 4925 3 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 18773 14 0 0
v_max_non_paid_realizn 3699 3699 0 0.00 0 1990 3 0 0
v_min_id_clo_ord 5354 5354 0 0.00 0 4917 9 0 0
v_min_id_clo_res 929 929 0 0.00 0 6601 24 0 0
v_min_id_ord_sup 5394 5394 0 0.00 0 5300 8 0 0
v_min_non_paid_invoice 5678 5678 0 0.00 0 8627 26 0 0
v_min_non_paid_realizn 6986 6986 0 0.00 0 11228 24 0 0
v_our_firm 9816 9816 0 0.00 0 15 1 0 0
v_reserve_write_off 19772 19772 0 0.00 0 7118 6 0 0
x_lock_depdocs_on_canc_invoice 12188 12188 0 0.00 0 16824 114 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 18823 384 0 0
x_make_qstorno_1000_1200 5355 5355 0 0.00 2 47879 1231 0 0
x_make_qstorno_1200_2000 5406 5406 0 0.00 3 90187 3376 0 0
x_make_qstorno_2100_3300 448981 448981 0 0.00 0 91538 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-04-2022 22:40:57 to 20-04-2022 22:41:01.

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:17 8 8192000000 8803019360 8815742976 8756224 0.106 3 1 2 0 8855856 56848 42784
20:09:17 10547 8192000000 9883053616 10186932224 968059152 10.568 100 86 172 0 996394080 5262256 23072672
20:10:36 18073 8192000000 9936983488 10257022976 1022294144 11.094 100 91 182 0 1053018832 7491936 23232752
20:12:01 19430 8192000000 9974065968 10305863680 1055551120 11.414 100 79 158 1 1084261488 8975024 19735344
20:13:25 9583 8192000000 10030695200 10380341248 1120978032 12.036 100 76 152 0 1148944656 9659968 18306656
20:14:37 9585 8192000000 10093586464 10453839872 1179129456 12.582 100 78 156 1 1208181104 11779280 17272368
20:15:49 4790 8192000000 10130241824 10498396160 1216872672 12.933 100 87 174 0 1249683776 13636992 19174112
20:16:55 3580 8192000000 10163656592 10536841216 1248045056 13.220 100 83 167 3 1279977472 13458768 18473648
20:17:59 1949 8192000000 10193451696 10574946304 1277546400 13.491 100 83 166 0 1309905680 14471008 17888272
20:19:03 2152 8192000000 10215581232 10602930176 1301183632 13.706 100 90 178 2 1334445392 14012416 19249344
20:20:06 28514 8192000000 10201866224 10614751232 1313120368 13.814 100 27 54 0 1326982736 7470656 6391712
20:21:35 9261 8192000000 10199240624 10619170816 1321829984 13.893 100 19 35 2 1331882608 5865808 4186816
20:22:49 10901 8192000000 10218035376 10630860800 1328364928 13.952 100 12 24 1 1336277952 5185152 2727872
20:24:01 15177 8192000000 10212378384 10637357056 1331088080 13.977 100 24 48 0 1341446208 5085568 5272560
20:25:45 7759 8192000000 10229933520 10648989696 1333738848 14.001 100 34 64 1 1348299760 7095008 7465904
20:26:53 8754 8192000000 10212698800 10636500992 1317956368 13.858 99 19 38 2 1328255712 5497792 4801552
20:28:03 19106 8192000000 10208862864 10633797632 1297150544 13.669 97 15 30 1 1306655744 6406288 3098912
20:29:23 4045 8192000000 10184837584 10586546176 1268142560 13.405 98 28 55 1 1284542704 9347680 7052464
20:30:29 8288 8192000000 10135652704 10534694912 1192934352 12.711 95 27 53 1 1204151840 4689376 6528112
20:31:41 55541 8192000000 10127233088 10532503552 1199519632 12.772 96 29 58 0 1215215712 8994480 6701600
20:33:38 11826 8192000000 10167235616 10568077312 1267407632 13.398 100 23 46 0 1279069920 6722992 4939296
20:34:51 11421 8192000000 10175692064 10589134848 1286888352 13.576 100 17 34 0 1296703520 5583472 4231696
20:36:03 58612 8192000000 10204145632 10609688576 1298719088 13.684 100 34 68 1 1312574144 4713984 9141072
20:38:02 6927 8192000000 10217271632 10626146304 1318175760 13.860 100 28 56 0 1329764480 5704368 5884352
20:39:17 18511 8192000000 10241875136 10653310976 1337609248 14.036 100 43 85 2 1353499280 5856528 10033504
20:40:44 4658 8192000000 10284719184 10688184320 1357398944 14.214 100 62 124 0 1383601888 12428528 13774416
20:41:52 22768 8192000000 10279090912 10704261120 1369379248 14.321 100 36 72 0 1390546928 12642704 8524976
20:43:20 13614 8192000000 10297718944 10721341440 1387485440 14.483 100 44 88 1 1411245184 13596736 10163008
20:44:38 11452 8192000000 10315956080 10740801536 1398944176 14.586 100 42 82 0 1427198400 18605504 9648720
20:45:52 6648 8192000000 10326337456 10747895808 1404559840 14.636 100 59 116 0 1439146512 20169872 14416800
20:47:02 12095 8192000000 10314456432 10728497152 1379655344 14.413 98 60 120 2 1414665936 20303616 14706976
20:48:18 15042 8192000000 10342936512 10762530816 1414592208 14.725 100 56 112 1 1453094320 24223328 14278784
20:49:37 6069 8192000000 10329072272 10747158528 1386064640 14.471 99 63 124 1 1428163072 25966656 16131776
20:50:48 6356 8192000000 10345246208 10761576448 1405871952 14.647 100 72 143 0 1452930032 29294656 17763424
20:52:04 5253 8192000000 10345194928 10766610432 1402505040 14.617 100 67 132 3 1455812880 35801680 17506160
20:53:16 2328 8192000000 10374508848 10790244352 1420750800 14.779 100 80 161 1 1475829952 35366576 19712576
20:54:21 2325 8192000000 10362577136 10774327296 1405628592 14.645 100 80 160 0 1464042112 38609760 19803760
20:55:30 2320 8192000000 10351870880 10774065152 1406844304 14.656 100 53 105 0 1460963984 40633920 13485760
20:56:39 6822 8192000000 10329233632 10749911040 1376236928 14.383 100 46 91 3 1431701696 42894368 12570400
20:58:00 6828 8192000000 10316512848 10730311680 1354547152 14.188 99 55 109 0 1415773040 46697440 14528448
20:59:17 4400 8192000000 10344716928 10762153984 1381655056 14.431 100 65 130 1 1440874400 42466800 16752544
21:00:23 13132 8192000000 10326150848 10761293824 1390340256 14.509 100 27 54 2 1434476320 36939696 7196368
21:01:42 7569 8192000000 10312337104 10742112256 1352636560 14.171 98 26 52 0 1399698016 39739712 7321744
21:02:54 42248 8192000000 10336608656 10774806528 1368758800 14.316 99 22 44 2 1424960880 49460288 6741792
21:05:01 29835 8192000000 10310682800 10748461056 1339735968 14.055 98 21 42 0 1391481152 45587312 6157872
21:06:31 70978 8192000000 10272108080 10713616384 1342430672 14.079 99 18 36 1 1360974656 14733808 3810176
21:09:00 44799 8192000000 10287480928 10727821312 1364657568 14.279 99 46 92 2 1387048352 12287776 10103008
21:10:47 36257 8192000000 10288913472 10742362112 1379242592 14.410 100 28 57 2 1404799520 18796224 6760704
21:12:23 103832 8192000000 10282418864 10742550528 1372172368 14.347 99 12 24 0 1399284320 24590864 2521088
21:15:07 34294 8192000000 10287076992 10737741824 1383467440 14.448 100 27 54 0 1397767776 8098704 6201632
21:16:45 117594 8192000000 10278754304 10739826688 1361934000 14.255 98 14 28 0 1381535328 16202800 3398528
21:20:12 86156 8192000000 10283568112 10742874112 1376873792 14.389 99 16 32 0 1394585856 14160448 3551616
21:22:43 36274 8192000000 10324295808 10761744384 1390091200 14.507 100 54 109 0 1416165776 12292720 13781856
21:24:20 42421 8192000000 10291205184 10749321216 1367932288 14.309 98 19 38 2 1388953712 16477904 4543520
21:26:14 71268 8192000000 10289246896 10746048512 1378586992 14.404 99 12 24 1 1394916320 13260384 3068944
21:28:26 45760 8192000000 10282524496 10731761664 1344809568 14.101 97 31 62 0 1373210816 20791216 7610032
21:30:16 45845 8192000000 10282379664 10738454528 1367765024 14.307 99 26 52 0 1390093968 16469520 5859424
21:32:06 85325 8192000000 10300254000 10775355392 1373583024 14.359 99 16 32 0 1399575008 22605136 3386848
21:34:37 63098 8192000000 10306417504 10765258752 1390634032 14.512 100 20 40 1 1411228864 16151184 4443648
21:36:51 45432 8192000000 10314639680 10766372864 1376940960 14.389 99 37 74 0 1405889536 20334224 8614352
21:39:07 87748 8192000000 10302992640 10767630336 1380779792 14.424 100 8 16 0 1404148480 22240496 1128192
21:41:35 141989 8192000000 10311739360 10758037504 1374488192 14.367 98 15 30 0 1390010800 12167296 3355312
21:45:31 63196 8192000000 10276701296 10740486144 1359257600 14.231 98 7 14 0 1367139216 6464336 1417280
21:48:38 47780 8192000000 10274235344 10727501824 1332890480 13.993 96 12 25 0 1343842560 7979424 2972656
21:50:31 36278 8192000000 10277333936 10713620480 1332074000 13.986 98 39 77 0 1348085792 7453568 8558224
21:52:08 98766 8192000000 10256329120 10710466560 1268305408 13.406 93 16 32 0 1281057840 8680608 4071824
21:55:25 47601 8192000000 10275455360 10717556736 1357201152 14.212 100 22 44 0 1377515344 14871104 5443088
21:57:13 100861 8192000000 10292911328 10745851904 1331379664 13.980 97 17 34 1 1352245856 16560688 4305504
21:59:59 34193 8192000000 10293297632 10732089344 1371081200 14.337 100 30 60 1 1387096400 9358160 6657040
22:01:42 65561 8192000000 10277172496 10731159552 1352372800 14.169 98 30 59 1 1371789344 12741936 6674608
22:04:34 61410 8192000000 10289052416 10727481344 1343373616 14.088 97 45 90 1 1364990784 11156928 10460240
22:06:39 4271 8192000000 10296398304 10736738304 1383215488 14.445 100 45 90 2 1406072768 12941488 9915792
22:08:14 84471 8192000000 10308327744 10764443648 1403222832 14.624 100 12 24 0 1421298736 15106368 2969536
22:11:10 60893 8192000000 10288947776 10756067328 1387145792 14.480 99 10 20 2 1396865648 7567600 2152256
22:13:13 70380 8192000000 10306637328 10759983104 1390096784 14.507 99 12 24 0 1402300928 9724656 2479488
22:15:23 26276 8192000000 10310266800 10748096512 1386078416 14.471 99 33 66 0 1402502736 8644432 7779888
22:16:59 10031 8192000000 10298280192 10745929728 1390083536 14.507 100 31 62 2 1405206352 8658816 6464000
22:18:21 85542 8192000000 10295410368 10763186176 1376096592 14.382 99 15 30 1 1392381952 12816272 3469088
22:20:48 91082 8192000000 10299108720 10752036864 1322472960 13.899 95 27 54 0 1341973760 13535488 5965312
22:23:21 72317 8192000000 10321708272 10769850368 1394044400 14.542 100 18 36 0 1425988672 27774544 4169728
22:25:34 51840 8192000000 10309835632 10750476288 1354965664 14.192 97 13 26 0 1371811072 13903472 2941936
22:27:27 118134 8192000000 10289910992 10741436416 1374886928 14.371 99 14 28 0 1398644064 20374656 3382480
22:30:30 83835 8192000000 10289847440 10742087680 1375166080 14.373 99 11 22 0 1386250656 8787808 2296768
22:33:03 58156 8192000000 10296396368 10752229376 1389081760 14.498 100 7 13 0 1400280944 9372928 1826256
22:35:01 55890 8192000000 10268344384 10717962240 1325530352 13.927 96 12 23 2 1338130944 9707984 2892608
22:36:58 67746 8192000000 10256674928 10712158208 1325829872 13.929 97 17 34 1 1345836912 15869936 4137104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-04-2022 22:41:03 to 20-04-2022 22:41:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-04-2022 22:41:18 to 20-04-2022 22:41:20.

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          235643
MON$OLDEST_ACTIVE               237513
MON$OLDEST_SNAPSHOT             237513
MON$NEXT_TRANSACTION            237513
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                       2034976
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:1366: 
:1367: mscs-test-161.budzdorov.ru    Wed Apr 20 22:38:17 2022
:1368:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1369: 
:1370: 
:1371: mscs-test-161.budzdorov.ru    Wed Apr 20 22:38:54 2022
:1372:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1373: 
:1374: 
:1375: mscs-test-161.budzdorov.ru    Wed Apr 20 22:38:55 2022
:1376:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1377: 
--- end of diff output ---

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

20.04.22 22:41:21. 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-04-20 22:41:24 No severe PSQL-related problems occured
20.04.22 22:41:25. End of report.