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

Hardware and OS info

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

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.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 2021-12-13 16:38:01
DTS_END                         2021-12-13 16:38:01.2440
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/60976
TRN_ID                          236472
ATT_ID                          684
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: 9446.74 22598 1133609
customer order: creation 173.10 349 20772
customer order: refuse 14.35 347 1722
order to supplier: creation 45.80 1364 5496
order to supplier: removal 3.38 102509 406
invoice (draft): creation 45.54 3510 5465
invoice (draft): removal 53.23 754 6388
invoice accept: apply 42.65 18244 5118
invoice accept: cancel 47.99 755 5759
customer reserve: creation 3999.06 179 479887
customer reserve: removal 4723.36 58 566803
realization accept: apply 82.62 166 9914
realization accept: cancel 85.56 118 10267
payment from customer: creation 59.39 105 7127
payment from customer: removal 6.01 68 721
payment to supplier: creation 48.88 126 5865
payment to supplier: removal 3.40 73 408
service: total inventory turnovers 4.73 1013 568
service: total monetary turnovers 6.70 24 804
service: aggregate perf. data 0.96 15616 115
service: refresh index statistics 0.03 306579 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-12-2021 16:38:48 to 13-12-2021 16:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7947
TEST_TIME 2 6982
TEST_TIME 3 6338
TEST_TIME 4 5669
TEST_TIME 5 6154
TEST_TIME 6 5954
TEST_TIME 7 6128
TEST_TIME 8 6020
TEST_TIME 9 6305
TEST_TIME 10 6283
TEST_TIME 11 6416
TEST_TIME 12 6504
TEST_TIME 13 6778
TEST_TIME 14 7080
TEST_TIME 15 7278
TEST_TIME 16 7218
TEST_TIME 17 7884
TEST_TIME 18 8017
TEST_TIME 19 8682
TEST_TIME 20 9279
TEST_TIME 21 9718
TEST_TIME 22 10219
TEST_TIME 23 10712
TEST_TIME 24 10695
TEST_TIME 25 10759
TEST_TIME 26 10642
TEST_TIME 27 10650
TEST_TIME 28 10488
TEST_TIME 29 10272
TEST_TIME 30 10084
TEST_TIME 31 10051
TEST_TIME 32 10021
TEST_TIME 33 9870
TEST_TIME 34 9863
TEST_TIME 35 9761
TEST_TIME 36 9706
TEST_TIME 37 9712
TEST_TIME 38 9706
TEST_TIME 39 9605
TEST_TIME 40 9472
TEST_TIME 41 9543
TEST_TIME 42 9472
TEST_TIME 43 9930
TEST_TIME 44 9897
TEST_TIME 45 9871
TEST_TIME 46 9828
TEST_TIME 47 10024
TEST_TIME 48 10004
TEST_TIME 49 9987
TEST_TIME 50 10054
TEST_TIME 51 10169
TEST_TIME 52 10294
TEST_TIME 53 10315
TEST_TIME 54 10151
TEST_TIME 55 10056
TEST_TIME 56 9960
TEST_TIME 57 9955
TEST_TIME 58 9973
TEST_TIME 59 9899
TEST_TIME 60 9844
TEST_TIME 61 9931
TEST_TIME 62 9838
TEST_TIME 63 9754
TEST_TIME 64 9706
TEST_TIME 65 9822
TEST_TIME 66 9781
TEST_TIME 67 9742
TEST_TIME 68 9812
TEST_TIME 69 9786
TEST_TIME 70 9824
TEST_TIME 71 9853
TEST_TIME 72 9844
TEST_TIME 73 9796
TEST_TIME 74 9788
TEST_TIME 75 9944
TEST_TIME 76 9952
TEST_TIME 77 9985
TEST_TIME 78 9973
TEST_TIME 79 10008
TEST_TIME 80 9920
TEST_TIME 81 9873
TEST_TIME 82 9788
TEST_TIME 83 9779
TEST_TIME 84 9705
TEST_TIME 85 9704
TEST_TIME 86 9671
TEST_TIME 87 9589
TEST_TIME 88 9636
TEST_TIME 89 9614
TEST_TIME 90 9582
TEST_TIME 91 9583
TEST_TIME 92 9643
TEST_TIME 93 9658
TEST_TIME 94 9616
TEST_TIME 95 9716
TEST_TIME 96 9748
TEST_TIME 97 9755
TEST_TIME 98 9769
TEST_TIME 99 9758
TEST_TIME 100 9760
TEST_TIME 101 9763
TEST_TIME 102 9745
TEST_TIME 103 9820
TEST_TIME 104 9749
TEST_TIME 105 9697
TEST_TIME 106 9648
TEST_TIME 107 9602
TEST_TIME 108 9575
TEST_TIME 109 9569
TEST_TIME 110 9583
TEST_TIME 111 9573
TEST_TIME 112 9534
TEST_TIME 113 9513
TEST_TIME 114 9527
TEST_TIME 115 9566
TEST_TIME 116 9558
TEST_TIME 117 9551
TEST_TIME 118 9559
TEST_TIME 119 9534
TEST_TIME 120 9456

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-12-2021 16:38:51 to 13-12-2021 16:38: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 1577254 1577254 0 0.00 0 14694 6 0 0
doc_list_biud 1577254 1577254 0 0.00 0 52823 26 0 0
sp_add_invoice_to_stock 5237 5118 119 2.27 16 232937 18244 0 119
sp_cancel_adding_invoice 5759 5759 0 0.00 5 96376 755 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 10076 348 0 0
sp_cancel_customer_reserve 566803 566803 0 0.00 1 52824 58 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 2 4090 68 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 2734 73 0 0
sp_cancel_supplier_invoice 6396 6388 8 0.12 1 12321 755 0 8
sp_cancel_supplier_order 406 406 0 0.00 7 1166605 102509 0 0
sp_cancel_write_off 10267 10267 0 0.00 1 11542 119 0 0
sp_client_order 20772 20772 0 0.00 3 30620 349 0 0
sp_customer_reserve 479887 479887 0 0.00 0 84441 179 0 0
sp_fill_shopping_cart 31716 31716 0 0.00 1 13120 40 0 0
sp_fill_shopping_cart_clo_res 479873 479873 0 0.00 0 16414 13 0 0
sp_get_clo_for_invoice 5098 5098 0 0.00 0 14244 280 0 0
sp_kill_cost_storno 17155 17155 0 0.00 0 5400 51 0 0
sp_kill_qstorno_ret_qs2qd 1150667 1150667 0 0.00 0 52816 32 0 0
sp_kill_qty_storno 606357 606357 0 0.00 0 52822 68 0 0
sp_lock_selected_doc 20720 20720 0 0.00 0 8544 5 0 0
sp_make_cost_storno 28004 28004 0 0.00 0 14554 47 0 0
sp_multiply_rows_for_pdistr 28004 28004 0 0.00 0 6826 27 0 0
sp_multiply_rows_for_qdistr 20772 20772 0 0.00 0 15522 151 0 0
sp_pay_from_customer 7127 7127 0 0.00 4 10517 106 0 0
sp_pay_to_supplier 5865 5865 0 0.00 4 10561 126 0 0
sp_payment_common 12992 12992 0 0.00 2 9707 26 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 10068 314 0 0
sp_qd_handle_on_invoice_upd_sts 10857 10857 0 0.00 0 23308 298 0 0
sp_qd_handle_on_reserve_upd_sts 9914 9914 0 0.00 0 25504 62 0 0
sp_reserve_write_off 9914 9914 0 0.00 6 25550 167 0 0
sp_supplier_invoice 5465 5465 0 0.00 5 81791 3510 0 0
sp_supplier_order 5496 5496 0 0.00 5 33828 1365 0 0
srv_aggregate_perf_data 115 115 0 0.00 2854 143164 15617 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1305 131848 42389 0 0
srv_find_qd_qs_mism 1084758 1084758 0 0.00 0 12423 6 0 0
srv_make_invnt_saldo 568 568 0 0.00 44 27511 1014 0 0
srv_make_money_saldo 804 804 0 0.00 0 1693 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 155466 446772 306579 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 5118 5118 0 0.00 0 17489 187 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 4434 77 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 2060 11 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 934 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 2113 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 309 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 3520 21 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 19228 1589 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 2788 11 0 0
t$perf-norm:sp_client_order 20772 20772 0 0.00 0 16130 18 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 2206 7 0 0
t$perf-norm:sp_pay_from_customer 7127 7127 0 0.00 0 9709 12 0 0
t$perf-norm:sp_pay_to_supplier 5865 5865 0 0.00 0 6165 8 0 0
t$perf-norm:sp_reserve_write_off 9914 9914 0 0.00 0 20570 17 0 0
t$perf-norm:sp_supplier_invoice 5465 5465 0 0.00 0 4843 18 0 0
t$perf-norm:sp_supplier_order 5496 5496 0 0.00 0 4501 15 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 1322 21 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 3712 61 0 0
t$perf-norm:srv_make_invnt_saldo 568 568 0 0.00 0 1254 17 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 3093 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 18 7 0 0
v_add_invoice_to_stock 10430 10192 238 2.28 0 8862 19 0 238
v_all_customers 38522 38522 0 0.00 0 17 0 0 0
v_all_suppliers 30498 30498 0 0.00 0 13 1 0 0
v_all_wares 41544 41544 0 0.00 0 29 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 4888 14 0 0
v_cancel_client_order 3444 3444 0 0.00 0 3150 8 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 3974 12 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 1440 5 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 216 2 0 0
v_cancel_supplier_invoice 846 830 16 1.89 0 3139 16 0 16
v_cancel_supplier_order 812 812 0 0.00 0 3875 8 0 0
v_cancel_write_off 1896 1896 0 0.00 0 4385 17 0 0
v_max_id_clo_ord 5496 5496 0 0.00 0 609 1 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 2728 12 0 0
v_max_id_ord_sup 5448 5448 0 0.00 0 9631 7 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 1193 4 0 0
v_max_non_paid_realizn 3455 3455 0 0.00 0 3485 5 0 0
v_min_id_clo_ord 5496 5496 0 0.00 0 3907 5 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 2530 12 0 0
v_min_id_ord_sup 5448 5448 0 0.00 0 4325 6 0 0
v_min_non_paid_invoice 5865 5865 0 0.00 0 9705 21 0 0
v_min_non_paid_realizn 7127 7127 0 0.00 0 6061 19 0 0
v_our_firm 10366 10366 0 0.00 0 14 1 0 0
v_reserve_write_off 19828 19828 0 0.00 0 4247 5 0 0
x_lock_depdocs_on_canc_invoice 11732 11732 0 0.00 0 14039 108 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 2671 277 0 0
x_make_qstorno_1000_1200 5496 5496 0 0.00 2 33813 1295 0 0
x_make_qstorno_1200_2000 5465 5465 0 0.00 2 81784 3353 0 0
x_make_qstorno_2100_3300 479887 479887 0 0.00 0 84439 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-12-2021 16:38:56 to 13-12-2021 16:39:00.

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:04 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:06 33018 8192000000 9895162912 10204803072 973938768 10.625 100 87 174 0 1002588368 5767520 22882080
14:10:42 25663 8192000000 9939349232 10262519808 1021633680 11.088 100 91 181 2 1054079232 8199008 24246544
14:12:15 22312 8192000000 9977527168 10308739072 1055068912 11.409 100 86 172 0 1086236608 9780288 21387408
14:13:39 14520 8192000000 10038699552 10389286912 1123866992 12.064 100 77 154 1 1153255568 10909056 18479520
14:14:58 9311 8192000000 10076243792 10437419008 1166238560 12.462 100 74 148 0 1195151136 11778128 17134448
14:16:11 6488 8192000000 10128732784 10499411968 1213728368 12.904 100 79 158 0 1244817760 13120816 17968576
14:17:18 2588 8192000000 10164169696 10536448000 1248119312 13.221 100 94 182 1 1281715104 13766560 19829232
14:18:23 2582 8192000000 10192088192 10574098432 1277998576 13.495 100 84 168 1 1309904288 13460736 18444976
14:19:26 16430 8192000000 10215484688 10602295296 1301004048 13.704 100 88 178 1 1331669664 10230128 20435488
14:20:42 3558 8192000000 10200714720 10604781568 1304629728 13.737 100 44 88 1 1323482416 8533424 10319264
14:21:48 12454 8192000000 10197895312 10611429376 1311192752 13.797 100 19 38 1 1320552304 5109696 4249856
14:23:01 70316 8192000000 10220221024 10638163968 1320281616 13.879 100 34 70 1 1334374000 5266656 8825728
14:25:13 5949 8192000000 10219018256 10639708160 1324820112 13.920 100 22 44 2 1336583952 7032160 4731680
14:26:20 8484 8192000000 10229429136 10653061120 1334513392 14.008 100 23 46 0 1350254592 10838400 4902800
14:27:33 71297 8192000000 10207053616 10632417280 1287809520 13.584 96 28 57 0 1299772816 5532624 6430672
14:29:45 6066 8192000000 10179300464 10589548544 1258447264 13.316 96 27 54 0 1270311568 5649840 6214464
14:30:52 6699 8192000000 10154273408 10556051456 1246731552 13.208 99 33 66 2 1266050688 11900208 7418928
14:31:59 12293 8192000000 10146571312 10549321728 1243241648 13.176 99 17 34 0 1256792272 9671888 3878736
14:33:15 22618 8192000000 10136808480 10537476096 1216903568 12.933 97 33 66 3 1230487552 6484848 7099136
14:34:40 8976 8192000000 10154652080 10562183168 1265365264 13.379 100 26 52 0 1276699312 4935472 6398576
14:35:51 7230 8192000000 10182195824 10587160576 1282884400 13.539 100 38 75 2 1298048704 5070192 10094112
14:37:02 56320 8192000000 10193122592 10599501824 1293764560 13.639 100 27 54 0 1306156448 5528800 6863088
14:39:03 12151 8192000000 10229485072 10634555392 1321003056 13.886 100 54 108 1 1341552000 9441472 11107472
14:40:23 8726 8192000000 10253468320 10662301696 1337258720 14.033 100 56 112 1 1367610672 18098880 12253072
14:41:44 30426 8192000000 10271879040 10684366848 1353086864 14.175 100 55 110 0 1380089984 14449392 12553728
14:43:16 16925 8192000000 10278804528 10702200832 1367331664 14.303 100 39 78 0 1395958112 18924128 9702320
14:44:41 9326 8192000000 10293546304 10712473600 1377103664 14.391 100 45 90 0 1407561216 19379632 11077920
14:46:01 19251 8192000000 10313868784 10735718400 1392817712 14.531 100 43 87 1 1425788880 21346992 11624176
14:47:30 6766 8192000000 10324885888 10746810368 1400940688 14.603 100 51 101 0 1434563328 20890144 12732496
14:48:41 4988 8192000000 10341046464 10767388672 1414935536 14.728 100 63 126 0 1455094080 23992704 16165840
14:49:53 7845 8192000000 10360629824 10781786112 1422328432 14.793 100 71 142 2 1469169904 28892768 17948704
14:51:05 1304 8192000000 10385524640 10799714304 1431345696 14.873 100 86 172 0 1484395328 32737808 20311824
14:52:13 1906 8192000000 10391625632 10800881664 1432668960 14.885 100 89 178 2 1491527008 37121472 21736576
14:53:20 3963 8192000000 10375191088 10798690304 1429724752 14.859 100 63 124 1 1482419120 37255136 15439232
14:54:31 6732 8192000000 10354796208 10780000256 1400698976 14.601 99 47 94 0 1453794112 40017600 13077536
14:55:42 3022 8192000000 10339557936 10754621440 1385806112 14.468 100 61 119 2 1439755536 39006576 14942848
14:56:51 4348 8192000000 10341436032 10760019968 1384928432 14.461 100 58 117 0 1446284704 44907936 16448336
14:58:04 18242 8192000000 10317594656 10751696896 1370155440 14.328 100 31 62 0 1427145856 47885680 9104736
14:59:26 25220 8192000000 10290401776 10731593728 1342399744 14.079 98 27 54 0 1378579360 28999680 7179936
15:01:46 50649 8192000000 10260172496 10698432512 1302297856 13.716 96 11 22 1 1320642672 15905648 2439168
15:04:18 94822 8192000000 10247167888 10687082496 1293980144 13.640 96 21 42 0 1314888064 15699408 5208512
15:07:00 131847 8192000000 10248721936 10686259200 1292249136 13.625 96 23 46 2 1312022064 14612016 5160912
15:10:16 19708 8192000000 10271925264 10696626176 1350899536 14.156 100 58 116 2 1377263488 12932896 13431056
15:11:38 31510 8192000000 10270518352 10682417152 1307483216 13.763 96 59 117 0 1330535232 10582048 12469968
15:13:11 14869 8192000000 10280344528 10709786624 1341066080 14.067 98 40 78 3 1363615568 13285504 9263984
15:14:38 19157 8192000000 10286507504 10720247808 1365030096 14.282 100 31 63 0 1393590112 20212288 8347728
15:16:03 39612 8192000000 10284775968 10738831360 1372165584 14.346 100 19 38 1 1404970048 27977248 4827216
15:17:46 40268 8192000000 10287738224 10738188288 1366332096 14.294 99 14 28 1 1389986048 20044656 3609296
15:19:47 57948 8192000000 10287793040 10737582080 1353919088 14.183 98 15 29 0 1376355296 19229168 3207040
15:21:46 42786 8192000000 10277685776 10718593024 1344576656 14.099 98 11 22 0 1357028528 10106000 2345872
15:23:30 33541 8192000000 10301541568 10741149696 1379050320 14.408 100 25 49 0 1396225856 11411296 5764240
15:25:07 49972 8192000000 10302490256 10774331392 1387865344 14.487 100 10 20 0 1403194320 12984416 2344560
15:27:30 36755 8192000000 10322765280 10777296896 1401801200 14.611 100 25 50 1 1426754928 19275200 5678528
15:30:23 100156 8192000000 10308307408 10777354240 1396628176 14.565 100 7 14 0 1422972000 24771360 1572464
15:33:04 77905 8192000000 10322326560 10766000128 1389542240 14.502 99 38 76 1 1407501776 9270128 8689408
15:37:52 74760 8192000000 10297100784 10752593920 1373797280 14.361 98 20 40 0 1398349504 19316672 5235552
15:40:08 42156 8192000000 10265118704 10720391168 1341245632 14.069 98 12 24 0 1352384656 8580720 2558304
15:42:39 68193 8192000000 10272143392 10740637696 1343317248 14.087 97 18 36 0 1366696912 19196304 4183360
15:44:56 54354 8192000000 10276402848 10726367232 1329461664 13.962 96 20 40 1 1341975728 8426800 4087264
15:46:52 51632 8192000000 10273649248 10711814144 1275834864 13.475 93 22 45 1 1293448928 12229984 5384080
15:48:45 51649 8192000000 10270976448 10717925376 1348882384 14.137 99 22 44 0 1371120224 17577872 4659968
15:50:39 21580 8192000000 10269784160 10703552512 1331392992 13.980 98 25 51 1 1348474288 11690272 5391024
15:52:03 32578 8192000000 10292657152 10734039040 1350333648 14.150 98 39 78 1 1376847632 16943328 9570656
15:53:42 76963 8192000000 10277257952 10725199872 1342306528 14.078 98 14 28 2 1363536048 18327424 2902096
15:56:00 62021 8192000000 10277776192 10712494080 1311282592 13.798 96 30 60 2 1325659056 8250240 6126224
15:58:06 98446 8192000000 10255225696 10706055168 1341611856 14.072 99 29 58 0 1357683840 9350256 6721728
16:00:46 24333 8192000000 10247542656 10695966720 1348121952 14.131 100 30 60 1 1363396672 8674848 6599872
16:03:08 63799 8192000000 10254131792 10713374720 1342926288 14.084 99 10 20 2 1359944784 14636736 2381760
16:05:18 60389 8192000000 10261423568 10725277696 1364331056 14.276 100 17 34 0 1378298608 10227648 3739904
16:07:18 33023 8192000000 10254657824 10703290368 1347045328 14.121 99 13 26 0 1358174624 7793680 3335616
16:08:52 71198 8192000000 10283860576 10737836032 1352747632 14.172 98 26 50 0 1370686608 12582752 5356224
16:11:04 73825 8192000000 10299402672 10748035072 1365381584 14.286 98 14 28 0 1380708544 12608544 2718416
16:13:19 41904 8192000000 10295318176 10756669440 1375034272 14.372 99 10 20 1 1393700784 16186272 2480240
16:15:01 64747 8192000000 10307086880 10750464000 1368972944 14.318 98 21 43 2 1383749936 9858096 4918896
16:17:13 66623 8192000000 10325254176 10775187456 1407228576 14.659 100 24 48 0 1422894544 10702656 4963312
16:19:23 25933 8192000000 10342056976 10781544448 1410818704 14.691 100 42 84 1 1432941744 13247232 8875808
16:21:25 96110 8192000000 10292369168 10754453504 1376671392 14.387 98 15 30 1 1399509296 19623680 3214224
16:24:24 54172 8192000000 10283766928 10744221696 1361353856 14.250 97 14 28 0 1371778368 7595280 2829232
16:26:18 41350 8192000000 10293509360 10757947392 1386174656 14.472 99 18 36 0 1412218544 21678560 4365328
16:29:11 67688 8192000000 10296376304 10757492736 1400291808 14.598 100 6 11 1 1411968384 10805984 870592
16:31:19 57452 8192000000 10285784736 10738032640 1327400800 13.944 95 14 28 2 1345897328 15135424 3361104
16:33:26 62697 8192000000 10291895408 10747105280 1372960960 14.354 99 18 34 0 1384392288 7247792 4183536
16:35:35 27556 8192000000 10283863696 10730778624 1366416064 14.295 99 20 40 0 1379221584 8851072 3954448
16:37:06 38004 8192000000 10285352544 10714071040 1317277616 13.852 96 32 64 0 1333939088 9933536 6727936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 13-12-2021 16:39:02 to 13-12-2021 16:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 13-12-2021 16:39:17 to 13-12-2021 16:39:19.

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          234800
MON$OLDEST_ACTIVE               237194
MON$OLDEST_SNAPSHOT             237194
MON$NEXT_TRANSACTION            237194
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                       2016512
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.33543 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33543 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33543 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33543 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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Mon Dec 13 16:38:07 2021
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:105: 
:106: 
:107: mscs-test-161.budzdorov.ru    Mon Dec 13 16:38:07 2021
:108:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:109: 
:110: 
:111: mscs-test-161.budzdorov.ru    Mon Dec 13 16:38:10 2021
:112:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:113: 
:114: 
:115: mscs-test-161.budzdorov.ru    Mon Dec 13 16:38:10 2021
:116:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:117: 
--- end of diff output ---

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

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