Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.06.2022 07:37
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 16019680 kB
MemAvailable 31253672 kB
Buffers 0 kB
Cached 15651824 kB
SwapCached 2440 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-13 07:37:17
DTS_END                         2022-06-13 07:37:17.6540
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(195:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45790
TRN_ID                          230089
ATT_ID                          684
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9264.83 27194 1111779
customer order: creation 178.05 462 21366
customer order: refuse 15.23 387 1828
order to supplier: creation 47.19 1298 5663
order to supplier: removal 3.39 171356 407
invoice (draft): creation 47.04 3155 5645
invoice (draft): removal 52.27 925 6272
invoice accept: apply 43.72 25586 5246
invoice accept: cancel 46.45 1180 5574
customer reserve: creation 3974.97 250 476996
customer reserve: removal 4557.41 103 546889
realization accept: apply 85.44 266 10253
realization accept: cancel 79.42 168 9530
payment from customer: creation 61.08 157 7330
payment from customer: removal 6.29 106 755
payment to supplier: creation 50.32 176 6038
payment to supplier: removal 3.20 90 384
service: total inventory turnovers 4.05 3813 486
service: total monetary turnovers 8.19 306 983
service: aggregate perf. data 1.08 14221 129
service: refresh index statistics 0.04 319874 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-06-2022 07:38:07 to 13-06-2022 07:38:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7578
TEST_TIME 2 7668
TEST_TIME 3 7119
TEST_TIME 4 6840
TEST_TIME 5 6661
TEST_TIME 6 6568
TEST_TIME 7 6546
TEST_TIME 8 6368
TEST_TIME 9 6447
TEST_TIME 10 6336
TEST_TIME 11 6438
TEST_TIME 12 6538
TEST_TIME 13 6644
TEST_TIME 14 6783
TEST_TIME 15 7144
TEST_TIME 16 7266
TEST_TIME 17 7590
TEST_TIME 18 7971
TEST_TIME 19 8087
TEST_TIME 20 8182
TEST_TIME 21 8214
TEST_TIME 22 8365
TEST_TIME 23 8394
TEST_TIME 24 8565
TEST_TIME 25 9256
TEST_TIME 26 9412
TEST_TIME 27 9713
TEST_TIME 28 10131
TEST_TIME 29 10726
TEST_TIME 30 10947
TEST_TIME 31 11015
TEST_TIME 32 11156
TEST_TIME 33 10989
TEST_TIME 34 10953
TEST_TIME 35 10904
TEST_TIME 36 10710
TEST_TIME 37 10522
TEST_TIME 38 10515
TEST_TIME 39 10413
TEST_TIME 40 10230
TEST_TIME 41 10147
TEST_TIME 42 10134
TEST_TIME 43 10154
TEST_TIME 44 10076
TEST_TIME 45 9978
TEST_TIME 46 9973
TEST_TIME 47 9943
TEST_TIME 48 9957
TEST_TIME 49 9888
TEST_TIME 50 9884
TEST_TIME 51 9873
TEST_TIME 52 9779
TEST_TIME 53 9742
TEST_TIME 54 9999
TEST_TIME 55 9936
TEST_TIME 56 10001
TEST_TIME 57 10051
TEST_TIME 58 10060
TEST_TIME 59 10058
TEST_TIME 60 10030
TEST_TIME 61 10010
TEST_TIME 62 9874
TEST_TIME 63 9853
TEST_TIME 64 9739
TEST_TIME 65 9723
TEST_TIME 66 9647
TEST_TIME 67 9577
TEST_TIME 68 9485
TEST_TIME 69 9457
TEST_TIME 70 9468
TEST_TIME 71 9446
TEST_TIME 72 9423
TEST_TIME 73 9432
TEST_TIME 74 9461
TEST_TIME 75 9402
TEST_TIME 76 9421
TEST_TIME 77 9495
TEST_TIME 78 9570
TEST_TIME 79 9570
TEST_TIME 80 9636
TEST_TIME 81 9621
TEST_TIME 82 9622
TEST_TIME 83 9592
TEST_TIME 84 9498
TEST_TIME 85 9463
TEST_TIME 86 9427
TEST_TIME 87 9396
TEST_TIME 88 9326
TEST_TIME 89 9255
TEST_TIME 90 9304
TEST_TIME 91 9241
TEST_TIME 92 9274
TEST_TIME 93 9303
TEST_TIME 94 9284
TEST_TIME 95 9304
TEST_TIME 96 9272
TEST_TIME 97 9274
TEST_TIME 98 9298
TEST_TIME 99 9374
TEST_TIME 100 9351
TEST_TIME 101 9400
TEST_TIME 102 9344
TEST_TIME 103 9291
TEST_TIME 104 9252
TEST_TIME 105 9237
TEST_TIME 106 9192
TEST_TIME 107 9148
TEST_TIME 108 9134
TEST_TIME 109 9099
TEST_TIME 110 9064
TEST_TIME 111 9099
TEST_TIME 112 9101
TEST_TIME 113 9088
TEST_TIME 114 9065
TEST_TIME 115 9081
TEST_TIME 116 9080
TEST_TIME 117 9125
TEST_TIME 118 9220
TEST_TIME 119 9369
TEST_TIME 120 9297

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-06-2022 07:38:10 to 13-06-2022 07:38:14.

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 1549968 1549968 0 0.00 0 11017 14 0 0
doc_list_biud 1549951 1549951 0 0.00 0 29754 42 0 0
sp_add_invoice_to_stock 5396 5246 150 2.77 24 199883 25586 0 150
sp_cancel_adding_invoice 5574 5574 0 0.00 5 84818 1181 0 0
sp_cancel_client_order 1828 1828 0 0.00 6 10153 387 0 0
sp_cancel_customer_reserve 546889 546889 0 0.00 1 23380 103 0 0
sp_cancel_pay_from_customer 755 755 0 0.00 4 3806 107 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 931 91 0 0
sp_cancel_supplier_invoice 6289 6272 17 0.27 3 20028 925 0 17
sp_cancel_supplier_order 407 407 0 0.00 15 1362370 171357 0 0
sp_cancel_write_off 9530 9530 0 0.00 2 7675 168 0 0
sp_client_order 21366 21366 0 0.00 5 25252 462 0 0
sp_customer_reserve 476996 476996 0 0.00 1 142689 250 0 0
sp_fill_shopping_cart 32658 32658 0 0.00 1 10738 76 0 0
sp_fill_shopping_cart_clo_res 476961 476961 0 0.00 0 17417 19 0 0
sp_get_clo_for_invoice 5203 5203 0 0.00 0 31813 273 0 0
sp_kill_cost_storno 16243 16243 0 0.00 0 6908 65 0 0
sp_kill_qstorno_ret_qs2qd 1109987 1109987 0 0.00 0 21967 45 0 0
sp_kill_qty_storno 585954 585954 0 0.00 0 29754 102 0 0
sp_lock_selected_doc 21436 21436 0 0.00 0 9915 9 0 0
sp_make_cost_storno 28824 28824 0 0.00 0 10569 72 0 0
sp_multiply_rows_for_pdistr 28822 28822 0 0.00 0 6498 32 0 0
sp_multiply_rows_for_qdistr 21366 21366 0 0.00 0 9631 200 0 0
sp_pay_from_customer 7330 7330 0 0.00 5 9101 158 0 0
sp_pay_to_supplier 6038 6038 0 0.00 6 8655 176 0 0
sp_payment_common 13368 13368 0 0.00 2 7568 40 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 10128 314 0 0
sp_qd_handle_on_invoice_upd_sts 10775 10775 0 0.00 0 29754 483 0 0
sp_qd_handle_on_reserve_upd_sts 10253 10253 0 0.00 0 16917 65 0 0
sp_reserve_write_off 10253 10253 0 0.00 8 16962 266 0 0
sp_supplier_invoice 5645 5645 0 0.00 8 94085 3156 0 0
sp_supplier_order 5663 5663 0 0.00 7 38593 1299 0 0
srv_aggregate_perf_data 129 129 0 0.00 2561 103368 14221 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 857 114049 33873 0 0
srv_find_qd_qs_mism 1060720 1060720 0 0.00 0 11015 11 0 0
srv_make_invnt_saldo 486 486 0 0.00 108 75833 3813 0 0
srv_make_money_saldo 983 983 0 0.00 1 10087 306 0 0
srv_recalc_idx_stat 5 5 0 0.00 181859 433236 319874 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5255 5255 0 0.00 0 13683 865 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6455 417 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 1640 23 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 583 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 755 755 0 0.00 0 939 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 207 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 440 440 0 0.00 0 2429 38 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 112014 6053 0 0
t$perf-norm:sp_cancel_write_off 1081 1081 0 0.00 0 4549 23 0 0
t$perf-norm:sp_client_order 21367 21367 0 0.00 0 6685 32 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 7946 20 0 0
t$perf-norm:sp_pay_from_customer 7330 7330 0 0.00 0 5797 18 0 0
t$perf-norm:sp_pay_to_supplier 6038 6038 0 0.00 0 2494 18 0 0
t$perf-norm:sp_reserve_write_off 10253 10253 0 0.00 0 8993 23 0 0
t$perf-norm:sp_supplier_invoice 5646 5646 0 0.00 0 6832 29 0 0
t$perf-norm:sp_supplier_order 5663 5663 0 0.00 0 5128 29 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 292 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1067 21 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 626 14 0 0
t$perf-norm:srv_make_money_saldo 983 983 0 0.00 0 1602 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 219 59 0 0
v_add_invoice_to_stock 10690 10390 300 2.80 0 12825 25 0 300
v_all_customers 38958 38958 0 0.00 0 100 3 0 0
v_all_suppliers 31782 31782 0 0.00 0 81 4 0 0
v_all_wares 42724 42724 0 0.00 0 146 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1015 14 0 0
v_cancel_client_order 3656 3656 0 0.00 0 3233 10 0 0
v_cancel_customer_prepayment 1510 1510 0 0.00 0 2189 11 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 1 1405 11 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 346 9 0 0
v_cancel_supplier_invoice 914 880 34 3.71 0 9891 35 0 34
v_cancel_supplier_order 814 814 0 0.00 0 460 9 0 0
v_cancel_write_off 2162 2162 0 0.00 0 5828 27 0 0
v_max_id_clo_ord 5659 5659 0 0.00 0 2557 5 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 3334 21 0 0
v_max_id_ord_sup 5632 5632 0 0.00 0 4517 6 0 0
v_max_non_paid_invoice 1438 1438 0 0.00 0 3682 9 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 1729 7 0 0
v_min_id_clo_ord 5659 5659 0 0.00 0 3789 9 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 8246 21 0 0
v_min_id_ord_sup 5632 5632 0 0.00 0 1537 6 0 0
v_min_non_paid_invoice 6038 6038 0 0.00 0 3832 12 0 0
v_min_non_paid_realizn 7330 7330 0 0.00 0 3286 12 0 0
v_our_firm 10766 10766 0 0.00 0 63 4 0 0
v_reserve_write_off 20506 20506 0 0.00 0 5722 11 0 0
x_lock_depdocs_on_canc_invoice 11406 11406 0 0.00 0 23835 115 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 3091 309 0 0
x_make_qstorno_1000_1200 5663 5663 0 0.00 2 38585 1137 0 0
x_make_qstorno_1200_2000 5645 5645 0 0.00 3 92655 2955 0 0
x_make_qstorno_2100_3300 476994 476994 0 0.00 0 142687 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-06-2022 07:38:15 to 13-06-2022 07:38:19.

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
05:07:21 3 8192000000 8797560800 8808550400 3901568 0.047 2 1 2 0 4000880 63536 35776
05:08:22 26095 8192000000 9723448144 10023964672 800798800 8.904 100 88 176 0 830381376 7733248 21849328
05:09:55 24923 8192000000 9756716656 10066935808 831526784 9.215 100 87 174 0 865715584 12994880 21193920
05:11:33 18512 8192000000 9813694512 10136481792 870426976 9.604 100 85 170 0 918960976 28656320 19877680
05:13:02 8226 8192000000 9884324096 10220941312 924268336 10.138 100 81 162 1 989879824 47963424 17648064
05:14:13 5131 8192000000 9923565808 10269573120 964015968 10.528 100 85 165 2 1032577856 50776208 17785680
05:15:19 1432 8192000000 9973899024 10332016640 997637440 10.856 100 83 160 1 1090680112 76596432 16446240
05:16:23 1004 8192000000 10010982112 10376679424 1029761312 11.166 100 88 171 3 1127633120 80988032 16883776
05:17:26 928 8192000000 10024352336 10399891456 1042507968 11.289 100 92 181 0 1135612288 74972432 18131888
05:18:30 1027 8192000000 10032085344 10408034304 1057772528 11.435 100 96 187 0 1141941008 64235264 19933216
05:19:32 1063 8192000000 10060502864 10435186688 1059055600 11.447 100 94 181 0 1169856400 93075552 17725248
05:20:34 7087 8192000000 10038385856 10430509056 1066554640 11.519 100 68 126 4 1149177184 70573008 12049536
05:21:42 1057 8192000000 10047427584 10430091264 1072539200 11.576 100 93 177 4 1160964448 69424832 19000416
05:22:44 1872 8192000000 10044904624 10432638976 1078297120 11.631 100 76 148 1 1151254288 58032256 14924912
05:23:47 1337 8192000000 10082733664 10472722432 1079756048 11.645 100 99 193 0 1193442656 94069584 19617024
05:24:49 1078 8192000000 10055074784 10445238272 1086946352 11.714 100 90 174 1 1170041104 64315280 18779472
05:25:52 1212 8192000000 10079074016 10477031424 1086724944 11.712 100 87 170 1 1189784960 85384864 17675152
05:27:00 3647 8192000000 10033169872 10424246272 1082197488 11.668 100 62 124 1 1149287040 56333616 10755936
05:28:05 1162 8192000000 10044277328 10434883584 1080079824 11.648 100 95 177 3 1159626816 60132288 19414704
05:29:06 1195 8192000000 10050635088 10436206592 1076316688 11.612 100 85 163 3 1161743712 68357024 17070000
05:30:08 1505 8192000000 10033743120 10423386112 1063862976 11.493 100 74 146 2 1142803856 63572352 15368528
05:31:11 3334 8192000000 10026996128 10415124480 1050311792 11.364 99 72 134 2 1138730528 75007680 13411056
05:32:15 932 8192000000 10022202032 10403078144 1052473216 11.384 100 88 172 0 1133252736 61838560 18940960
05:33:16 1206 8192000000 9949357168 10314207232 986509248 10.748 98 89 162 3 1060411248 58580144 15321856
05:34:18 1283 8192000000 9974619568 10346426368 1023695056 11.108 100 91 176 1 1088228704 45957488 18576160
05:35:20 1332 8192000000 9980834048 10353700864 1021689872 11.088 100 94 183 0 1093394480 52966752 18737856
05:36:23 1236 8192000000 9985121984 10363662336 1017898384 11.052 99 82 161 2 1099654640 64491872 17264384
05:37:24 29831 8192000000 9988565280 10373357568 1036729152 11.233 100 58 112 1 1107780176 59683616 11367408
05:39:00 4448 8192000000 10013725056 10391371776 1049356672 11.355 100 79 143 1 1121400800 58755616 13288512
05:40:10 1527 8192000000 10041796528 10429673472 1066362480 11.517 100 75 132 0 1151055424 71696080 12996864
05:41:15 7316 8192000000 10099160816 10490011648 1091370912 11.756 100 78 145 0 1215942160 109203664 15367584
05:42:32 15506 8192000000 10137422912 10527834112 1106836848 11.902 100 79 149 1 1249831776 127264496 15730432
05:43:54 9052 8192000000 10181183472 10575450112 1117984704 12.008 100 82 153 1 1297448656 164130576 15333376
05:45:16 1997 8192000000 10201455280 10593619968 1134322480 12.162 100 79 144 1 1310456976 161217568 14916928
05:46:21 994 8192000000 10264474112 10657034240 1152816800 12.336 100 88 164 2 1370449200 199854496 17777904
05:47:27 3357 8192000000 10288520496 10687348736 1174971648 12.543 100 79 151 0 1405376320 213624208 16780464
05:48:36 919 8192000000 10340076976 10737397760 1187254416 12.658 100 95 183 2 1447856176 240439712 20162048
05:49:41 1301 8192000000 10386065008 10790461440 1192176288 12.704 100 96 184 0 1498719696 285717392 20826016
05:50:47 903 8192000000 10447584464 10855985152 1189957184 12.683 100 96 187 0 1556145296 344922240 21265872
05:51:49 1661 8192000000 10438434432 10879434752 1186625632 12.652 100 85 166 0 1551354928 345472816 19256480
05:52:58 1177 8192000000 10450146352 10868535296 1195288992 12.733 100 97 190 5 1556076864 338759536 22028336
05:54:01 1111 8192000000 10462799040 10912215040 1195502768 12.735 100 98 190 2 1567145488 350192288 21450432
05:55:05 1107 8192000000 10442364832 10866319360 1201568896 12.791 100 92 183 0 1552045520 328995360 21481264
05:56:09 853 8192000000 10482030432 10894643200 1183199136 12.620 99 92 179 1 1588807360 384002560 21605664
05:57:12 3664 8192000000 10485730016 10915938304 1189705104 12.681 100 72 141 0 1598933024 391715088 17512832
05:58:18 1417 8192000000 10522017184 10949447680 1188610816 12.670 100 96 185 0 1629074032 418106848 22356368
05:59:21 1004 8192000000 10536185920 10961309696 1189788256 12.681 100 96 181 2 1643239216 429048480 24402480
06:00:25 2737 8192000000 10584036048 11026001920 1188334704 12.668 100 79 154 0 1695321488 487203616 19783168
06:01:35 1803 8192000000 10555870992 11040854016 1189175888 12.676 100 68 136 1 1656655648 450718336 16761424
06:02:43 20053 8192000000 10432682832 10903322624 1178495328 12.576 99 42 83 2 1580736576 390847200 11394048
06:04:09 18348 8192000000 10375294592 10891468800 1171317904 12.509 100 26 52 0 1518737936 340236064 7183968
06:05:28 29670 8192000000 10311854352 10770710528 1158985920 12.394 100 27 54 3 1419548448 253735984 6826544
06:07:00 37263 8192000000 10123889824 10561200128 1082943104 11.676 94 20 39 1 1184767408 97344624 4479680
06:08:40 18811 8192000000 10109325792 10538348544 1137110192 12.188 100 60 111 0 1210015424 58638048 14267184
06:10:00 61439 8192000000 10113392128 10556329984 1108741968 11.921 96 32 56 2 1171377776 56653568 5982240
06:12:10 56459 8192000000 10056512704 10512437248 1091493824 11.757 96 44 82 3 1121239568 20977040 8768704
06:14:08 42051 8192000000 10055319680 10498805760 1127216848 12.095 100 41 74 1 1158468704 23703616 7548240
06:15:51 27913 8192000000 10075863824 10521219072 1133212544 12.152 99 47 94 0 1169268144 25916336 10139264
06:17:25 39527 8192000000 10086739120 10534506496 1139546432 12.211 99 22 42 2 1185794160 41831520 4416208
06:19:06 16151 8192000000 10129038544 10574016512 1147420912 12.285 100 49 92 2 1230341680 72893424 10027344
06:20:24 31533 8192000000 10130844144 10590531584 1136797424 12.185 99 45 90 1 1253869328 107611088 9460816
06:21:58 103405 8192000000 10109269312 10586996736 1099874992 11.836 96 15 30 0 1189285744 85425136 3985616
06:24:45 22399 8192000000 10108136160 10545049600 1133924144 12.158 100 38 74 1 1202544704 60557856 8062704
06:26:10 96874 8192000000 10083621392 10576093184 1106709616 11.901 97 8 16 1 1149760848 41000064 2051168
06:28:47 23043 8192000000 10098658992 10538864640 1127549568 12.098 98 19 38 0 1186535008 54548704 4436736
06:30:15 62347 8192000000 10179304480 10641215488 1140826992 12.223 100 34 65 1 1268920928 121428736 6665200
06:32:33 59422 8192000000 10089913600 10568785920 1100481936 11.842 97 24 48 0 1178049600 72443424 5124240
06:34:32 82455 8192000000 10096223712 10563719168 1116240400 11.991 98 19 38 0 1165139536 44365696 4533440
06:36:56 24802 8192000000 10084253408 10519134208 1114697264 11.977 98 47 94 0 1159348512 34439568 10211680
06:38:24 18591 8192000000 10087379504 10507419648 1099035808 11.828 97 52 100 4 1160688288 50924656 10727824
06:39:44 68190 8192000000 10048694336 10498646016 1102348992 11.860 98 35 70 0 1157017536 46879216 7789328
06:41:52 3183 8192000000 10048967536 10473037824 1110026352 11.933 99 47 90 0 1146116688 27509504 8580832
06:42:56 80877 8192000000 10053609984 10507931648 1090575376 11.748 98 14 28 2 1145285184 52048528 2661280
06:45:17 68409 8192000000 10109015920 10563457024 1120562768 12.032 99 15 30 0 1197915232 73872032 3480432
06:48:59 40424 8192000000 10067735200 10506059776 1090625456 11.749 96 19 36 0 1130870976 36362640 3882880
06:50:43 21468 8192000000 10092013584 10529685504 1111890544 11.950 98 38 74 2 1184282176 64588656 7802976
06:52:07 20799 8192000000 10122566800 10547068928 1125834368 12.082 100 40 80 2 1223479312 89362032 8282912
06:53:28 50704 8192000000 10142817856 10642944000 1103906784 11.875 98 29 58 1 1209042352 99282000 5853568
06:55:20 36805 8192000000 10097550352 10519482368 1081569376 11.662 96 28 56 0 1149883088 62092112 6221600
06:56:58 64202 8192000000 10105344224 10577924096 1130587216 12.127 100 35 70 1 1215232656 77258688 7386752
06:59:02 33499 8192000000 10107973904 10551435264 1116723824 11.996 98 48 94 1 1204128384 78248848 9155712
07:00:38 114040 8192000000 10092053152 10539335680 1105587104 11.891 97 51 85 2 1207803712 93721968 8494640
07:03:33 68067 8192000000 10083781328 10573664256 1124214528 12.067 99 8 17 1 1175178720 49157536 1806656
07:05:48 106878 8192000000 10092799648 10568237056 1056036208 11.419 93 15 30 1 1148825856 89651152 3138496
07:10:01 43619 8192000000 10111205328 10570039296 1129559360 12.117 100 26 52 1 1192042304 56592256 5890688
07:11:45 37334 8192000000 10122576624 10554765312 1127980736 12.102 99 36 70 2 1202542016 66808048 7753232
07:13:23 48372 8192000000 10148733360 10589564928 1143704224 12.250 100 38 74 1 1233970992 82568320 7698448
07:15:21 46216 8192000000 10133513296 10627997696 1121329152 12.040 99 30 60 2 1250634064 122877872 6427040
07:17:07 32853 8192000000 10110577344 10529320960 1108028832 11.914 97 48 96 1 1157602784 39919712 9654240
07:18:41 34799 8192000000 10074369888 10501812224 1119922848 12.026 99 34 65 2 1162036944 35995632 6118464
07:20:19 73347 8192000000 10058601360 10499219456 1116425312 11.993 98 48 92 2 1153064288 26585760 10053216
07:22:32 70327 8192000000 10047689024 10524008448 1100299072 11.840 97 17 34 0 1125555344 21950960 3305312
07:24:43 44098 8192000000 10120769680 10576232448 1145707936 12.269 100 25 48 3 1202342224 51416960 5217328
07:26:56 43511 8192000000 10155666992 10596315136 1104494112 11.880 96 29 59 0 1225211520 114049696 6667712
07:28:42 32125 8192000000 10176653600 10636627968 1158344128 12.388 100 27 54 0 1281957184 116664240 6948816
07:30:14 22478 8192000000 10145102176 10579963904 1100133392 11.839 95 42 83 2 1214891920 105817920 8940608
07:31:39 44215 8192000000 10260203760 10698088448 1148152448 12.292 99 30 60 1 1330117200 174714800 7249952
07:33:29 61880 8192000000 10240070432 10725466112 1105762416 11.892 97 25 50 2 1314986832 203520736 5703680
07:35:34 39644 8192000000 10136015120 10655698944 1121630128 12.042 100 22 44 0 1230988368 104559056 4799184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-06-2022 07:38:21 to 13-06-2022 07:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-06-2022 07:38:38 to 13-06-2022 07:38:40.

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          229553
MON$OLDEST_ACTIVE               230295
MON$OLDEST_SNAPSHOT             230295
MON$NEXT_TRANSACTION            230295
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                       2064032
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             792
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 ---
:142: 
:143: mscs-test-161.budzdorov.ru    Mon Jun 13 07:37:21 2022
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:145: 
:146: 
:147: mscs-test-161.budzdorov.ru    Mon Jun 13 07:37:28 2022
:148:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:149: 
:150: 
:151: mscs-test-161.budzdorov.ru    Mon Jun 13 07:37:30 2022
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:153: 
--- end of diff output ---

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

13.06.22 07:38:41. 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-13 07:38:44 No severe PSQL-related problems occured
13.06.22 07:38:45. End of report.