Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.06.2022 01: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 16193332 kB
MemAvailable 31370184 kB
Buffers 0 kB
Cached 15549192 kB
SwapCached 2444 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-06-05 01:38:23
DTS_END                         2022-06-05 01:38:23.2580
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/45590
TRN_ID                          244153
ATT_ID                          712
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: 9370.37 21097 1124444
customer order: creation 174.28 382 20914
customer order: refuse 14.80 373 1776
order to supplier: creation 46.48 1257 5577
order to supplier: removal 3.39 96255 407
invoice (draft): creation 46.50 3566 5580
invoice (draft): removal 54.01 780 6481
invoice accept: apply 41.64 18428 4997
invoice accept: cancel 48.25 866 5790
customer reserve: creation 3885.33 180 466239
customer reserve: removal 4753.78 53 570454
realization accept: apply 85.58 201 10269
realization accept: cancel 84.52 118 10142
payment from customer: creation 60.20 130 7224
payment from customer: removal 5.75 99 690
payment to supplier: creation 50.32 147 6038
payment to supplier: removal 3.30 88 396
service: total inventory turnovers 5.08 1011 610
service: total monetary turnovers 6.33 34 759
service: aggregate perf. data 0.81 21748 97
service: refresh index statistics 0.03 276223 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-06-2022 01:40:48 to 05-06-2022 01:40:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5666
TEST_TIME 2 3855
TEST_TIME 3 3968
TEST_TIME 4 4471
TEST_TIME 5 4811
TEST_TIME 6 5230
TEST_TIME 7 5407
TEST_TIME 8 5635
TEST_TIME 9 5488
TEST_TIME 10 6008
TEST_TIME 11 6345
TEST_TIME 12 6460
TEST_TIME 13 6729
TEST_TIME 14 7331
TEST_TIME 15 7718
TEST_TIME 16 7640
TEST_TIME 17 7800
TEST_TIME 18 8039
TEST_TIME 19 8543
TEST_TIME 20 9245
TEST_TIME 21 9117
TEST_TIME 22 9445
TEST_TIME 23 9456
TEST_TIME 24 9546
TEST_TIME 25 9591
TEST_TIME 26 9894
TEST_TIME 27 10242
TEST_TIME 28 10639
TEST_TIME 29 11002
TEST_TIME 30 10945
TEST_TIME 31 10755
TEST_TIME 32 10680
TEST_TIME 33 10794
TEST_TIME 34 10690
TEST_TIME 35 10433
TEST_TIME 36 10256
TEST_TIME 37 10301
TEST_TIME 38 10257
TEST_TIME 39 10116
TEST_TIME 40 10003
TEST_TIME 41 10055
TEST_TIME 42 9945
TEST_TIME 43 9895
TEST_TIME 44 9982
TEST_TIME 45 9958
TEST_TIME 46 9848
TEST_TIME 47 9768
TEST_TIME 48 9641
TEST_TIME 49 9710
TEST_TIME 50 9743
TEST_TIME 51 9753
TEST_TIME 52 9704
TEST_TIME 53 9750
TEST_TIME 54 9793
TEST_TIME 55 9798
TEST_TIME 56 9848
TEST_TIME 57 9819
TEST_TIME 58 9785
TEST_TIME 59 9922
TEST_TIME 60 9901
TEST_TIME 61 9986
TEST_TIME 62 9959
TEST_TIME 63 9856
TEST_TIME 64 9741
TEST_TIME 65 9806
TEST_TIME 66 9690
TEST_TIME 67 9703
TEST_TIME 68 9701
TEST_TIME 69 9617
TEST_TIME 70 9573
TEST_TIME 71 9507
TEST_TIME 72 9502
TEST_TIME 73 9511
TEST_TIME 74 9456
TEST_TIME 75 9456
TEST_TIME 76 9487
TEST_TIME 77 9493
TEST_TIME 78 9458
TEST_TIME 79 9495
TEST_TIME 80 9466
TEST_TIME 81 9495
TEST_TIME 82 9466
TEST_TIME 83 9579
TEST_TIME 84 9587
TEST_TIME 85 9677
TEST_TIME 86 9664
TEST_TIME 87 9680
TEST_TIME 88 9668
TEST_TIME 89 9665
TEST_TIME 90 9652
TEST_TIME 91 9570
TEST_TIME 92 9542
TEST_TIME 93 9536
TEST_TIME 94 9540
TEST_TIME 95 9494
TEST_TIME 96 9498
TEST_TIME 97 9468
TEST_TIME 98 9447
TEST_TIME 99 9422
TEST_TIME 100 9412
TEST_TIME 101 9403
TEST_TIME 102 9402
TEST_TIME 103 9444
TEST_TIME 104 9513
TEST_TIME 105 9482
TEST_TIME 106 9472
TEST_TIME 107 9474
TEST_TIME 108 9570
TEST_TIME 109 9534
TEST_TIME 110 9566
TEST_TIME 111 9652
TEST_TIME 112 9648
TEST_TIME 113 9566
TEST_TIME 114 9534
TEST_TIME 115 9563
TEST_TIME 116 9513
TEST_TIME 117 9468
TEST_TIME 118 9442
TEST_TIME 119 9440
TEST_TIME 120 9373

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-06-2022 01:40:52 to 05-06-2022 01:40:55.

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 1555996 1555996 0 0.00 0 21073 6 0 0
doc_list_biud 1555994 1555994 0 0.00 0 27399 25 0 0
sp_add_invoice_to_stock 5086 4997 89 1.74 45 178715 18429 0 89
sp_cancel_adding_invoice 5790 5790 0 0.00 4 57498 866 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 13827 373 0 0
sp_cancel_customer_reserve 570454 570454 0 0.00 1 27400 53 0 0
sp_cancel_pay_from_customer 690 690 0 0.00 2 6717 100 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 2 10124 88 0 0
sp_cancel_supplier_invoice 6488 6481 7 0.10 2 20070 781 0 7
sp_cancel_supplier_order 407 407 0 0.00 6 1031458 96256 0 0
sp_cancel_write_off 10142 10142 0 0.00 1 21074 118 0 0
sp_client_order 20914 20914 0 0.00 3 46052 383 0 0
sp_customer_reserve 466239 466239 0 0.00 0 110327 180 0 0
sp_fill_shopping_cart 32063 32063 0 0.00 1 25930 40 0 0
sp_fill_shopping_cart_clo_res 466235 466235 0 0.00 0 13236 13 0 0
sp_get_clo_for_invoice 4991 4991 0 0.00 0 28975 297 0 0
sp_kill_cost_storno 17018 17018 0 0.00 0 21072 56 0 0
sp_kill_qstorno_ret_qs2qd 1157938 1157938 0 0.00 0 27395 29 0 0
sp_kill_qty_storno 610309 610309 0 0.00 0 27397 64 0 0
sp_lock_selected_doc 21110 21110 0 0.00 0 7999 6 0 0
sp_make_cost_storno 28522 28522 0 0.00 0 13739 57 0 0
sp_multiply_rows_for_pdistr 28522 28522 0 0.00 0 8747 29 0 0
sp_multiply_rows_for_qdistr 20914 20914 0 0.00 0 46042 165 0 0
sp_pay_from_customer 7224 7224 0 0.00 4 29184 130 0 0
sp_pay_to_supplier 6038 6038 0 0.00 4 14039 147 0 0
sp_payment_common 13262 13262 0 0.00 2 29181 35 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 13810 328 0 0
sp_qd_handle_on_invoice_upd_sts 10780 10780 0 0.00 0 21940 333 0 0
sp_qd_handle_on_reserve_upd_sts 10269 10269 0 0.00 0 13460 58 0 0
sp_reserve_write_off 10269 10269 0 0.00 6 23058 201 0 0
sp_supplier_invoice 5580 5580 0 0.00 7 97645 3566 0 0
sp_supplier_order 5577 5577 0 0.00 5 37080 1258 0 0
srv_aggregate_perf_data 97 97 0 0.00 2756 162445 21749 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1979 180781 48457 0 0
srv_find_qd_qs_mism 1075983 1075983 0 0.00 0 18569 5 0 0
srv_make_invnt_saldo 610 610 0 0.00 40 37383 1012 0 0
srv_make_money_saldo 759 759 0 0.00 1 9599 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 206013 364107 276223 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4997 4997 0 0.00 0 9881 182 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 9186 90 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 1164 10 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 3544 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 690 690 0 0.00 0 1293 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1242 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 449 449 0 0.00 0 924 11 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 18094 1583 0 0
t$perf-norm:sp_cancel_write_off 1031 1031 0 0.00 0 5444 12 0 0
t$perf-norm:sp_client_order 20914 20914 0 0.00 0 6035 16 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 691 3 0 0
t$perf-norm:sp_pay_from_customer 7224 7224 0 0.00 0 9564 12 0 0
t$perf-norm:sp_pay_to_supplier 6038 6038 0 0.00 0 9545 12 0 0
t$perf-norm:sp_reserve_write_off 10269 10269 0 0.00 0 8332 10 0 0
t$perf-norm:sp_supplier_invoice 5580 5580 0 0.00 0 4239 18 0 0
t$perf-norm:sp_supplier_order 5577 5577 0 0.00 0 3433 14 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 644 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 17 1 0 0
t$perf-norm:srv_make_invnt_saldo 610 610 0 0.00 0 2391 21 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 3237 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10158 9980 178 1.75 0 11154 20 0 178
v_all_customers 37784 37784 0 0.00 0 139 1 0 0
v_all_suppliers 31250 31250 0 0.00 0 22 1 0 0
v_all_wares 41828 41828 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 12747 67 0 0
v_cancel_client_order 3552 3552 0 0.00 0 12576 12 0 0
v_cancel_customer_prepayment 1380 1380 0 0.00 0 3784 10 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 4117 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 5016 11 0 0
v_cancel_supplier_invoice 912 898 14 1.53 0 3294 22 0 14
v_cancel_supplier_order 814 814 0 0.00 0 13644 26 0 0
v_cancel_write_off 2062 2062 0 0.00 0 3534 15 0 0
v_max_id_clo_ord 5575 5575 0 0.00 0 286 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 1146 8 0 0
v_max_id_ord_sup 5574 5574 0 0.00 0 3984 6 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 1679 4 0 0
v_max_non_paid_realizn 3878 3878 0 0.00 0 3581 6 0 0
v_min_id_clo_ord 5575 5575 0 0.00 0 6907 10 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 9579 17 0 0
v_min_id_ord_sup 5574 5574 0 0.00 0 4903 9 0 0
v_min_non_paid_invoice 6038 6038 0 0.00 0 6605 20 0 0
v_min_non_paid_realizn 7224 7224 0 0.00 0 29178 30 0 0
v_our_firm 10736 10736 0 0.00 0 17 1 0 0
v_reserve_write_off 20538 20538 0 0.00 0 12699 13 0 0
x_lock_depdocs_on_canc_invoice 11822 11822 0 0.00 0 41239 130 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 17492 398 0 0
x_make_qstorno_1000_1200 5577 5577 0 0.00 2 36960 1173 0 0
x_make_qstorno_1200_2000 5580 5580 0 0.00 3 97488 3427 0 0
x_make_qstorno_2100_3300 466239 466239 0 0.00 0 110326 165 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 05-06-2022 01:40:56 to 05-06-2022 01: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
23:08:27 6 8192000000 8804185120 8817184768 9332080 0.113 3 1 2 0 9446416 67184 47152
23:09:27 26157 8192000000 9907185552 10215763968 985502256 10.738 100 93 186 0 1016059472 5994704 24562512
23:10:57 15390 8192000000 9947032976 10267144192 1030711200 11.175 100 88 176 0 1061816352 8098704 23006448
23:12:18 15194 8192000000 9982003376 10310922240 1065082160 11.505 100 89 178 0 1097356160 9692880 22581120
23:13:39 14913 8192000000 10029498192 10375413760 1113627008 11.967 100 77 151 1 1142440896 10633472 18180416
23:15:04 13143 8192000000 10094042592 10452811776 1177671824 12.568 100 80 160 2 1208159024 12631584 17855616
23:16:21 5659 8192000000 10139000256 10507223040 1223134832 12.991 100 90 181 0 1258066704 14656880 20274992
23:17:28 3436 8192000000 10164824864 10544164864 1251807712 13.255 100 79 157 2 1282268304 12823552 17637040
23:18:34 2461 8192000000 10202319856 10583830528 1286169296 13.569 100 94 188 1 1322248368 15179952 20899120
23:19:38 2125 8192000000 10215168288 10604802048 1305942864 13.749 100 83 166 2 1336625392 11630848 19051680
23:20:41 6897 8192000000 10195845440 10608562176 1310380736 13.790 100 46 94 0 1328360448 7308176 10671536
23:21:49 7644 8192000000 10209229680 10621231104 1320521952 13.881 100 25 50 0 1331805728 5876160 5407616
23:22:57 13739 8192000000 10209895984 10634477568 1329117472 13.959 100 16 30 2 1338286560 6134704 3034384
23:24:13 23954 8192000000 10213006608 10642763776 1335862224 14.020 100 22 42 4 1345934432 5069840 5002368
23:25:37 16482 8192000000 10232358848 10649239552 1322288384 13.897 99 23 46 1 1331599552 4961488 4349680
23:26:53 7774 8192000000 10220159712 10635075584 1305292880 13.743 98 22 43 3 1316394720 6348624 4753216
23:28:02 17633 8192000000 10212044480 10626019328 1289125728 13.596 97 9 18 1 1296464608 5102736 2236144
23:29:21 52648 8192000000 10184350448 10588106752 1267874400 13.402 98 55 110 0 1286919216 6140912 12903904
23:31:17 7723 8192000000 10142975184 10542092288 1215100512 12.916 96 32 64 2 1228962976 6462960 7399504
23:32:27 7896 8192000000 10151810384 10550951936 1251680368 13.254 100 25 48 1 1271735232 14559648 5495216
23:33:36 7271 8192000000 10170669040 10577522688 1277120432 13.487 100 30 60 0 1290212944 5749360 7343152
23:34:48 25953 8192000000 10171375408 10583789568 1282050336 13.532 100 26 52 0 1294015968 6042736 5922896
23:36:16 6556 8192000000 10200888544 10610786304 1302069248 13.714 100 38 76 0 1318009168 5986768 9953152
23:37:25 8822 8192000000 10203432976 10615771136 1311122624 13.796 100 25 50 2 1322812128 4406080 7283424
23:38:40 19115 8192000000 10242941056 10655109120 1340113440 14.058 100 41 81 0 1355379360 5170272 10095648
23:40:02 59979 8192000000 10268853344 10685153280 1360759664 14.244 100 49 98 1 1382027200 10232592 11034944
23:42:15 10568 8192000000 10287023040 10697994240 1359667792 14.234 99 59 118 1 1390238496 17178832 13391872
23:43:29 3666 8192000000 10295495168 10715103232 1377359472 14.393 100 62 124 2 1408834176 17396176 14078528
23:44:37 10350 8192000000 10309864784 10723692544 1385867632 14.469 100 60 119 1 1417473536 17656128 13949776
23:45:53 17165 8192000000 10326097056 10747346944 1399275392 14.589 100 54 108 1 1433244016 21106000 12862624
23:47:16 7414 8192000000 10328176848 10752208896 1405462992 14.644 100 52 102 2 1440454736 22139360 12852384
23:48:26 5282 8192000000 10351258496 10770575360 1413992208 14.719 100 61 122 1 1458894336 30285136 14616992
23:49:36 3769 8192000000 10333422976 10751610880 1399744912 14.593 100 62 123 1 1442280720 28137568 14398240
23:50:44 6742 8192000000 10321632928 10732060672 1377686592 14.396 99 78 156 0 1427745744 31621600 18437552
23:51:57 5816 8192000000 10333318192 10744958976 1386714784 14.477 100 71 141 1 1438095296 34520912 16859600
23:53:07 4263 8192000000 10325562432 10726981632 1374282800 14.365 99 84 165 2 1427357824 33288544 19786480
23:54:14 5182 8192000000 10319859440 10732081152 1369507184 14.323 99 66 132 0 1418062096 32023792 16531120
23:55:26 1979 8192000000 10317358928 10725552128 1367476000 14.304 100 69 137 2 1423655600 38840688 17338912
23:56:30 4372 8192000000 10344720608 10752696320 1373079008 14.355 100 74 148 0 1438770336 47177296 18514032
23:57:38 5304 8192000000 10342244592 10757189632 1380964160 14.425 100 55 110 0 1442758864 47206592 14588112
23:58:45 15940 8192000000 10307037904 10737287168 1366905952 14.299 99 29 58 2 1403091840 28431344 7754544
00:00:11 6226 8192000000 10320026400 10743341056 1375335760 14.375 100 50 98 1 1424564944 36910448 12318736
00:01:25 17716 8192000000 10300149584 10732707840 1351185648 14.158 98 22 44 0 1387525488 30462272 5877568
00:02:46 16062 8192000000 10291830928 10723270656 1359515472 14.233 100 28 57 1 1397408336 30299488 7593376
00:04:28 20074 8192000000 10284296640 10721374208 1340750464 14.064 99 21 42 0 1386090464 39229008 6110992
00:05:59 60093 8192000000 10272681696 10709491712 1332497328 13.990 98 18 36 0 1368938624 31887568 4553728
00:08:51 96012 8192000000 10277224432 10707234816 1335745856 14.019 97 27 52 2 1348826944 7654016 5427072
00:11:28 97375 8192000000 10302670336 10745073664 1375406512 14.375 99 13 25 1 1397833632 19662032 2765088
00:14:05 93461 8192000000 10303777776 10752389120 1352663728 14.171 97 27 55 2 1375353840 16018848 6671264
00:16:41 180780 8192000000 10291488224 10744221696 1330884592 13.975 95 36 72 2 1346947840 7743152 8320096
00:20:46 6330 8192000000 10324351520 10767519744 1402012672 14.613 100 52 103 2 1427619520 13115472 12491376
00:21:58 12540 8192000000 10339226800 10777870336 1413357984 14.714 100 59 114 0 1439482720 12906320 13218416
00:23:22 47476 8192000000 10308517200 10774708224 1404896176 14.639 100 12 24 1 1422629424 14144464 3588784
00:25:11 117962 8192000000 10327605744 10787627008 1413613536 14.716 100 18 36 0 1431555424 13405120 4536768
00:28:46 37509 8192000000 10323091696 10788757504 1394660448 14.547 99 18 36 1 1408397488 9906496 3830544
00:30:24 63753 8192000000 10329449152 10792607744 1416381744 14.741 100 25 50 0 1439682528 16612416 6688368
00:33:08 36249 8192000000 10300610320 10751987712 1363641184 14.270 98 24 48 0 1383131632 14108848 5381600
00:34:45 59409 8192000000 10296455328 10750537728 1365545696 14.287 99 10 20 0 1387225216 19285808 2393712
00:37:12 50122 8192000000 10263230320 10718896128 1309334384 13.780 96 13 26 0 1324928976 13028784 2565808
00:39:03 79062 8192000000 10273346960 10699030528 1248315440 13.223 91 31 62 1 1268029392 12868976 6844976
00:41:23 116234 8192000000 10249560208 10700111872 1318060000 13.859 97 40 80 0 1343613888 15768688 9785200
00:45:55 19685 8192000000 10267179888 10729840640 1355149312 14.194 99 22 46 0 1374658544 14001200 5508032
00:47:40 50998 8192000000 10283626560 10736263168 1366988160 14.300 99 19 38 1 1382940912 11274832 4677920
00:49:52 72652 8192000000 10274858304 10736971776 1354027328 14.184 98 19 38 0 1374086464 15217360 4841776
00:52:13 70716 8192000000 10266253968 10736050176 1343593088 14.090 98 13 26 0 1365982208 19618240 2770880
00:56:26 50514 8192000000 10279608128 10726318080 1347096704 14.121 98 24 48 0 1361328432 8936352 5295376
00:58:20 77845 8192000000 10294679744 10747740160 1324912608 13.921 95 19 38 1 1346150032 17198640 4038784
01:00:45 47402 8192000000 10286784384 10749341696 1350963504 14.156 98 19 38 0 1376085200 20547856 4573840
01:02:38 62558 8192000000 10295180304 10757554176 1373431680 14.358 99 23 46 1 1396574352 17746208 5396464
01:05:24 96731 8192000000 10294443680 10744365056 1354437440 14.187 98 23 46 2 1375150768 15233504 5479824
01:08:01 46645 8192000000 10306780416 10761834496 1398365600 14.580 100 34 68 0 1419418288 13752096 7300592
01:09:53 90199 8192000000 10314124944 10778869760 1388444976 14.492 99 23 46 0 1407624976 14637872 4542128
01:12:25 47278 8192000000 10303369856 10771382272 1403876192 14.629 100 12 24 0 1416304128 9577760 2850176
01:14:26 58139 8192000000 10292174896 10764062720 1398602528 14.583 100 12 24 1 1416007024 14348320 3056176
01:16:36 76663 8192000000 10288323744 10746630144 1342762144 14.082 96 21 41 0 1355181264 7283616 5135504
01:18:53 104427 8192000000 10318786000 10778365952 1397927776 14.577 100 17 34 0 1417318896 15174688 4216432
01:21:41 46125 8192000000 10303180528 10763915264 1394668752 14.548 100 24 47 2 1413805344 13719712 5416880
01:23:28 19719 8192000000 10285308448 10736443392 1374033792 14.363 99 28 55 1 1387129472 6559104 6536576
01:25:04 76067 8192000000 10279503008 10745417728 1321954880 13.894 95 18 36 0 1341841984 15693536 4193568
01:27:21 83884 8192000000 10286596192 10750562304 1341592720 14.072 97 30 60 3 1359804992 11238416 6973856
01:29:45 114620 8192000000 10278299296 10741760000 1357986288 14.219 99 6 12 0 1387684768 28470256 1228224
01:32:43 10298 8192000000 10276013168 10727600128 1341063056 14.067 98 50 100 1 1373225520 18812032 13350432
01:34:15 79338 8192000000 10245130304 10706587648 1289169056 13.597 95 8 16 1 1302619840 11897984 1552800
01:36:35 103488 8192000000 10260881552 10707263488 1305933536 13.749 96 17 34 0 1324027200 13842928 4250736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-06-2022 01:41:02 to 05-06-2022 01:41:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-06-2022 01:41:18 to 05-06-2022 01: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          240863
MON$OLDEST_ACTIVE               244305
MON$OLDEST_SNAPSHOT             244305
MON$NEXT_TRANSACTION            244305
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                       2056160
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Sun Jun  5 01:38:41 2022
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Sun Jun  5 01:38:44 2022
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sun Jun  5 01:38:44 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sun Jun  5 01:38:46 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
--- end of diff output ---

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

05.06.22 01: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-06-05 01:41:23 No severe PSQL-related problems occured
05.06.22 01:41:25. End of report.