Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.05.2022 10: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 16410700 kB
MemAvailable 31454308 kB
Buffers 0 kB
Cached 15263116 kB
SwapCached 2552 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-05-08 10:37:16
DTS_END                         2022-05-08 10:37:16.5660
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(76:13) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60352
TRN_ID                          225091
ATT_ID                          568
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: 9204.13 27751 1104495
customer order: creation 169.75 414 20370
customer order: refuse 14.75 380 1770
order to supplier: creation 47.03 1260 5644
order to supplier: removal 3.50 146015 420
invoice (draft): creation 44.88 2983 5386
invoice (draft): removal 52.33 986 6279
invoice accept: apply 42.32 25211 5078
invoice accept: cancel 46.55 1111 5586
customer reserve: creation 3957.88 240 474946
customer reserve: removal 4524.74 86 542969
realization accept: apply 84.25 243 10110
realization accept: cancel 84.48 149 10138
payment from customer: creation 59.67 156 7160
payment from customer: removal 6.03 92 723
payment to supplier: creation 49.57 181 5948
payment to supplier: removal 3.74 99 449
service: total inventory turnovers 4.14 3485 497
service: total monetary turnovers 7.63 308 916
service: aggregate perf. data 0.85 16281 102
service: refresh index statistics 0.03 355332 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 10:38:05 to 08-05-2022 10:38:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9967
TEST_TIME 2 6294
TEST_TIME 3 6543
TEST_TIME 4 6153
TEST_TIME 5 6052
TEST_TIME 6 5826
TEST_TIME 7 5522
TEST_TIME 8 5461
TEST_TIME 9 5795
TEST_TIME 10 5845
TEST_TIME 11 6159
TEST_TIME 12 6162
TEST_TIME 13 6510
TEST_TIME 14 6662
TEST_TIME 15 6806
TEST_TIME 16 7043
TEST_TIME 17 7173
TEST_TIME 18 7253
TEST_TIME 19 7361
TEST_TIME 20 7285
TEST_TIME 21 7499
TEST_TIME 22 7929
TEST_TIME 23 8127
TEST_TIME 24 8427
TEST_TIME 25 9175
TEST_TIME 26 9057
TEST_TIME 27 9665
TEST_TIME 28 10389
TEST_TIME 29 10669
TEST_TIME 30 10859
TEST_TIME 31 11130
TEST_TIME 32 11178
TEST_TIME 33 10949
TEST_TIME 34 10917
TEST_TIME 35 10863
TEST_TIME 36 10875
TEST_TIME 37 10684
TEST_TIME 38 10542
TEST_TIME 39 10367
TEST_TIME 40 10270
TEST_TIME 41 10158
TEST_TIME 42 10092
TEST_TIME 43 10173
TEST_TIME 44 10090
TEST_TIME 45 10027
TEST_TIME 46 9918
TEST_TIME 47 9908
TEST_TIME 48 9849
TEST_TIME 49 9841
TEST_TIME 50 9905
TEST_TIME 51 9974
TEST_TIME 52 9975
TEST_TIME 53 9980
TEST_TIME 54 10023
TEST_TIME 55 10119
TEST_TIME 56 10068
TEST_TIME 57 10030
TEST_TIME 58 10048
TEST_TIME 59 10032
TEST_TIME 60 10144
TEST_TIME 61 10092
TEST_TIME 62 9955
TEST_TIME 63 9850
TEST_TIME 64 9804
TEST_TIME 65 9777
TEST_TIME 66 9695
TEST_TIME 67 9608
TEST_TIME 68 9715
TEST_TIME 69 9608
TEST_TIME 70 9534
TEST_TIME 71 9514
TEST_TIME 72 9490
TEST_TIME 73 9495
TEST_TIME 74 9456
TEST_TIME 75 9533
TEST_TIME 76 9511
TEST_TIME 77 9514
TEST_TIME 78 9521
TEST_TIME 79 9555
TEST_TIME 80 9545
TEST_TIME 81 9529
TEST_TIME 82 9590
TEST_TIME 83 9605
TEST_TIME 84 9611
TEST_TIME 85 9597
TEST_TIME 86 9500
TEST_TIME 87 9451
TEST_TIME 88 9446
TEST_TIME 89 9401
TEST_TIME 90 9342
TEST_TIME 91 9353
TEST_TIME 92 9280
TEST_TIME 93 9250
TEST_TIME 94 9304
TEST_TIME 95 9303
TEST_TIME 96 9270
TEST_TIME 97 9232
TEST_TIME 98 9234
TEST_TIME 99 9230
TEST_TIME 100 9271
TEST_TIME 101 9299
TEST_TIME 102 9373
TEST_TIME 103 9338
TEST_TIME 104 9354
TEST_TIME 105 9404
TEST_TIME 106 9330
TEST_TIME 107 9312
TEST_TIME 108 9271
TEST_TIME 109 9239
TEST_TIME 110 9255
TEST_TIME 111 9231
TEST_TIME 112 9197
TEST_TIME 113 9179
TEST_TIME 114 9223
TEST_TIME 115 9205
TEST_TIME 116 9225
TEST_TIME 117 9278
TEST_TIME 118 9257
TEST_TIME 119 9252
TEST_TIME 120 9229

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-05-2022 10:38:09 to 08-05-2022 10:38:12.

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 1539346 1539346 0 0.00 0 13327 12 0 0
doc_list_biud 1539344 1539344 0 0.00 0 50460 36 0 0
sp_add_invoice_to_stock 5208 5078 130 2.49 55 178852 25211 0 130
sp_cancel_adding_invoice 5586 5586 0 0.00 7 68907 1111 0 0
sp_cancel_client_order 1770 1770 0 0.00 6 16409 381 0 0
sp_cancel_customer_reserve 542969 542969 0 0.00 1 50473 87 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 4 2900 92 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 3642 99 0 0
sp_cancel_supplier_invoice 6291 6279 12 0.19 3 45429 986 0 12
sp_cancel_supplier_order 420 420 0 0.00 13 1482872 146016 0 0
sp_cancel_write_off 10138 10138 0 0.00 2 33204 149 0 0
sp_client_order 20370 20370 0 0.00 6 32508 414 0 0
sp_customer_reserve 474946 474946 0 0.00 1 93972 240 0 0
sp_fill_shopping_cart 31399 31399 0 0.00 1 11957 78 0 0
sp_fill_shopping_cart_clo_res 474930 474930 0 0.00 0 14718 17 0 0
sp_get_clo_for_invoice 5044 5044 0 0.00 0 14508 289 0 0
sp_kill_cost_storno 16896 16896 0 0.00 0 7571 60 0 0
sp_kill_qstorno_ret_qs2qd 1102775 1102775 0 0.00 0 50455 38 0 0
sp_kill_qty_storno 582316 582316 0 0.00 0 50459 88 0 0
sp_lock_selected_doc 21103 21103 0 0.00 0 8554 6 0 0
sp_make_cost_storno 28262 28262 0 0.00 0 13319 70 0 0
sp_multiply_rows_for_pdistr 28262 28262 0 0.00 0 6968 32 0 0
sp_multiply_rows_for_qdistr 20370 20370 0 0.00 0 17023 149 0 0
sp_pay_from_customer 7160 7160 0 0.00 5 7762 157 0 0
sp_pay_to_supplier 5948 5948 0 0.00 7 6913 181 0 0
sp_payment_common 13108 13108 0 0.00 2 6079 38 0 0
sp_qd_handle_on_cancel_clo 1770 1770 0 0.00 1 16400 315 0 0
sp_qd_handle_on_invoice_upd_sts 10630 10630 0 0.00 0 31539 471 0 0
sp_qd_handle_on_reserve_upd_sts 10110 10110 0 0.00 0 17719 56 0 0
sp_reserve_write_off 10110 10110 0 0.00 9 17754 243 0 0
sp_supplier_invoice 5386 5386 0 0.00 9 65966 2984 0 0
sp_supplier_order 5644 5644 0 0.00 8 25303 1260 0 0
srv_aggregate_perf_data 102 102 0 0.00 2396 125574 16281 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 745 134310 38541 0 0
srv_find_qd_qs_mism 1053696 1053696 0 0.00 0 10167 10 0 0
srv_make_invnt_saldo 497 497 0 0.00 78 63666 3485 0 0
srv_make_money_saldo 916 916 0 0.00 1 12363 309 0 0
srv_recalc_idx_stat 4 4 0 0.00 194805 551546 355332 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5080 5080 0 0.00 0 16606 811 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 5977 432 0 0
t$perf-norm:sp_cancel_client_order 1770 1770 0 0.00 0 16498 34 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 2063 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 2837 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 10805 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2778 40 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 68610 6036 0 0
t$perf-norm:sp_cancel_write_off 1089 1089 0 0.00 0 2180 18 0 0
t$perf-norm:sp_client_order 20370 20370 0 0.00 0 9329 32 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1172 14 0 0
t$perf-norm:sp_pay_from_customer 7160 7160 0 0.00 0 3359 18 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 8999 21 0 0
t$perf-norm:sp_reserve_write_off 10110 10110 0 0.00 0 11239 24 0 0
t$perf-norm:sp_supplier_invoice 5386 5386 0 0.00 0 5761 31 0 0
t$perf-norm:sp_supplier_order 5644 5644 0 0.00 0 14140 32 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 193 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 696 13 0 0
t$perf-norm:srv_make_invnt_saldo 497 497 0 0.00 0 688 11 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 603 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 198 113 0 0
v_add_invoice_to_stock 10348 10088 260 2.51 0 4060 21 0 260
v_all_customers 37208 37208 0 0.00 0 154 3 0 0
v_all_suppliers 30942 30942 0 0.00 0 84 4 0 0
v_all_wares 40740 40740 0 0.00 0 102 3 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 2260 15 0 0
v_cancel_client_order 3540 3540 0 0.00 0 2783 11 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 2865 10 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 303 8 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 3629 15 0 0
v_cancel_supplier_invoice 868 844 24 2.76 0 1957 24 0 24
v_cancel_supplier_order 840 840 0 0.00 0 2209 14 0 0
v_cancel_write_off 2178 2178 0 0.00 0 3318 25 0 0
v_max_id_clo_ord 5644 5644 0 0.00 0 4168 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 4783 20 0 0
v_max_id_ord_sup 5385 5385 0 0.00 0 7288 7 0 0
v_max_non_paid_invoice 1506 1506 0 0.00 0 787 7 0 0
v_max_non_paid_realizn 3758 3758 0 0.00 0 767 6 0 0
v_min_id_clo_ord 5644 5644 0 0.00 0 2079 7 0 0
v_min_id_clo_res 989 989 0 0.00 0 6997 23 0 0
v_min_id_ord_sup 5385 5385 0 0.00 0 7026 12 0 0
v_min_non_paid_invoice 5948 5948 0 0.00 0 2509 11 0 0
v_min_non_paid_realizn 7160 7160 0 0.00 0 2665 13 0 0
v_our_firm 10336 10336 0 0.00 0 155 4 0 0
v_reserve_write_off 20218 20218 0 0.00 0 4221 11 0 0
x_lock_depdocs_on_canc_invoice 11443 11443 0 0.00 0 20240 126 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 7747 358 0 0
x_make_qstorno_1000_1200 5644 5644 0 0.00 3 25260 1108 0 0
x_make_qstorno_1200_2000 5386 5386 0 0.00 4 56083 2761 0 0
x_make_qstorno_2100_3300 474945 474945 0 0.00 0 93968 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 10:38:13 to 08-05-2022 10:38:17.

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
08:07:20 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 20725 8192000000 9712638208 10009186304 788265744 8.777 100 89 178 0 817836400 7806224 21764432
08:09:49 27318 8192000000 9759269168 10065391616 829595712 9.195 100 91 182 0 865250528 13871168 21783648
08:11:23 15065 8192000000 9804862048 10123165696 863056496 9.531 100 83 166 0 910818736 28570992 19191248
08:12:47 8351 8192000000 9881524224 10215399424 924562112 10.141 100 87 167 1 985877168 43286592 18028464
08:13:58 7399 8192000000 9934465264 10277031936 958696128 10.476 100 81 154 0 1038133632 63036896 16400608
08:15:09 2572 8192000000 9970404800 10324189184 998749328 10.866 100 93 184 0 1086751088 69325632 18676128
08:16:13 1363 8192000000 10001163440 10365751296 1021214336 11.084 100 95 183 0 1126181216 85419792 19547088
08:17:16 2367 8192000000 10002249648 10370420736 1039244912 11.257 100 82 158 1 1123544912 69168192 15131808
08:18:20 1572 8192000000 10047027792 10424201216 1065302848 11.507 100 96 185 0 1164412368 80560640 18548880
08:19:22 719 8192000000 10036908000 10416893952 1076489520 11.614 100 96 180 0 1156877088 61999696 18387872
08:20:24 1031 8192000000 10044620048 10426613760 1078226768 11.631 100 89 174 0 1164912832 68153184 18532880
08:21:26 6431 8192000000 10039869296 10430324736 1084737536 11.693 100 63 123 1 1172078496 75118272 12222688
08:22:33 3601 8192000000 10041403216 10430128128 1087302704 11.717 100 74 134 3 1163373264 63736112 12334448
08:23:37 3915 8192000000 10056488432 10447540224 1092326528 11.765 100 72 136 6 1173832112 66558752 14946832
08:24:42 971 8192000000 10046753728 10433822720 1099709344 11.835 100 87 171 2 1161474464 45062000 16703120
08:25:43 1009 8192000000 10088366464 10477494272 1100557168 11.843 100 86 164 0 1199257360 82787200 15912992
08:26:45 1300 8192000000 10057517920 10449784832 1103880032 11.874 100 88 176 2 1172390864 50526832 17984000
08:27:47 2542 8192000000 10054545376 10447491072 1089506240 11.738 99 85 164 0 1177717616 71463200 16748176
08:28:51 1387 8192000000 10049784032 10439929856 1101174112 11.849 100 100 186 1 1169205696 49647136 18384448
08:29:53 1213 8192000000 10034838448 10420019200 1077344816 11.622 99 92 169 1 1157656624 61000496 19311312
08:30:56 1039 8192000000 10049470160 10432868352 1079821008 11.646 100 94 173 2 1170010912 70771856 19418048
08:31:58 1364 8192000000 9998470896 10370531328 1038717088 11.252 99 95 182 1 1116467408 59713840 18036480
08:33:00 5558 8192000000 9973124016 10352852992 1026023456 11.130 98 55 102 4 1083331760 46549104 10759200
08:34:06 4881 8192000000 9952476288 10321641472 1008985856 10.966 97 62 118 2 1070890448 48645888 13258704
08:35:12 7916 8192000000 9978255824 10357018624 1039475392 11.260 99 64 125 2 1122812240 70448480 12888368
08:36:21 9103 8192000000 9973317936 10370150400 1037414320 11.240 99 54 103 1 1086197232 36992848 11790064
08:37:33 16081 8192000000 9966708672 10345766912 1044465920 11.308 100 64 117 3 1097833024 40720288 12646816
08:38:58 18921 8192000000 10026619840 10418462720 1063309072 11.488 100 62 113 1 1144607744 69792160 11506512
08:40:30 6269 8192000000 10031482448 10420338688 1082637072 11.673 100 72 138 1 1157144672 60467744 14039856
08:41:47 11582 8192000000 10087202736 10468782080 1094694368 11.787 100 77 147 5 1201470560 91168816 15607376
08:43:02 7282 8192000000 10121210704 10519347200 1113575216 11.966 100 72 143 0 1241141568 112533312 15033040
08:44:15 5896 8192000000 10158206096 10551029760 1126892912 12.092 100 69 131 1 1274988544 133494704 14600928
08:45:35 5759 8192000000 10212058288 10604482560 1132893936 12.149 100 84 165 1 1325447616 175314800 17238880
08:46:45 11734 8192000000 10245458448 10650927104 1145343392 12.266 100 68 132 0 1364956768 204673728 14939648
08:48:04 1686 8192000000 10287666192 10696122368 1166094816 12.460 100 90 175 3 1399413696 214265712 19053168
08:49:11 1322 8192000000 10325094560 10727911424 1182285728 12.612 100 92 172 3 1438980832 236644544 20050560
08:50:14 735 8192000000 10346251216 10758336512 1184422864 12.631 100 92 180 0 1456489456 251176976 20889616
08:51:18 1636 8192000000 10374361552 10786439168 1189220288 12.676 100 90 171 0 1485576784 275996400 20360096
08:52:23 1060 8192000000 10416294608 10830413824 1194992688 12.730 100 98 193 3 1524030864 306911792 22126384
08:53:29 2656 8192000000 10441780688 10854006784 1204227008 12.816 100 93 182 0 1547124624 322361344 20536272
08:54:36 1346 8192000000 10483334304 10890829824 1211888768 12.887 100 100 196 1 1589246448 353493408 23864272
08:55:40 912 8192000000 10525720928 10934956032 1192874496 12.710 100 96 188 0 1628487040 412720432 22892112
08:56:45 1345 8192000000 10547182752 10955087872 1197631280 12.754 100 96 184 1 1653748752 433859296 22258176
08:57:48 945 8192000000 10594275088 11025899520 1202263728 12.797 100 86 168 0 1697575824 474206464 21105632
08:58:51 1175 8192000000 10558829008 11024674816 1193496224 12.716 100 80 157 2 1670886384 458214240 19175920
08:59:55 9283 8192000000 10564037520 11012743168 1171249040 12.509 99 51 102 0 1671624560 486831904 13543616
09:01:06 14810 8192000000 10532773664 11007787008 1168197536 12.480 100 46 88 1 1645560208 465248208 12114464
09:02:43 37920 8192000000 10442104288 10911125504 1161210048 12.415 100 23 46 0 1527524496 360067008 6247440
09:04:49 10196 8192000000 10321639648 10825715712 1160467696 12.408 100 26 52 0 1442092784 274635408 6989680
09:06:04 25873 8192000000 10215884288 10654097408 1131746992 12.138 97 38 74 2 1280223664 139654144 8822528
09:07:48 61862 8192000000 10130140720 10648649728 1114934560 11.979 97 14 28 0 1241491104 123093280 3463264
09:09:51 35124 8192000000 10064221568 10497794048 1123350688 12.059 99 42 76 1 1156063744 23687968 9025088
09:11:26 42662 8192000000 10087450512 10522722304 1120904976 12.036 99 46 84 1 1168170896 37736544 9529376
09:13:13 134308 8192000000 10065382032 10511429632 1100353792 11.841 97 23 46 1 1190623696 85192064 5077840
09:16:34 13545 8192000000 10086329968 10525925376 1121184864 12.038 99 44 87 0 1177787200 47907280 8695056
09:18:04 9223 8192000000 10077710496 10507460608 1115879856 11.988 99 44 86 2 1168572592 43106832 9585904
09:19:16 34999 8192000000 10141748704 10591555584 1116679088 11.996 98 40 80 1 1231821888 106149360 8993440
09:20:59 93563 8192000000 10138194176 10621894656 1128390624 12.106 98 18 34 1 1240888608 108827504 3670480
09:23:33 70552 8192000000 10138165360 10583560192 1115576288 11.985 97 31 62 1 1242006864 119567216 6863360
09:25:45 70186 8192000000 10127222752 10580361216 1127817616 12.101 99 8 16 0 1219450528 89935104 1697808
09:27:58 37845 8192000000 10110694224 10600407040 1129593136 12.118 99 38 75 1 1219208048 79917936 9696976
09:29:40 35357 8192000000 10142808112 10610036736 1143470032 12.248 100 31 62 3 1232054064 81548576 7035456
09:31:25 67104 8192000000 10069207840 10564530176 1126427504 12.088 99 20 40 1 1217678768 85837568 5413696
09:33:37 27567 8192000000 10101398432 10532581376 1124240720 12.067 98 54 95 1 1175096672 40558368 10297584
09:35:08 37701 8192000000 10115345168 10568024064 1112083792 11.952 97 35 70 0 1193657248 74377776 7195680
09:36:48 97547 8192000000 10110745840 10591559680 1106728832 11.901 97 20 40 2 1205524512 94320592 4475088
09:39:25 107266 8192000000 10092993776 10517843968 1101496736 11.852 97 26 50 0 1151408560 44211264 5700560
09:42:13 59302 8192000000 10105720912 10572070912 1120608672 12.033 99 27 54 2 1209843392 84046912 5187808
09:44:23 54518 8192000000 10097107136 10594254848 1134743024 12.166 100 11 22 0 1233098928 95907392 2448512
09:46:37 35095 8192000000 10061844272 10514509824 1110262352 11.935 98 10 21 0 1145437888 33097024 2078512
09:48:13 66383 8192000000 10093892960 10546982912 1121484560 12.041 98 19 36 0 1171416208 46577552 3354096
09:50:20 76725 8192000000 10097289792 10544136192 1090306256 11.746 95 16 32 0 1157565040 64278992 2979792
09:52:39 49223 8192000000 10079409040 10525749248 1130882800 12.130 100 27 54 1 1167804240 31537872 5383568
09:54:32 51819 8192000000 10145925120 10611445760 1134671984 12.165 100 37 74 0 1215014160 72624768 7717408
09:56:24 15266 8192000000 10093913776 10527055872 1113030112 11.961 99 34 66 0 1181202656 61728192 6444352
09:57:40 36217 8192000000 10066742208 10491953152 1052590272 11.386 94 30 58 0 1100771136 42677040 5503824
09:59:32 71563 8192000000 10105035424 10534326272 1077573936 11.624 96 35 66 0 1156462224 71904720 6983568
10:01:43 113559 8192000000 10089927136 10575097856 1113877952 11.969 99 28 56 0 1203793792 84729120 5186720
10:05:11 38410 8192000000 10089355088 10536628224 1114904336 11.979 100 27 50 3 1179890992 59711504 5275152
10:06:58 51635 8192000000 10090954144 10549395456 1117068944 11.999 99 13 26 0 1173406448 53693888 2643616
10:08:50 60062 8192000000 10101121584 10552909824 1133084048 12.150 100 17 34 2 1195947376 59062240 3801088
10:11:05 67848 8192000000 10078509616 10563215360 1104136736 11.877 98 20 41 1 1162382816 54108672 4137408
10:13:31 20279 8192000000 10084396160 10510168064 1101416736 11.851 98 45 80 2 1160209152 49004544 9787872
10:14:57 24820 8192000000 10126822080 10562310144 1123076912 12.056 100 31 62 2 1229345328 100063632 6204784
10:16:23 46708 8192000000 10148355808 10584698880 1085686928 11.702 97 32 64 1 1229909952 137081488 7141536
10:18:10 53931 8192000000 10113428640 10541629440 1089682960 11.740 98 30 60 2 1169127552 73169408 6275184
10:20:08 36332 8192000000 10079280384 10507825152 1087267888 11.717 98 42 84 0 1138078624 42413520 8397216
10:21:44 49572 8192000000 10063058640 10539646976 1101713888 11.854 99 29 58 0 1174759776 66919136 6126752
10:23:42 77092 8192000000 10040273872 10510036992 1109933824 11.932 99 18 35 2 1135359312 22166048 3259440
10:25:59 68474 8192000000 10106702848 10553012224 1098177552 11.820 97 37 54 3 1132400048 29865312 4357184
10:29:17 20140 8192000000 10090010208 10521583616 1124646624 12.071 99 52 100 0 1186319968 50776016 10897328
10:30:48 97294 8192000000 10106032240 10555961344 1146569760 12.277 100 15 31 0 1191157616 41413648 3174208
10:33:31 62118 8192000000 10137436208 10585976832 1125908912 12.083 98 21 42 1 1200605712 70261664 4435136
10:35:38 43361 8192000000 10111036880 10563137536 1152411840 12.332 100 41 78 2 1225628672 64322352 8894480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-05-2022 10:38:19 to 08-05-2022 10:38:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 130 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-05-2022 10:38:36 to 08-05-2022 10:38:38.

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          223974
MON$OLDEST_ACTIVE               225240
MON$OLDEST_SNAPSHOT             225240
MON$NEXT_TRANSACTION            225240
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                       2047712
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2763 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2763 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2763 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2763 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 ---
:103: 
:104: mscs-test-161.budzdorov.ru    Sun May  8 10:37:18 2022
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
:107: 
:108: mscs-test-161.budzdorov.ru    Sun May  8 10:37:18 2022
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Sun May  8 10:37:18 2022
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Sun May  8 10:37:34 2022
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:118: 
:119: 
:120: mscs-test-161.budzdorov.ru    Sun May  8 10:37:34 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
--- end of diff output ---

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

08.05.22 10:38:39. 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-05-08 10:38:41 No severe PSQL-related problems occured
08.05.22 10:38:42. End of report.