Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.04.2022 04: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 16541952 kB
MemAvailable 31483960 kB
Buffers 20 kB
Cached 15139588 kB
SwapCached 2712 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-19 04:37:16
DTS_END                         2022-04-19 04:37:16.3280
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(68:17)
IP                              ::1/52186
TRN_ID                          214487
ATT_ID                          637
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: 8721.33 21121 1046559
customer order: creation 170.21 468 20425
customer order: refuse 14.41 367 1729
order to supplier: creation 44.18 1372 5302
order to supplier: removal 3.23 164731 387
invoice (draft): creation 44.36 2992 5323
invoice (draft): removal 48.51 973 5821
invoice accept: apply 40.37 27712 4844
invoice accept: cancel 43.79 1259 5255
customer reserve: creation 3757.07 267 450848
customer reserve: removal 4263.93 99 511671
realization accept: apply 79.91 236 9589
realization accept: cancel 84.35 185 10122
payment from customer: creation 58.48 156 7018
payment from customer: removal 5.52 107 662
payment to supplier: creation 47.32 160 5678
payment to supplier: removal 3.34 114 401
service: total inventory turnovers 3.98 3879 477
service: total monetary turnovers 7.46 358 895
service: aggregate perf. data 0.90 13231 108
service: refresh index statistics 0.03 203757 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-04-2022 04:38:49 to 19-04-2022 04:38:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5996
TEST_TIME 2 5358
TEST_TIME 3 4762
TEST_TIME 4 5453
TEST_TIME 5 5388
TEST_TIME 6 5821
TEST_TIME 7 5706
TEST_TIME 8 5629
TEST_TIME 9 5872
TEST_TIME 10 5957
TEST_TIME 11 6057
TEST_TIME 12 6341
TEST_TIME 13 6519
TEST_TIME 14 6687
TEST_TIME 15 6691
TEST_TIME 16 7239
TEST_TIME 17 7278
TEST_TIME 18 7262
TEST_TIME 19 7451
TEST_TIME 20 7702
TEST_TIME 21 7866
TEST_TIME 22 8028
TEST_TIME 23 7905
TEST_TIME 24 8303
TEST_TIME 25 9268
TEST_TIME 26 9485
TEST_TIME 27 9782
TEST_TIME 28 10328
TEST_TIME 29 10902
TEST_TIME 30 11130
TEST_TIME 31 10987
TEST_TIME 32 10920
TEST_TIME 33 10680
TEST_TIME 34 10590
TEST_TIME 35 10446
TEST_TIME 36 10417
TEST_TIME 37 10513
TEST_TIME 38 10384
TEST_TIME 39 10201
TEST_TIME 40 10089
TEST_TIME 41 9956
TEST_TIME 42 9902
TEST_TIME 43 9883
TEST_TIME 44 9938
TEST_TIME 45 9791
TEST_TIME 46 9787
TEST_TIME 47 9641
TEST_TIME 48 9645
TEST_TIME 49 9650
TEST_TIME 50 9687
TEST_TIME 51 9560
TEST_TIME 52 9537
TEST_TIME 53 9618
TEST_TIME 54 9560
TEST_TIME 55 9509
TEST_TIME 56 9468
TEST_TIME 57 9552
TEST_TIME 58 9604
TEST_TIME 59 9544
TEST_TIME 60 9551
TEST_TIME 61 9570
TEST_TIME 62 9545
TEST_TIME 63 9475
TEST_TIME 64 9378
TEST_TIME 65 9372
TEST_TIME 66 9361
TEST_TIME 67 9312
TEST_TIME 68 9230
TEST_TIME 69 9141
TEST_TIME 70 9130
TEST_TIME 71 9070
TEST_TIME 72 9096
TEST_TIME 73 9190
TEST_TIME 74 9168
TEST_TIME 75 9137
TEST_TIME 76 9122
TEST_TIME 77 9171
TEST_TIME 78 9159
TEST_TIME 79 9154
TEST_TIME 80 9100
TEST_TIME 81 9133
TEST_TIME 82 9308
TEST_TIME 83 9296
TEST_TIME 84 9293
TEST_TIME 85 9314
TEST_TIME 86 9217
TEST_TIME 87 9212
TEST_TIME 88 9178
TEST_TIME 89 9161
TEST_TIME 90 9109
TEST_TIME 91 9037
TEST_TIME 92 9101
TEST_TIME 93 9050
TEST_TIME 94 9043
TEST_TIME 95 9033
TEST_TIME 96 9024
TEST_TIME 97 8981
TEST_TIME 98 9042
TEST_TIME 99 9068
TEST_TIME 100 9029
TEST_TIME 101 9058
TEST_TIME 102 9020
TEST_TIME 103 9112
TEST_TIME 104 9179
TEST_TIME 105 9136
TEST_TIME 106 9087
TEST_TIME 107 9061
TEST_TIME 108 9031
TEST_TIME 109 8989
TEST_TIME 110 8932
TEST_TIME 111 8914
TEST_TIME 112 8898
TEST_TIME 113 8863
TEST_TIME 114 8839
TEST_TIME 115 8862
TEST_TIME 116 8825
TEST_TIME 117 8803
TEST_TIME 118 8800
TEST_TIME 119 8785
TEST_TIME 120 8742

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-04-2022 04:38:52 to 19-04-2022 04:38: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 1463366 1463366 0 0.00 0 34483 14 0 0
doc_list_biud 1463351 1463351 0 0.00 0 48733 40 0 0
sp_add_invoice_to_stock 4988 4844 144 2.88 46 216465 27713 0 144
sp_cancel_adding_invoice 5255 5255 0 0.00 7 83093 1260 0 0
sp_cancel_client_order 1729 1729 0 0.00 6 9240 368 0 0
sp_cancel_customer_reserve 511671 511671 0 0.00 1 26817 100 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 4 3575 107 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 4 2802 114 0 0
sp_cancel_supplier_invoice 5835 5821 14 0.23 3 48741 973 0 14
sp_cancel_supplier_order 387 387 0 0.00 8 1294343 164731 0 0
sp_cancel_write_off 10122 10122 0 0.00 2 21211 185 0 0
sp_client_order 20425 20425 0 0.00 5 26212 468 0 0
sp_customer_reserve 450848 450848 0 0.00 0 76336 267 0 0
sp_fill_shopping_cart 31038 31038 0 0.00 1 8834 74 0 0
sp_fill_shopping_cart_clo_res 450816 450816 0 0.00 0 21567 20 0 0
sp_get_clo_for_invoice 4804 4804 0 0.00 0 28762 262 0 0
sp_kill_cost_storno 16440 16440 0 0.00 0 34469 80 0 0
sp_kill_qstorno_ret_qs2qd 1039672 1039672 0 0.00 0 48731 44 0 0
sp_kill_qty_storno 549378 549378 0 0.00 0 48732 99 0 0
sp_lock_selected_doc 19981 19981 0 0.00 0 6204 6 0 0
sp_make_cost_storno 27089 27089 0 0.00 0 23571 73 0 0
sp_multiply_rows_for_pdistr 27089 27089 0 0.00 0 6683 34 0 0
sp_multiply_rows_for_qdistr 20425 20425 0 0.00 0 11820 202 0 0
sp_pay_from_customer 7018 7018 0 0.00 5 7850 156 0 0
sp_pay_to_supplier 5678 5678 0 0.00 6 7263 161 0 0
sp_payment_common 12695 12695 0 0.00 2 6361 39 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 9232 302 0 0
sp_qd_handle_on_invoice_upd_sts 10059 10059 0 0.00 0 27901 456 0 0
sp_qd_handle_on_reserve_upd_sts 9589 9589 0 0.00 0 10624 49 0 0
sp_reserve_write_off 9589 9589 0 0.00 9 11037 237 0 0
sp_supplier_invoice 5323 5323 0 0.00 9 112227 2993 0 0
sp_supplier_order 5302 5302 0 0.00 7 62850 1372 0 0
srv_aggregate_perf_data 108 108 0 0.00 1949 161133 13232 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 769 123953 33909 0 0
srv_find_qd_qs_mism 999508 999508 0 0.00 0 12245 11 0 0
srv_make_invnt_saldo 477 477 0 0.00 54 86877 3879 0 0
srv_make_money_saldo 895 895 0 0.00 1 18168 358 0 0
srv_recalc_idx_stat 4 4 0 0.00 100647 310538 203758 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4845 4845 0 0.00 1 20987 841 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 2 7053 461 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 5253 26 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 3090 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 4540 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 2225 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 376 376 0 0.00 0 4225 46 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 69783 6023 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 5101 30 0 0
t$perf-norm:sp_client_order 20425 20425 0 0.00 0 7460 34 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 4917 25 0 0
t$perf-norm:sp_pay_from_customer 7018 7018 0 0.00 0 11762 18 0 0
t$perf-norm:sp_pay_to_supplier 5678 5678 0 0.00 0 7436 23 0 0
t$perf-norm:sp_reserve_write_off 9589 9589 0 0.00 0 11030 25 0 0
t$perf-norm:sp_supplier_invoice 5323 5323 0 0.00 0 8715 38 0 0
t$perf-norm:sp_supplier_order 5303 5303 0 0.00 0 4496 28 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 292 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 159 6 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 1404 18 0 0
t$perf-norm:srv_make_money_saldo 895 895 0 0.00 0 3900 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 18 188 101 0 0
v_add_invoice_to_stock 9890 9602 288 2.91 0 4915 23 0 288
v_all_customers 37718 37718 0 0.00 0 119 3 0 0
v_all_suppliers 29528 29528 0 0.00 0 125 4 0 0
v_all_wares 40848 40848 0 0.00 0 139 3 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 113 12 0 0
v_cancel_client_order 3458 3458 0 0.00 0 2923 10 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 1432 12 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1294 10 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 2727 17 0 0
v_cancel_supplier_invoice 780 752 28 3.58 0 2140 23 0 28
v_cancel_supplier_order 774 774 0 0.00 0 937 11 0 0
v_cancel_write_off 1910 1910 0 0.00 0 4750 23 0 0
v_max_id_clo_ord 5302 5302 0 0.00 0 185 4 0 0
v_max_id_clo_res 933 933 0 0.00 0 634 9 0 0
v_max_id_ord_sup 5310 5310 0 0.00 0 3543 7 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 1041 8 0 0
v_max_non_paid_realizn 3437 3437 0 0.00 0 3109 9 0 0
v_min_id_clo_ord 5302 5302 0 0.00 0 5917 8 0 0
v_min_id_clo_res 933 933 0 0.00 0 5624 20 0 0
v_min_id_ord_sup 5310 5310 0 0.00 0 8229 11 0 0
v_min_non_paid_invoice 5677 5677 0 0.00 0 4746 12 0 0
v_min_non_paid_realizn 7018 7018 0 0.00 0 4032 16 0 0
v_our_firm 10292 10292 0 0.00 0 61 4 0 0
v_reserve_write_off 19178 19178 0 0.00 0 2580 8 0 0
x_lock_depdocs_on_canc_invoice 10700 10700 0 0.00 0 24407 132 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 3556 285 0 0
x_make_qstorno_1000_1200 5302 5302 0 0.00 3 62836 1229 0 0
x_make_qstorno_1200_2000 5323 5323 0 0.00 4 112180 2785 0 0
x_make_qstorno_2100_3300 450846 450846 0 0.00 0 76335 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-04-2022 04:38:57 to 19-04-2022 04:39: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
02:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 19096 8192000000 9720628816 10020216832 802678896 8.923 100 86 172 0 831815408 7916336 21220176
02:09:42 23430 8192000000 9769263904 10079522816 845660576 9.357 100 89 178 0 877067440 10470160 20936704
02:11:12 17187 8192000000 9805892496 10126086144 872658608 9.627 100 82 163 1 906423136 15135744 18628784
02:12:34 13171 8192000000 9858727408 10193895424 918438816 10.081 100 82 164 1 967555696 31400288 17716592
02:13:56 5371 8192000000 9912127456 10257879040 955470368 10.445 100 90 174 2 1020726672 47742176 17514128
02:15:05 3634 8192000000 9943917536 10297364480 991276720 10.794 100 87 174 2 1061269232 52983888 17008624
02:16:11 1722 8192000000 10014375792 10376200192 1014543632 11.019 100 94 186 0 1133608320 100726096 18338592
02:17:16 1321 8192000000 10010047312 10379653120 1045460544 11.317 100 91 176 1 1127445328 64527392 17457392
02:18:19 1200 8192000000 10036621200 10418114560 1055890960 11.417 100 93 177 2 1159530464 85618336 18021168
02:19:22 1179 8192000000 10042570528 10422583296 1067573424 11.529 100 91 179 0 1164839504 79078672 18187408
02:20:26 2315 8192000000 10028806640 10404216832 1073528272 11.586 100 88 168 1 1144985728 54570288 16887168
02:21:30 1059 8192000000 10048857456 10433974272 1080631696 11.653 100 95 184 0 1173153776 73259344 19262736
02:22:33 3812 8192000000 10059642800 10446934016 1086866832 11.713 100 82 152 0 1187031312 85424432 14740048
02:23:37 1581 8192000000 10068149472 10457141248 1089042640 11.734 100 97 187 2 1187434816 79343824 19048352
02:24:40 903 8192000000 10054113808 10438135808 1085595040 11.701 99 94 191 3 1176289024 70671888 20022096
02:25:43 1348 8192000000 10069848608 10463059968 1096135344 11.801 100 98 187 2 1195057808 80768528 18153936
02:26:45 1112 8192000000 10055720080 10440142848 1092975136 11.771 100 94 183 0 1176697344 65140112 18582096
02:27:47 1231 8192000000 10062688656 10449104896 1096800784 11.807 100 97 186 2 1183899616 68416048 18682784
02:28:49 1518 8192000000 10050150240 10446655488 1096018672 11.800 100 87 165 2 1172757040 59127664 17610704
02:29:52 1392 8192000000 10047502432 10437443584 1101712384 11.854 100 97 187 1 1170544784 48240784 20591616
02:30:55 881 8192000000 10057829392 10443444224 1082897824 11.675 100 97 180 1 1179688112 78349120 18441168
02:31:56 1128 8192000000 10014651600 10390278144 1043985904 11.303 100 95 181 1 1129379712 68206064 17187744
02:32:59 1016 8192000000 10006721936 10384236544 1045934000 11.322 100 91 173 2 1124672096 60075264 18662832
02:34:01 1447 8192000000 9989765520 10374389760 1048406416 11.345 100 85 162 2 1112705568 47286384 17012768
02:35:03 7838 8192000000 9972355520 10345492480 1032740912 11.195 99 69 127 2 1085730656 40601632 12388112
02:36:11 2705 8192000000 9998612864 10376986624 1029392656 11.163 99 79 155 1 1114412432 69226576 15793200
02:37:21 2064 8192000000 9998726192 10376683520 1055639424 11.415 100 77 137 2 1114484464 44724448 14120592
02:38:29 12764 8192000000 10025330736 10407247872 1074566352 11.596 100 71 134 0 1150870224 62737264 13566608
02:39:51 23347 8192000000 10045512576 10438569984 1094634800 11.787 100 67 132 1 1168049680 60385056 13029824
02:41:18 8435 8192000000 10102700640 10491957248 1099498896 11.833 100 68 134 0 1216525696 102998640 14028160
02:42:33 15102 8192000000 10147900864 10546163712 1117386800 12.002 100 82 157 2 1267034720 132580080 17067840
02:43:58 7247 8192000000 10198355632 10598612992 1134165728 12.161 100 74 148 0 1312113584 161626912 16320944
02:45:25 7233 8192000000 10212700624 10617159680 1141454416 12.229 100 72 140 1 1320960336 163928096 15577824
02:46:38 3036 8192000000 10239919088 10640822272 1151880208 12.327 100 68 136 1 1353864992 186408560 15576224
02:47:47 1973 8192000000 10254658336 10652266496 1150848816 12.317 100 89 177 0 1366348448 196398832 19100800
02:48:55 1585 8192000000 10293853248 10720010240 1172784896 12.523 100 75 149 1 1410013408 219966864 17261648
02:50:01 1407 8192000000 10331559984 10739507200 1180564608 12.595 100 86 167 0 1439496464 239949456 18982400
02:51:05 894 8192000000 10366562896 10772811776 1186435312 12.650 100 93 179 1 1480564624 273866816 20262496
02:52:08 1352 8192000000 10389879216 10809458688 1191648208 12.699 100 87 171 0 1496840416 285464944 19727264
02:53:15 846 8192000000 10407415680 10831736832 1200264736 12.779 100 95 186 1 1513920640 292592400 21063504
02:54:18 760 8192000000 10418551696 10823708672 1181896192 12.608 100 96 184 0 1526070720 323896048 20278480
02:55:21 1140 8192000000 10462850928 10886713344 1194207392 12.723 100 93 179 1 1576239632 360405072 21627168
02:56:25 878 8192000000 10504089696 10934587392 1206702448 12.839 100 98 194 0 1609445296 380012064 22730784
02:57:27 1155 8192000000 10513757984 10937884672 1196658928 12.745 100 93 183 3 1619632896 401026928 21947040
02:58:31 1010 8192000000 10524686336 10956611584 1185882640 12.645 100 91 181 1 1624307888 416981776 21443472
02:59:43 7642 8192000000 10548069104 10983825408 1178800128 12.579 100 51 101 0 1652768352 460518640 13449584
03:00:55 975 8192000000 10580417744 11046445056 1183788688 12.626 100 69 139 0 1681538672 479604096 18145888
03:02:16 20099 8192000000 10421884528 10893680640 1161472096 12.417 99 39 78 0 1529307024 358105216 9729712
03:03:40 36465 8192000000 10364931152 10861182976 1160496208 12.408 100 25 50 0 1494296368 327003584 6796576
03:05:17 27769 8192000000 10259769472 10804023296 1131103200 12.132 99 17 34 0 1414438768 277908096 5427472
03:07:02 51532 8192000000 10070044784 10585051136 1132388576 12.144 99 11 22 0 1203717072 68760896 2567600
03:09:48 9964 8192000000 10074807232 10494586880 1120437568 12.031 98 55 98 1 1160682784 29213376 11031840
03:11:01 119749 8192000000 10168987584 10646585344 1116008704 11.989 97 40 80 0 1211586064 86940656 8636704
03:14:41 55538 8192000000 10104472096 10532708352 1124306640 12.068 98 52 95 0 1165663760 31264288 10092832
03:16:37 35560 8192000000 10129387968 10558599168 1135837264 12.176 99 50 101 2 1206675232 59366816 11471152
03:18:14 39242 8192000000 10156190544 10594201600 1110696832 11.939 97 33 57 3 1218894224 101249696 6947696
03:20:21 63236 8192000000 10148225536 10628677632 1106317600 11.898 97 20 40 0 1261130304 149647984 5164720
03:22:59 48322 8192000000 10125313168 10608738304 1116276096 11.992 98 24 48 1 1241430992 119333792 5821104
03:24:48 59722 8192000000 10097474528 10547040256 1133770480 12.157 100 25 49 1 1202620016 63638800 5210736
03:26:56 95269 8192000000 10105745008 10615480320 1114240912 11.973 98 10 20 0 1190072096 73777264 2053920
03:29:41 50229 8192000000 10110979664 10568671232 1119426400 12.022 98 47 91 0 1183195440 53184320 10584720
03:31:31 28731 8192000000 10080898320 10510520320 1121573136 12.042 99 47 90 1 1159736528 28297696 9865696
03:33:01 63812 8192000000 10108256304 10569015296 1113879520 11.969 98 32 62 0 1218877952 97694672 7303760
03:35:07 16035 8192000000 10112475984 10555346944 1131526912 12.136 100 33 66 0 1206867024 68766544 6573568
03:36:29 6853 8192000000 10082033776 10503393280 1136128272 12.179 100 65 114 1 1170724080 21723408 12872400
03:37:38 26313 8192000000 10106380896 10561114112 1119142368 12.019 99 48 92 2 1221314480 92989648 9182464
03:39:06 27877 8192000000 10124128192 10551975936 1127663648 12.099 100 55 103 2 1205983936 67872608 10447680
03:40:40 123953 8192000000 10110135056 10589487104 1129260848 12.114 100 29 52 0 1221365152 86125392 5978912
03:43:48 23332 8192000000 10114650400 10584059904 1129219280 12.114 99 44 85 1 1204529472 66531584 8778608
03:45:21 53683 8192000000 10111544672 10559377408 1137219008 12.189 100 14 28 2 1223405856 83281888 2904960
03:48:02 63849 8192000000 10135110032 10626359296 1122265536 12.048 99 23 46 2 1250990656 123734560 4990560
03:50:13 39354 8192000000 10037111424 10484490240 1107722880 11.911 99 13 25 0 1141627872 31149584 2755408
03:51:53 83116 8192000000 10092667136 10547617792 1112377168 11.955 98 34 67 1 1178742416 59365696 6999552
03:54:30 40258 8192000000 10090830064 10533343232 1109254896 11.925 98 11 22 1 1157408560 46044112 2109552
03:56:27 43805 8192000000 10127381200 10563366912 1137353936 12.191 100 25 49 1 1218293328 75215920 5723472
03:58:16 44461 8192000000 10193519696 10640842752 1135685888 12.175 99 33 66 1 1284623952 141691328 7246736
04:00:09 64148 8192000000 10111417904 10563645440 1126388272 12.087 99 20 40 0 1218318288 87780656 4149360
04:02:17 44368 8192000000 10096571952 10562801664 1132579040 12.146 99 33 66 0 1211136832 72133120 6424672
04:04:24 57907 8192000000 10055808192 10535743488 1105857216 11.893 97 11 22 0 1145943792 37886240 2200336
04:06:23 47511 8192000000 10053174752 10523844608 1107168816 11.906 98 10 20 1 1136844816 27467584 2208416
04:08:11 63936 8192000000 10091614624 10575765504 1125949440 12.083 99 14 28 0 1201507824 72543888 3014496
04:10:29 48241 8192000000 10099203024 10547433472 1131631568 12.137 99 28 54 1 1198111200 60586448 5893184
04:12:18 22523 8192000000 10132852176 10570055680 1143255744 12.246 100 37 70 1 1223849696 72459904 8134048
04:13:45 38210 8192000000 10144748224 10585231360 1133322448 12.153 99 35 70 0 1235444864 93914816 8207600
04:15:31 43170 8192000000 10148317104 10647842816 1137153200 12.189 99 22 44 0 1275781104 133537232 5090672
04:17:16 27516 8192000000 10132460992 10562473984 1104126480 11.877 96 40 79 1 1206593792 94155392 8311920
04:18:46 31569 8192000000 10156030608 10619682816 1083938288 11.685 95 37 74 0 1207084080 115317808 7827984
04:20:18 27597 8192000000 10133640384 10603958272 1132386512 12.144 100 40 80 1 1227805152 87269440 8149200
04:21:47 19289 8192000000 10068521536 10481864704 1095682912 11.797 98 53 98 1 1155132656 49894176 9555568
04:23:06 71633 8192000000 10093599136 10531528704 1118711232 12.015 100 69 133 0 1189841312 57583856 13546224
04:25:19 53031 8192000000 10034429088 10490105856 1112642720 11.957 99 22 44 1 1138763712 21376960 4744032
04:27:24 82578 8192000000 10059015120 10545975296 1133091424 12.150 100 10 20 0 1179104480 44338128 1674928
04:29:46 46229 8192000000 10083353168 10529538048 1110028512 11.933 98 22 43 1 1165053536 49868768 5156256
04:31:38 23868 8192000000 10106700880 10545516544 1108883728 11.922 98 40 78 2 1192662192 75631696 8146768
04:33:05 25058 8192000000 10137388576 10573467648 1135811088 12.176 99 31 62 0 1224017616 81223280 6983248
04:34:38 28404 8192000000 10184109888 10631348224 1143221280 12.246 100 39 77 2 1281458128 129528544 8708304
04:36:14 41545 8192000000 10189693296 10626441216 1130683616 12.128 99 31 62 0 1275715280 137699888 7331776

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-04-2022 04:39:03 to 19-04-2022 04:39:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          209412
MON$OLDEST_ACTIVE               217713
MON$OLDEST_SNAPSHOT             217713
MON$NEXT_TRANSACTION            217713
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-30 07:30:08.6030 GMT
MON$PAGES                       2034144
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:317: 
:318: mscs-test-161.budzdorov.ru    Tue Apr 19 04:37:21 2022
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:320: 
:321: 
:322: mscs-test-161.budzdorov.ru    Tue Apr 19 04:37:26 2022
:323:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:324: 
--- end of diff output ---

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

19.04.22 04:39:23. There are 3 logs in /var/tmp/logs-oltp40 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-19 04:39:25 No severe PSQL-related problems occured
19.04.22 04:39:26. End of report.