Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.2021 13: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15841096 kB
MemAvailable 30662248 kB
Buffers 0 kB
Cached 15880848 kB
SwapCached 3716 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.1
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 2021-10-19 13:37:07
DTS_END                         2021-10-19 13:37:07.7010
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/36642
TRN_ID                          224551
ATT_ID                          570
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: 8814.66 24211 1057759
customer order: creation 161.98 431 19437
customer order: refuse 13.60 381 1632
order to supplier: creation 42.95 1475 5154
order to supplier: removal 3.13 135727 375
invoice (draft): creation 43.54 3685 5225
invoice (draft): removal 49.84 1111 5981
invoice accept: apply 40.38 19928 4845
invoice accept: cancel 45.58 967 5469
customer reserve: creation 3722.77 195 446732
customer reserve: removal 4413.41 75 529609
realization accept: apply 78.56 219 9427
realization accept: cancel 76.83 138 9219
payment from customer: creation 56.23 140 6748
payment from customer: removal 5.28 110 634
payment to supplier: creation 46.50 171 5580
payment to supplier: removal 3.50 134 420
service: total inventory turnovers 3.80 3908 456
service: total monetary turnovers 5.92 530 710
service: aggregate perf. data 0.84 17072 101
service: refresh index statistics 0.04 297820 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2021 13:38:41 to 19-10-2021 13:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6915
TEST_TIME 2 4506
TEST_TIME 3 4001
TEST_TIME 4 4266
TEST_TIME 5 4430
TEST_TIME 6 4477
TEST_TIME 7 4420
TEST_TIME 8 4430
TEST_TIME 9 4590
TEST_TIME 10 4770
TEST_TIME 11 4975
TEST_TIME 12 4965
TEST_TIME 13 5223
TEST_TIME 14 5459
TEST_TIME 15 5479
TEST_TIME 16 5986
TEST_TIME 17 6133
TEST_TIME 18 6648
TEST_TIME 19 7032
TEST_TIME 20 7648
TEST_TIME 21 7559
TEST_TIME 22 7853
TEST_TIME 23 7833
TEST_TIME 24 8085
TEST_TIME 25 8384
TEST_TIME 26 8530
TEST_TIME 27 9415
TEST_TIME 28 10198
TEST_TIME 29 10390
TEST_TIME 30 10217
TEST_TIME 31 10157
TEST_TIME 32 9965
TEST_TIME 33 10137
TEST_TIME 34 10356
TEST_TIME 35 10420
TEST_TIME 36 10135
TEST_TIME 37 9925
TEST_TIME 38 9872
TEST_TIME 39 9731
TEST_TIME 40 9687
TEST_TIME 41 9796
TEST_TIME 42 9688
TEST_TIME 43 9694
TEST_TIME 44 9661
TEST_TIME 45 9633
TEST_TIME 46 9553
TEST_TIME 47 9547
TEST_TIME 48 9426
TEST_TIME 49 9378
TEST_TIME 50 9414
TEST_TIME 51 9399
TEST_TIME 52 9392
TEST_TIME 53 9414
TEST_TIME 54 9310
TEST_TIME 55 9282
TEST_TIME 56 9287
TEST_TIME 57 9307
TEST_TIME 58 9386
TEST_TIME 59 9355
TEST_TIME 60 9415
TEST_TIME 61 9505
TEST_TIME 62 9519
TEST_TIME 63 9448
TEST_TIME 64 9556
TEST_TIME 65 9493
TEST_TIME 66 9413
TEST_TIME 67 9302
TEST_TIME 68 9217
TEST_TIME 69 9223
TEST_TIME 70 9127
TEST_TIME 71 9217
TEST_TIME 72 9202
TEST_TIME 73 9165
TEST_TIME 74 9124
TEST_TIME 75 9035
TEST_TIME 76 8997
TEST_TIME 77 8937
TEST_TIME 78 8889
TEST_TIME 79 8874
TEST_TIME 80 8881
TEST_TIME 81 8904
TEST_TIME 82 8917
TEST_TIME 83 8920
TEST_TIME 84 8901
TEST_TIME 85 8916
TEST_TIME 86 8999
TEST_TIME 87 8959
TEST_TIME 88 8982
TEST_TIME 89 8979
TEST_TIME 90 8900
TEST_TIME 91 8842
TEST_TIME 92 8916
TEST_TIME 93 8856
TEST_TIME 94 8875
TEST_TIME 95 8850
TEST_TIME 96 8854
TEST_TIME 97 8825
TEST_TIME 98 8899
TEST_TIME 99 8929
TEST_TIME 100 8927
TEST_TIME 101 8963
TEST_TIME 102 8925
TEST_TIME 103 8901
TEST_TIME 104 9028
TEST_TIME 105 9046
TEST_TIME 106 9083
TEST_TIME 107 9095
TEST_TIME 108 9028
TEST_TIME 109 8995
TEST_TIME 110 8952
TEST_TIME 111 8939
TEST_TIME 112 8904
TEST_TIME 113 8896
TEST_TIME 114 8893
TEST_TIME 115 8906
TEST_TIME 116 8872
TEST_TIME 117 8839
TEST_TIME 118 8873
TEST_TIME 119 8884
TEST_TIME 120 8817

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-10-2021 13:38:44 to 19-10-2021 13:38:48.

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 1468835 1468835 0 0.00 0 27037 7 0 0
doc_list_biud 1468835 1468835 0 0.00 0 92361 35 0 0
sp_add_invoice_to_stock 4910 4845 65 1.32 24 197326 19929 0 65
sp_cancel_adding_invoice 5469 5469 0 0.00 7 74591 967 0 0
sp_cancel_client_order 1632 1632 0 0.00 5 8582 382 0 0
sp_cancel_customer_reserve 529609 529609 0 0.00 1 26856 75 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 5298 110 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 18872 134 0 0
sp_cancel_supplier_invoice 5988 5981 7 0.11 2 92460 1112 0 7
sp_cancel_supplier_order 375 375 0 0.00 8 1489705 135727 0 0
sp_cancel_write_off 9219 9219 0 0.00 1 12184 138 0 0
sp_client_order 19437 19437 0 0.00 4 29636 431 0 0
sp_customer_reserve 446732 446732 0 0.00 0 84757 196 0 0
sp_fill_shopping_cart 29811 29811 0 0.00 1 13913 46 0 0
sp_fill_shopping_cart_clo_res 446729 446729 0 0.00 0 18475 18 0 0
sp_get_clo_for_invoice 4840 4840 0 0.00 0 33118 407 0 0
sp_kill_cost_storno 15742 15742 0 0.00 0 10532 74 0 0
sp_kill_qstorno_ret_qs2qd 1074793 1074793 0 0.00 0 92359 41 0 0
sp_kill_qty_storno 566552 566552 0 0.00 0 92361 89 0 0
sp_lock_selected_doc 19763 19763 0 0.00 0 6710 6 0 0
sp_make_cost_storno 26595 26595 0 0.00 0 27029 66 0 0
sp_multiply_rows_for_pdistr 26595 26595 0 0.00 0 10915 37 0 0
sp_multiply_rows_for_qdistr 19437 19437 0 0.00 0 26836 234 0 0
sp_pay_from_customer 6748 6748 0 0.00 4 19004 140 0 0
sp_pay_to_supplier 5580 5580 0 0.00 5 19251 171 0 0
sp_payment_common 12328 12328 0 0.00 1 11780 29 0 0
sp_qd_handle_on_cancel_clo 1632 1632 0 0.00 0 8568 344 0 0
sp_qd_handle_on_invoice_upd_sts 10309 10309 0 0.00 0 25647 423 0 0
sp_qd_handle_on_reserve_upd_sts 9427 9427 0 0.00 0 19619 66 0 0
sp_reserve_write_off 9427 9427 0 0.00 6 19704 219 0 0
sp_supplier_invoice 5225 5225 0 0.00 8 103536 3685 0 0
sp_supplier_order 5154 5154 0 0.00 6 90589 1476 0 0
srv_aggregate_perf_data 101 101 0 0.00 2708 201231 17073 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1546 145904 46053 0 0
srv_find_qd_qs_mism 1011005 1011005 0 0.00 0 25977 6 0 0
srv_make_invnt_saldo 456 456 0 0.00 50 154185 3909 0 0
srv_make_money_saldo 710 710 0 0.00 0 18890 530 0 0
srv_recalc_idx_stat 5 5 0 0.00 202718 580790 297820 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 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 4845 4845 0 0.00 0 30025 272 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 3876 120 0 0
t$perf-norm:sp_cancel_client_order 1632 1632 0 0.00 0 3142 13 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 4322 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 395 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 5056 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 369 369 0 0.00 0 1852 17 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 29421 1950 0 0
t$perf-norm:sp_cancel_write_off 958 958 0 0.00 0 4784 18 0 0
t$perf-norm:sp_client_order 19437 19437 0 0.00 0 20039 21 0 0
t$perf-norm:sp_customer_reserve 895 895 0 0.00 0 1393 4 0 0
t$perf-norm:sp_pay_from_customer 6748 6748 0 0.00 0 11627 13 0 0
t$perf-norm:sp_pay_to_supplier 5580 5580 0 0.00 0 20445 13 0 0
t$perf-norm:sp_reserve_write_off 9427 9427 0 0.00 0 17697 15 0 0
t$perf-norm:sp_supplier_invoice 5225 5225 0 0.00 0 5222 22 0 0
t$perf-norm:sp_supplier_order 5154 5154 0 0.00 0 9870 18 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 854 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1248 21 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 1210 9 0 0
t$perf-norm:srv_make_money_saldo 710 710 0 0.00 0 8394 22 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 15 7 0 0
v_add_invoice_to_stock 9810 9680 130 1.32 0 5805 18 0 130
v_all_customers 35828 35828 0 0.00 0 126 1 0 0
v_all_suppliers 28730 28730 0 0.00 0 18 1 0 0
v_all_wares 38874 38874 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 318 7 0 0
v_cancel_client_order 3264 3264 0 0.00 0 1826 6 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 5173 18 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 3180 10 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 18854 36 0 0
v_cancel_supplier_invoice 752 738 14 1.86 0 4518 21 0 14
v_cancel_supplier_order 750 750 0 0.00 0 18921 30 0 0
v_cancel_write_off 1916 1916 0 0.00 0 4518 20 0 0
v_max_id_clo_ord 5154 5154 0 0.00 0 243 1 0 0
v_max_id_clo_res 895 895 0 0.00 0 1969 10 0 0
v_max_id_ord_sup 5220 5220 0 0.00 0 6010 4 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 1196 3 0 0
v_max_non_paid_realizn 3403 3403 0 0.00 0 2183 6 0 0
v_min_id_clo_ord 5154 5154 0 0.00 0 6029 11 0 0
v_min_id_clo_res 895 895 0 0.00 0 5604 12 0 0
v_min_id_ord_sup 5220 5220 0 0.00 0 7055 10 0 0
v_min_non_paid_invoice 5580 5580 0 0.00 0 6434 17 0 0
v_min_non_paid_realizn 6748 6748 0 0.00 0 8532 25 0 0
v_our_firm 9736 9736 0 0.00 0 14 1 0 0
v_reserve_write_off 18854 18854 0 0.00 0 14725 11 0 0
x_lock_depdocs_on_canc_invoice 11081 11081 0 0.00 0 20777 129 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 13105 430 0 0
x_make_qstorno_1000_1200 5154 5154 0 0.00 2 90561 1385 0 0
x_make_qstorno_1200_2000 5225 5225 0 0.00 4 99925 3515 0 0
x_make_qstorno_2100_3300 446732 446732 0 0.00 0 84755 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2021 13:38:49 to 19-10-2021 13:38:53.

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
11:07:11 8 8192000000 8798449312 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:12 34268 8192000000 9724381536 10020909056 796331040 8.859 100 79 157 1 822768624 7146160 19291424
11:09:53 22725 8192000000 9754705328 10061717504 830178704 9.201 100 87 172 1 864349552 13178992 20991856
11:11:21 11517 8192000000 9790152464 10106417152 857419648 9.474 100 81 162 1 902633328 26098160 19115520
11:12:41 12664 8192000000 9865366368 10196017152 907538176 9.973 100 78 157 0 974375536 49620704 17216656
11:13:57 7396 8192000000 9898209456 10242351104 944940944 10.341 100 71 140 0 1016446752 56641136 14864672
11:15:06 3950 8192000000 9954007344 10306650112 995493312 10.835 100 80 159 0 1065376320 53130608 16752400
11:16:13 5273 8192000000 9989158960 10349015040 1023326544 11.104 100 85 164 2 1107274592 67158784 16789264
11:17:20 3459 8192000000 10020327328 10387394560 1046343408 11.326 100 83 166 2 1125546208 62377344 16825456
11:18:26 2553 8192000000 10041851264 10419716096 1059360160 11.450 100 87 174 0 1164471152 87654192 17456800
11:19:32 7702 8192000000 10047971040 10419818496 1069969072 11.552 100 89 176 0 1155514848 67354736 18191040
11:20:44 3323 8192000000 10064728992 10442039296 1074716304 11.597 100 74 148 0 1170895920 81784304 14395312
11:21:49 7892 8192000000 9980372048 10386714624 1080216464 11.650 100 34 65 2 1106008688 19088480 6703744
11:22:58 5792 8192000000 9989854304 10393432064 1085004064 11.695 100 35 69 3 1107771968 14489424 8278480
11:24:07 44041 8192000000 10000671648 10395123712 1088150624 11.725 100 35 69 0 1110670224 15004368 7515232
11:25:54 4056 8192000000 10006492480 10407747584 1093575200 11.777 100 46 85 2 1116831568 13040432 10215936
11:27:02 5382 8192000000 10013165376 10412154880 1094129616 11.782 100 51 103 0 1133291568 28458864 10703088
11:28:13 7872 8192000000 9977430432 10361139200 1021868208 11.090 94 27 52 2 1046367264 18687536 5811520
11:29:22 4952 8192000000 10010513344 10388119552 1057621328 11.434 99 76 152 1 1105760880 30117472 18022080
11:30:27 6690 8192000000 9968412640 10358370304 1039233280 11.257 98 24 48 1 1075565536 31445264 4886992
11:31:34 6163 8192000000 9936960576 10323255296 1010461632 10.980 97 36 72 2 1035757920 16958944 8337344
11:32:44 75373 8192000000 9935211216 10322964480 942123936 10.314 91 37 74 1 974907152 25140160 7643056
11:35:03 5764 8192000000 9963010720 10358222848 1049320448 11.354 100 33 64 3 1081156704 25086224 6750032
11:36:12 10197 8192000000 9977960720 10381496320 1059793328 11.455 100 20 39 0 1091503904 27089936 4620640
11:37:28 24449 8192000000 9998816864 10390679552 1088022256 11.724 100 49 97 1 1112357328 14031184 10303888
11:38:56 97064 8192000000 10049439728 10449600512 1108817504 11.921 100 42 84 2 1147779088 30436768 8524816
11:41:46 6449 8192000000 10116183184 10516332544 1134542000 12.164 100 55 110 0 1222849440 76214592 12092848
11:42:59 12867 8192000000 10150820432 10546925568 1146557456 12.277 100 61 122 0 1253379728 94105056 12717216
11:44:16 11518 8192000000 10175960656 10583060480 1156115264 12.367 100 48 96 0 1283386160 116526720 10744176
11:45:32 23787 8192000000 10250900656 10662875136 1166923184 12.468 100 53 106 0 1352659840 173379552 12357104
11:47:17 13840 8192000000 10276677792 10690502656 1176661520 12.559 100 51 102 0 1399688464 211227264 11799680
11:48:40 12632 8192000000 10310473792 10728808448 1183730288 12.625 100 53 107 0 1420694784 223413408 13551088
11:50:02 2527 8192000000 10343066144 10754981888 1180950992 12.599 100 71 140 0 1447705568 250903472 15851104
11:51:09 2997 8192000000 10365378624 10790240256 1184661216 12.634 100 71 142 0 1463720080 262877792 16181072
11:52:31 2045 8192000000 10388440464 10788184064 1173809536 12.532 99 87 170 0 1483966416 290842976 19313904
11:53:36 4050 8192000000 10383250528 10798604288 1172813552 12.523 100 79 156 1 1489108144 297626416 18668176
11:54:55 1539 8192000000 10405548064 10814599168 1174815936 12.542 100 75 151 1 1510831776 318078848 17936992
11:56:04 2957 8192000000 10410098512 10819936256 1166777632 12.467 100 87 174 1 1510432592 322938880 20716080
11:57:11 5432 8192000000 10424789424 10838433792 1170021680 12.497 100 61 122 2 1523777632 339662736 14093216
11:58:26 13448 8192000000 10391158144 10815807488 1160460384 12.408 99 46 92 2 1485672960 314040304 11172272
11:59:44 14922 8192000000 10409964848 10847055872 1159376656 12.397 100 42 83 0 1511165888 340688192 11101040
12:01:03 14349 8192000000 10444393904 10912141312 1144303680 12.256 98 40 79 0 1562215632 407325104 10586848
12:02:21 18040 8192000000 10437715008 10882113536 1116375648 11.993 98 36 72 2 1514996512 388477488 10143376
12:03:48 19718 8192000000 10396027648 10839658496 1121656064 12.043 100 31 62 0 1507029248 376800672 8572512
12:05:22 20085 8192000000 10158841888 10664923136 1130296272 12.124 100 34 68 1 1290406176 152940832 7169072
12:06:44 42909 8192000000 10104192000 10539745280 1114049840 11.971 98 23 45 4 1168691168 50007888 4633440
12:08:35 45699 8192000000 10150843152 10572939264 1126395360 12.087 99 32 65 0 1240741664 106616944 7729360
12:10:39 65166 8192000000 10161820128 10628259840 1127173456 12.095 99 10 20 0 1251914224 122378816 2361952
12:12:48 145904 8192000000 10112567456 10558988288 1135979504 12.178 99 40 80 0 1194497296 50571088 7946704
12:16:32 41053 8192000000 10139295952 10600026112 1110747744 11.939 97 12 24 0 1221917264 108662960 2506560
12:18:18 90791 8192000000 10091492656 10534514688 1072617728 11.577 94 22 43 0 1164542272 87226816 4697728
12:20:50 70717 8192000000 10117024784 10578714624 1142448800 12.239 100 21 42 0 1231393376 84199216 4745360
12:24:05 60315 8192000000 10119790336 10604621824 1133785328 12.157 99 26 53 0 1231613424 92070672 5757424
12:26:09 49806 8192000000 10156120736 10635190272 1146442736 12.276 100 23 46 0 1250523312 99017488 5063088
12:28:09 54995 8192000000 10117238896 10641813504 1135021728 12.169 99 13 26 2 1226949024 88947104 2980192
12:30:38 56898 8192000000 10089812384 10522992640 1146298032 12.275 100 40 79 1 1179605056 25057952 8249072
12:32:35 58658 8192000000 10115659120 10597433344 1133085840 12.150 98 22 44 0 1218501904 80667200 4748864
12:34:44 62941 8192000000 10076534368 10516905984 1137394624 12.191 99 19 38 1 1180765264 39691632 3679008
12:36:48 60413 8192000000 10148762736 10636394496 1135543680 12.174 98 17 34 0 1237063488 97796208 3723600
12:38:48 97139 8192000000 10106403376 10553376768 1133893632 12.158 99 9 18 0 1190913536 55522080 1497824
12:41:27 65164 8192000000 10091276832 10557980672 1105283984 11.888 97 13 25 1 1163900096 56276880 2339232
12:43:33 75031 8192000000 10088181728 10515443712 1134604640 12.165 99 53 102 0 1161442608 16336096 10501872
12:45:50 92882 8192000000 10158490960 10651078656 1133944704 12.159 98 21 42 0 1250695792 112265680 4485408
12:48:23 33847 8192000000 10083450752 10639085568 1108581664 11.919 97 4 8 0 1183067872 73661104 825104
12:49:58 45030 8192000000 10073496672 10545934336 1138393520 12.200 100 13 25 0 1176238304 35603712 2241072
12:51:47 79978 8192000000 10082987184 10548314112 1146399024 12.276 100 12 24 1 1193737632 44681328 2657280
12:54:08 46776 8192000000 10047827792 10509864960 1096314912 11.803 96 11 23 1 1122352480 24083040 1954528
12:56:02 54280 8192000000 10062993360 10522750976 1105515088 11.890 97 15 30 0 1151313200 42376800 3421312
12:57:59 25248 8192000000 10079692144 10528964608 1115442240 11.984 98 18 36 1 1165715616 46496176 3777200
12:59:42 46011 8192000000 10088735360 10531557376 1114672224 11.977 98 22 43 1 1161202432 41972960 4557248
13:01:32 46835 8192000000 10127441152 10554015744 1091363152 11.756 96 36 72 1 1182601504 83823408 7414944
13:03:19 39664 8192000000 10086181168 10539610112 1111172992 11.944 98 26 52 1 1184696432 68530848 4992592
13:05:01 29690 8192000000 10098619664 10552913920 1094610832 11.786 97 48 96 0 1178926608 74980656 9335120
13:06:35 6439 8192000000 10118402080 10532175872 1121372032 12.040 100 60 120 0 1197279040 64223776 11683232
13:07:43 121794 8192000000 10058903392 10573041664 1119033536 12.018 100 13 26 0 1211585344 89660368 2891440
13:10:45 60356 8192000000 10033237312 10494689280 1061816944 11.474 94 14 28 1 1114914032 50625616 2471472
13:12:46 52640 8192000000 10093381616 10532687872 1127091216 12.094 99 24 47 0 1176445232 44673152 4680864
13:14:41 80915 8192000000 10116946784 10616246272 1133670432 12.156 99 14 26 1 1208789824 72430288 2689104
13:17:08 24814 8192000000 10095149552 10532417536 1144688992 12.260 100 43 86 2 1178587552 24514224 9384336
13:18:37 43949 8192000000 10097510896 10540167168 1142342688 12.238 99 24 48 0 1195175664 48013712 4819264
13:20:22 38679 8192000000 10154062944 10590584832 1144249488 12.255 100 27 54 1 1256029616 106450288 5329840
13:22:01 86931 8192000000 10089204240 10543906816 1097466144 11.814 96 14 28 0 1151177056 51144528 2566384
13:24:28 103771 8192000000 10059890096 10493800448 1106904480 11.903 97 13 26 0 1146234512 36644160 2685872
13:27:17 51818 8192000000 10083385584 10532687872 1137634816 12.193 100 28 57 0 1177320048 34200704 5484528
13:29:40 74412 8192000000 10102536688 10560040960 1131159792 12.132 99 7 14 0 1201284800 68670368 1454640
13:31:55 81556 8192000000 10052336096 10519724032 1117593232 12.004 98 14 28 0 1185574832 65209360 2772240
13:34:18 42813 8192000000 10120685888 10589278208 1133163952 12.151 100 21 42 1 1208500400 70221600 5114848
13:36:03 67887 8192000000 9858230896 10253434880 867086848 9.571 76 22 44 0 997494688 125454896 4952944

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-10-2021 13:38:54 to 19-10-2021 13:39:10.

Exceptions occured during test was in run

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

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          222277
MON$OLDEST_ACTIVE               225628
MON$OLDEST_SNAPSHOT             225628
MON$NEXT_TRANSACTION            225628
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                       2046944
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:344: 
:345: mscs-test-161.budzdorov.ru    Tue Oct 19 13:37:28 2021
:346:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:347: 
:348: 
:349: mscs-test-161.budzdorov.ru    Tue Oct 19 13:37:28 2021
:350:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:351: 
--- end of diff output ---

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

19.10.21 13:39:13. 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
2021-10-19 13:39:16 No severe PSQL-related problems occured
19.10.21 13:39:17. End of report.