Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2022 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 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 15779828 kB
MemAvailable 31310580 kB
Buffers 0 kB
Cached 15949160 kB
SwapCached 2444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 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-06-14 13:37:17
DTS_END                         2022-06-14 13:37:17.7430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(751:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/36662
TRN_ID                          229707
ATT_ID                          667
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: 9132.73 25775 1095927
customer order: creation 179.62 454 21554
customer order: refuse 15.24 438 1829
order to supplier: creation 48.07 1368 5768
order to supplier: removal 3.38 188875 406
invoice (draft): creation 47.37 3323 5684
invoice (draft): removal 48.78 1113 5854
invoice accept: apply 43.78 26230 5254
invoice accept: cancel 44.66 1403 5359
customer reserve: creation 4088.21 247 490585
customer reserve: removal 4304.91 116 516589
realization accept: apply 87.13 296 10455
realization accept: cancel 85.22 202 10226
payment from customer: creation 61.55 171 7386
payment from customer: removal 6.42 105 770
payment to supplier: creation 50.93 195 6111
payment to supplier: removal 3.96 114 475
service: total inventory turnovers 4.18 4469 501
service: total monetary turnovers 8.44 413 1013
service: aggregate perf. data 0.87 18947 104
service: refresh index statistics 0.03 267020 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-06-2022 13:38:51 to 14-06-2022 13:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11150
TEST_TIME 2 7307
TEST_TIME 3 5946
TEST_TIME 4 5188
TEST_TIME 5 4927
TEST_TIME 6 4922
TEST_TIME 7 5342
TEST_TIME 8 5433
TEST_TIME 9 5625
TEST_TIME 10 6071
TEST_TIME 11 6295
TEST_TIME 12 6463
TEST_TIME 13 6543
TEST_TIME 14 6969
TEST_TIME 15 7065
TEST_TIME 16 7194
TEST_TIME 17 7145
TEST_TIME 18 7227
TEST_TIME 19 7229
TEST_TIME 20 7408
TEST_TIME 21 7327
TEST_TIME 22 7407
TEST_TIME 23 7618
TEST_TIME 24 7737
TEST_TIME 25 7844
TEST_TIME 26 7837
TEST_TIME 27 7941
TEST_TIME 28 8329
TEST_TIME 29 8493
TEST_TIME 30 9304
TEST_TIME 31 9624
TEST_TIME 32 9784
TEST_TIME 33 9816
TEST_TIME 34 10031
TEST_TIME 35 10514
TEST_TIME 36 10423
TEST_TIME 37 10411
TEST_TIME 38 10650
TEST_TIME 39 10987
TEST_TIME 40 10957
TEST_TIME 41 10874
TEST_TIME 42 10686
TEST_TIME 43 10509
TEST_TIME 44 10755
TEST_TIME 45 10697
TEST_TIME 46 10661
TEST_TIME 47 10611
TEST_TIME 48 10582
TEST_TIME 49 10542
TEST_TIME 50 10416
TEST_TIME 51 10261
TEST_TIME 52 10179
TEST_TIME 53 10174
TEST_TIME 54 10032
TEST_TIME 55 9959
TEST_TIME 56 9938
TEST_TIME 57 9913
TEST_TIME 58 9824
TEST_TIME 59 9868
TEST_TIME 60 9860
TEST_TIME 61 9797
TEST_TIME 62 9874
TEST_TIME 63 9776
TEST_TIME 64 9752
TEST_TIME 65 9910
TEST_TIME 66 9876
TEST_TIME 67 9826
TEST_TIME 68 9778
TEST_TIME 69 9889
TEST_TIME 70 9927
TEST_TIME 71 9914
TEST_TIME 72 9874
TEST_TIME 73 9865
TEST_TIME 74 9870
TEST_TIME 75 9893
TEST_TIME 76 9958
TEST_TIME 77 9995
TEST_TIME 78 9940
TEST_TIME 79 9858
TEST_TIME 80 9784
TEST_TIME 81 9804
TEST_TIME 82 9710
TEST_TIME 83 9691
TEST_TIME 84 9696
TEST_TIME 85 9660
TEST_TIME 86 9588
TEST_TIME 87 9592
TEST_TIME 88 9531
TEST_TIME 89 9454
TEST_TIME 90 9468
TEST_TIME 91 9429
TEST_TIME 92 9502
TEST_TIME 93 9474
TEST_TIME 94 9456
TEST_TIME 95 9490
TEST_TIME 96 9464
TEST_TIME 97 9469
TEST_TIME 98 9494
TEST_TIME 99 9484
TEST_TIME 100 9473
TEST_TIME 101 9511
TEST_TIME 102 9530
TEST_TIME 103 9532
TEST_TIME 104 9507
TEST_TIME 105 9430
TEST_TIME 106 9387
TEST_TIME 107 9423
TEST_TIME 108 9360
TEST_TIME 109 9314
TEST_TIME 110 9255
TEST_TIME 111 9218
TEST_TIME 112 9192
TEST_TIME 113 9167
TEST_TIME 114 9163
TEST_TIME 115 9160
TEST_TIME 116 9147
TEST_TIME 117 9166
TEST_TIME 118 9205
TEST_TIME 119 9217
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-06-2022 13:38:55 to 14-06-2022 13:38:58.

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 1545791 1545791 0 0.00 0 13608 15 0 0
doc_list_biud 1545781 1545781 0 0.00 0 34877 45 0 0
sp_add_invoice_to_stock 5340 5254 86 1.61 26 200489 26230 0 86
sp_cancel_adding_invoice 5359 5359 0 0.00 6 97813 1403 0 0
sp_cancel_client_order 1829 1829 0 0.00 6 11502 438 0 0
sp_cancel_customer_reserve 516589 516589 0 0.00 1 30550 117 0 0
sp_cancel_pay_from_customer 770 770 0 0.00 4 1948 105 0 0
sp_cancel_pay_to_supplier 475 475 0 0.00 4 1263 115 0 0
sp_cancel_supplier_invoice 5860 5854 6 0.10 3 25260 1114 0 6
sp_cancel_supplier_order 406 406 0 0.00 11 1704199 188875 0 0
sp_cancel_write_off 10226 10226 0 0.00 2 17959 203 0 0
sp_client_order 21554 21554 0 0.00 6 26355 455 0 0
sp_customer_reserve 490585 490585 0 0.00 1 192442 247 0 0
sp_fill_shopping_cart 32989 32989 0 0.00 2 24221 87 0 0
sp_fill_shopping_cart_clo_res 490565 490565 0 0.00 0 22727 18 0 0
sp_get_clo_for_invoice 5215 5215 0 0.00 0 33035 257 0 0
sp_kill_cost_storno 16830 16830 0 0.00 0 9177 86 0 0
sp_kill_qstorno_ret_qs2qd 1049664 1049664 0 0.00 0 30542 50 0 0
sp_kill_qty_storno 555929 555929 0 0.00 0 34875 115 0 0
sp_lock_selected_doc 21810 21810 0 0.00 0 11241 8 0 0
sp_make_cost_storno 29167 29167 0 0.00 0 12572 81 0 0
sp_multiply_rows_for_pdistr 29163 29163 0 0.00 0 9223 38 0 0
sp_multiply_rows_for_qdistr 21553 21553 0 0.00 0 8510 182 0 0
sp_pay_from_customer 7386 7386 0 0.00 5 9698 171 0 0
sp_pay_to_supplier 6111 6111 0 0.00 6 18553 195 0 0
sp_payment_common 13495 13495 0 0.00 2 8221 44 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 1 8664 355 0 0
sp_qd_handle_on_invoice_upd_sts 10571 10571 0 0.00 0 34874 563 0 0
sp_qd_handle_on_reserve_upd_sts 10454 10454 0 0.00 0 33677 78 0 0
sp_reserve_write_off 10455 10455 0 0.00 9 33801 297 0 0
sp_supplier_invoice 5684 5684 0 0.00 10 101683 3324 0 0
sp_supplier_order 5768 5768 0 0.00 8 34645 1369 0 0
srv_aggregate_perf_data 104 104 0 0.00 2591 172017 18948 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 779 228276 34614 0 0
srv_find_qd_qs_mism 1043499 1043499 0 0.00 0 13524 11 0 0
srv_make_invnt_saldo 501 501 0 0.00 60 96584 4470 0 0
srv_make_money_saldo 1013 1013 0 0.00 1 22480 413 0 0
srv_recalc_idx_stat 4 4 0 0.00 100832 434932 267021 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5258 5258 0 0.00 0 11328 1013 0 0
t$perf-norm:sp_cancel_adding_invoice 443 443 0 0.00 1 6155 486 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 3110 28 0 0
t$perf-norm:sp_cancel_customer_reserve 749 749 0 0.00 0 3379 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 770 770 0 0.00 0 1936 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 475 475 0 0.00 0 5202 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 3395 37 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 134977 7991 0 0
t$perf-norm:sp_cancel_write_off 1070 1070 0 0.00 0 4192 23 0 0
t$perf-norm:sp_client_order 21554 21554 0 0.00 0 10563 38 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 5173 21 0 0
t$perf-norm:sp_pay_from_customer 7386 7386 0 0.00 0 10477 22 0 0
t$perf-norm:sp_pay_to_supplier 6111 6111 0 0.00 0 5079 20 0 0
t$perf-norm:sp_reserve_write_off 10456 10456 0 0.00 0 5858 25 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 10601 31 0 0
t$perf-norm:sp_supplier_order 5768 5768 0 0.00 0 5754 32 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1068 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 409 13 0 0
t$perf-norm:srv_make_invnt_saldo 501 501 0 0.00 0 2772 28 0 0
t$perf-norm:srv_make_money_saldo 1013 1013 0 0.00 0 1257 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 37 181 115 0 0
v_add_invoice_to_stock 10586 10414 172 1.62 0 5176 25 0 172
v_all_customers 40070 40070 0 0.00 0 183 4 0 0
v_all_suppliers 31652 31652 0 0.00 0 187 4 0 0
v_all_wares 43100 43100 0 0.00 0 264 4 0 0
v_cancel_adding_invoice 886 886 0 0.00 0 391 15 0 0
v_cancel_client_order 3658 3658 0 0.00 0 2771 12 0 0
v_cancel_customer_prepayment 1540 1540 0 0.00 0 1838 11 0 0
v_cancel_customer_reserve 1498 1498 0 0.00 0 3936 17 0 0
v_cancel_payment_to_supplier 950 950 0 0.00 0 597 12 0 0
v_cancel_supplier_invoice 828 816 12 1.44 0 553 21 0 12
v_cancel_supplier_order 812 812 0 0.00 0 1583 14 0 0
v_cancel_write_off 2140 2140 0 0.00 0 1766 22 0 0
v_max_id_clo_ord 5764 5764 0 0.00 0 945 5 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 1100 15 0 0
v_max_id_ord_sup 5673 5673 0 0.00 0 2966 7 0 0
v_max_non_paid_invoice 1722 1722 0 0.00 0 2825 9 0 0
v_max_non_paid_realizn 3572 3572 0 0.00 0 7399 10 0 0
v_min_id_clo_ord 5764 5764 0 0.00 0 922 8 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 2272 10 0 0
v_min_id_ord_sup 5673 5673 0 0.00 0 6650 14 0 0
v_min_non_paid_invoice 6111 6111 0 0.00 0 8217 13 0 0
v_min_non_paid_realizn 7384 7384 0 0.00 0 4511 14 0 0
v_our_firm 10654 10654 0 0.00 0 125 5 0 0
v_reserve_write_off 20906 20906 0 0.00 0 4088 11 0 0
x_lock_depdocs_on_canc_invoice 10805 10805 0 0.00 0 41345 160 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 14074 404 0 0
x_make_qstorno_1000_1200 5768 5768 0 0.00 2 34630 1181 0 0
x_make_qstorno_1200_2000 5684 5684 0 0.00 5 101547 3098 0 0
x_make_qstorno_2100_3300 490583 490583 0 0.00 0 192421 216 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-06-2022 13:38:59 to 14-06-2022 13:39:04.

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:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:22 29089 8192000000 9720196496 10019479552 799262368 8.889 100 85 169 1 827110128 7390784 20456976
11:09:56 8890 8192000000 9769700944 10077122560 843179408 9.332 100 93 186 0 877534608 11992240 22362960
11:11:12 21726 8192000000 9811874752 10130542592 877910736 9.679 100 86 172 1 919285232 21839184 19535312
11:12:38 11232 8192000000 9855414416 10193731584 912584896 10.023 100 75 147 1 968852608 40031760 16235952
11:13:55 7263 8192000000 9913834608 10256789504 957476528 10.464 100 80 161 1 1023820032 49560416 16783088
11:15:05 4056 8192000000 9956661168 10310979584 993623360 10.817 100 92 182 0 1073032608 60005376 19403872
11:16:12 1962 8192000000 9988060480 10348945408 1024205200 11.113 100 90 171 0 1114173088 72620368 17347520
11:17:17 740 8192000000 10009062416 10375860224 1040650672 11.271 100 97 187 1 1127056736 68215824 18190240
11:18:19 991 8192000000 10033713088 10406825984 1055450544 11.413 100 96 187 1 1151877232 78127520 18299168
11:19:22 918 8192000000 10022390768 10396446720 1063123040 11.486 100 95 184 1 1142308000 61031936 18153024
11:20:25 970 8192000000 10038971232 10417545216 1070950400 11.561 100 95 180 1 1161928672 72843616 18134656
11:21:27 1635 8192000000 10046679088 10432151552 1072970320 11.580 100 90 163 0 1167930304 79283168 15676816
11:22:31 4058 8192000000 10057586400 10445463552 1078716512 11.635 100 71 133 0 1174882928 82941536 13224880
11:23:36 2850 8192000000 10030748368 10422235136 1084569472 11.691 100 74 140 0 1150946800 52014608 14362720
11:24:40 1433 8192000000 10048712400 10435866624 1088235008 11.726 100 94 179 2 1172656480 65685168 18736304
11:25:43 2030 8192000000 10065865648 10456010752 1086950640 11.714 100 83 159 0 1183046576 80495056 15600880
11:26:46 891 8192000000 10060419328 10450669568 1095344064 11.793 100 90 172 3 1184091248 70448496 18298688
11:27:49 1072 8192000000 10059459712 10446966784 1096314928 11.803 100 94 188 1 1182915296 66642192 19958176
11:28:51 2163 8192000000 10027461056 10417160192 1085867888 11.703 100 62 121 1 1157036320 58898144 12270288
11:29:55 1102 8192000000 10040564320 10429304832 1085649120 11.701 100 87 173 0 1163161600 59115536 18396944
11:30:57 1345 8192000000 10060428272 10445856768 1077712704 11.626 100 95 182 0 1179428000 83415920 18299376
11:32:00 1288 8192000000 10023721088 10400264192 1040272128 11.267 98 93 179 1 1144928256 86796944 17859184
11:33:02 844 8192000000 9993959696 10371067904 1042875840 11.292 100 95 177 2 1116736752 54045936 19814976
11:34:06 754 8192000000 9989100224 10357628928 1030204848 11.170 100 96 184 1 1104760016 54831008 19724160
11:35:08 1374 8192000000 9963684352 10334957568 1032317600 11.191 100 85 165 5 1087086416 37989296 16779520
11:36:10 964 8192000000 9968263936 10338033664 1025028992 11.121 100 88 164 0 1090748640 49106720 16612928
11:37:15 1025 8192000000 10000834080 10379968512 1049909696 11.360 100 85 160 3 1123738416 56324992 17503728
11:38:24 2613 8192000000 10031150592 10418294784 1066113472 11.515 100 83 164 0 1155756784 72276016 17367296
11:39:37 31798 8192000000 10028224800 10423545856 1082810352 11.674 100 49 95 0 1145941552 53611264 9519936
11:41:28 22423 8192000000 10085423280 10478985216 1102822672 11.864 100 63 123 1 1196912128 80378608 13710848
11:42:57 12124 8192000000 10117097904 10514644992 1089858928 11.741 98 70 128 0 1223541264 119290672 14391664
11:44:15 2673 8192000000 10175491072 10572730368 1117037888 11.999 100 84 155 2 1297441280 164291696 16111696
11:45:28 9773 8192000000 10194562992 10591879168 1118355680 12.011 99 82 158 0 1312728768 177115904 17257184
11:46:44 1541 8192000000 10229214912 10624884736 1151439056 12.323 100 84 158 4 1347569552 178051504 18078992
11:47:49 1454 8192000000 10265686672 10679992320 1172533792 12.521 100 78 145 4 1381198112 192036080 16628240
11:48:54 1355 8192000000 10303277184 10703499264 1181603376 12.605 100 93 179 0 1411902752 209465232 20834144
11:49:58 783 8192000000 10302296240 10714759168 1180723376 12.597 100 93 186 1 1413800528 211876208 21200944
11:51:02 1776 8192000000 10325139008 10755977216 1194455344 12.725 100 87 167 1 1442154944 229281824 18417776
11:52:06 1135 8192000000 10375572944 10793369600 1207915920 12.850 100 96 186 0 1484456144 255268528 21271696
11:53:10 1137 8192000000 10394828912 10809344000 1214537392 12.911 100 93 182 2 1508805872 273118096 21150384
11:54:16 1230 8192000000 10425232608 10828861440 1193523552 12.716 98 93 181 0 1530745712 316340688 20881472
11:55:21 1146 8192000000 10504983248 10918907904 1219956832 12.961 100 100 197 1 1615563696 371849504 23757360
11:56:26 856 8192000000 10531743472 10940104704 1212411968 12.891 100 95 182 2 1637004800 403528000 21064832
11:57:31 922 8192000000 10549803616 10966564864 1205225136 12.825 100 98 188 1 1657321344 428688016 23408192
11:58:36 835 8192000000 10603112080 11010846720 1201058000 12.786 100 94 189 1 1702395232 478692176 22645056
11:59:39 3268 8192000000 10627045104 11058978816 1207814480 12.849 100 92 180 0 1726822624 498019600 20988544
12:00:44 1372 8192000000 10644230304 11080282112 1207478352 12.846 100 68 134 0 1749994992 525511664 17004976
12:01:57 2699 8192000000 10665556928 11081834496 1202713664 12.802 99 85 163 1 1761560080 539881312 18965104
12:03:03 15471 8192000000 10666891632 11102797824 1200337856 12.779 100 49 96 2 1778714336 565340208 13036272
12:04:27 11636 8192000000 10661924256 11115147264 1179393440 12.585 100 49 96 0 1771264720 578504848 13366432
12:06:10 8113 8192000000 10556555168 11050442752 1143058464 12.244 99 41 77 1 1703141328 549430000 10652864
12:07:19 21914 8192000000 10363112240 10868256768 1129714096 12.119 99 37 70 1 1514698768 376287920 8696752
12:08:51 8431 8192000000 10324842576 10824089600 1135683648 12.175 100 52 102 3 1428221760 280333632 12204480
12:10:02 1522 8192000000 10291668864 10779566080 1145265088 12.265 100 73 142 0 1392787728 230964736 16557904
12:11:07 773 8192000000 10266691120 10810462208 1158302448 12.387 100 77 147 0 1365943632 190258432 17382752
12:12:09 25050 8192000000 10231676704 10701656064 1146925360 12.281 99 51 99 0 1352819280 193745856 12148064
12:13:39 15682 8192000000 10220304272 10668179456 1152465856 12.333 100 43 82 2 1333685008 170552960 10666192
12:14:55 61289 8192000000 10216368272 10760138752 1138882000 12.205 99 18 36 2 1353018624 209397056 4739568
12:17:49 109529 8192000000 10104127472 10528862208 1104911888 11.884 96 23 41 1 1164363984 55265248 4186848
12:20:41 65223 8192000000 10091528288 10579021824 1121047632 12.037 98 37 74 0 1173916944 45500928 7368384
12:22:54 110117 8192000000 10078591232 10539692032 1088224992 11.726 95 7 14 0 1122199584 32804672 1169920
12:25:45 84990 8192000000 10067409216 10540298240 1116413680 11.993 98 14 28 0 1149341200 30039920 2887600
12:28:20 38316 8192000000 10069970256 10506944512 1120362944 12.030 99 49 93 0 1159463040 28883968 10216128
12:30:05 27061 8192000000 10107956112 10548523008 1130573264 12.127 100 44 87 0 1178527680 38949952 9004464
12:31:40 48383 8192000000 10071928336 10500038656 1090609088 11.748 96 41 74 2 1126368800 27156752 8602960
12:33:29 15810 8192000000 10104127328 10537873408 1140001520 12.216 100 38 75 0 1219235744 71392320 7841904
12:34:48 12512 8192000000 10133026432 10567032832 1132995040 12.150 99 37 73 2 1231345360 89618416 8731904
12:36:01 11351 8192000000 10224160080 10640576512 1128330400 12.106 99 69 129 3 1307405360 165313872 13761088
12:37:13 46938 8192000000 10186876304 10669879296 1128238880 12.105 99 18 36 0 1296601552 164000864 4361808
12:39:51 228276 8192000000 10056990528 10518237184 1078479952 11.633 95 17 32 1 1124859216 42470016 3909248
12:44:40 56874 8192000000 10138908016 10587426816 1140881904 12.224 100 26 50 1 1226787616 80242816 5662896
12:47:02 55091 8192000000 10076022064 10559516672 1065893040 11.513 94 18 35 1 1092100768 23283056 2924672
12:48:57 24662 8192000000 10090994336 10522210304 1121965824 12.046 99 56 102 3 1177788992 45926128 9897040
12:50:22 9393 8192000000 10077036960 10501451776 1111628944 11.948 98 37 74 1 1155636144 37111552 6895648
12:52:04 2119 8192000000 10134564800 10552819712 1138707632 12.203 100 78 147 2 1229954000 75354320 15892048
12:53:08 114326 8192000000 10072907696 10540142592 1131121200 12.132 99 12 24 0 1204595952 71173088 2301664
12:56:02 125118 8192000000 10099784144 10556542976 1135521680 12.173 100 45 87 0 1209659456 64754096 9383680
12:59:09 45155 8192000000 10078605184 10519937024 1121457232 12.041 99 46 90 2 1175203952 45086144 8660576
13:00:55 41843 8192000000 10071501040 10510372864 1107022592 11.904 99 30 59 1 1168152768 54780208 6349968
13:02:38 65159 8192000000 10082689232 10525659136 1104720432 11.882 98 21 42 1 1155631584 46122336 4788816
13:04:43 46259 8192000000 10086162976 10553303040 1130375984 12.125 100 9 18 1 1184133360 51725264 2032112
13:06:30 63904 8192000000 10134359984 10610540544 1141955632 12.234 100 16 32 0 1241965632 96569904 3440096
13:08:51 56602 8192000000 10061983328 10532605952 1112803088 11.959 98 19 38 1 1177723024 60813680 4106256
13:10:56 28781 8192000000 10105349440 10539573248 1136440096 12.182 100 43 86 1 1199642208 53943168 9258944
13:12:26 35622 8192000000 10085445872 10540142592 1132609440 12.146 99 36 72 1 1187540720 47343472 7587808
13:14:05 62346 8192000000 10116747712 10577432576 1111159088 11.943 97 29 58 1 1180091824 63433328 5499408
13:16:13 9213 8192000000 10073011168 10502606848 1100535920 11.843 98 46 92 1 1146660400 37335856 8788624
13:17:25 12343 8192000000 10096756496 10523156480 1110550368 11.938 99 51 100 1 1181974480 61518608 9905504
13:18:41 39379 8192000000 10087472912 10507825152 1096781712 11.807 98 43 87 0 1148590336 42968544 8840080
13:20:21 60558 8192000000 10056405008 10506514432 1082650064 11.673 96 21 42 2 1124937088 38108576 4178448
13:22:22 130147 8192000000 10048548784 10530435072 1101314384 11.850 99 17 34 0 1176164864 70292336 4558144
13:26:43 55276 8192000000 10116330688 10573885440 1131383456 12.134 100 20 38 0 1201809152 66516256 3909440
13:28:39 77742 8192000000 10179589856 10617778176 1128531264 12.108 99 18 36 1 1251746288 118909472 4305552
13:31:02 32756 8192000000 10232006816 10668957696 1149859856 12.308 100 32 63 1 1328075440 170949424 7266160
13:32:40 28780 8192000000 10235636832 10687901696 1144923888 12.262 100 31 62 1 1332708256 180578800 7205568
13:34:09 33459 8192000000 10216320144 10697674752 1133717984 12.156 99 35 70 0 1318824704 176588288 8518432
13:35:43 38512 8192000000 10218607584 10659749888 1132416288 12.144 99 35 67 0 1285118688 144980752 7721648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 14-06-2022 13:39:05 to 14-06-2022 13:39:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 86 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2022 13:39:22 to 14-06-2022 13:39:24.

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          216908
MON$OLDEST_ACTIVE               229942
MON$OLDEST_SNAPSHOT             229942
MON$NEXT_TRANSACTION            229942
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                       2099904
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 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 ---
:236: 
:237: mscs-test-161.budzdorov.ru    Tue Jun 14 13:37:24 2022
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:239: 
:240: 
:241: mscs-test-161.budzdorov.ru    Tue Jun 14 13:37:26 2022
:242:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:243: 
:244: 
:245: mscs-test-161.budzdorov.ru    Tue Jun 14 13:37:27 2022
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:247: 
:248: 
:249: mscs-test-161.budzdorov.ru    Tue Jun 14 13:37:28 2022
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:251: 
--- end of diff output ---

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

14.06.22 13:39:26. 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-06-14 13:39:28 No severe PSQL-related problems occured
14.06.22 13:39:29. End of report.