Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.10.2022 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15079288 kB
MemAvailable 30392188 kB
Buffers 20 kB
Cached 16555864 kB
SwapCached 1980 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.3
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-10-26 04:37:22
DTS_END                         2022-10-26 04:37:22.5460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60644
TRN_ID                          214369
ATT_ID                          629
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: 8900.73 25263 1068088
customer order: creation 169.05 505 20286
customer order: refuse 14.66 500 1759
order to supplier: creation 45.13 1482 5415
order to supplier: removal 3.38 161632 406
invoice (draft): creation 43.97 3335 5276
invoice (draft): removal 48.28 1013 5793
invoice accept: apply 40.88 28033 4905
invoice accept: cancel 43.93 1284 5271
customer reserve: creation 3876.87 259 465224
customer reserve: removal 4342.92 99 521150
realization accept: apply 81.07 306 9728
realization accept: cancel 62.73 199 7527
payment from customer: creation 58.03 191 6964
payment from customer: removal 5.52 141 662
payment to supplier: creation 48.55 221 5826
payment to supplier: removal 3.45 134 414
service: total inventory turnovers 4.02 4187 482
service: total monetary turnovers 7.32 425 878
service: aggregate perf. data 0.98 16316 118
service: refresh index statistics 0.03 285000 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-10-2022 04:38:41 to 26-10-2022 04:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10065
TEST_TIME 2 8226
TEST_TIME 3 6966
TEST_TIME 4 6548
TEST_TIME 5 6836
TEST_TIME 6 6752
TEST_TIME 7 6830
TEST_TIME 8 6494
TEST_TIME 9 6720
TEST_TIME 10 6513
TEST_TIME 11 6496
TEST_TIME 12 6604
TEST_TIME 13 6464
TEST_TIME 14 6655
TEST_TIME 15 6899
TEST_TIME 16 7159
TEST_TIME 17 7478
TEST_TIME 18 7453
TEST_TIME 19 7596
TEST_TIME 20 7806
TEST_TIME 21 7785
TEST_TIME 22 7819
TEST_TIME 23 7991
TEST_TIME 24 8047
TEST_TIME 25 8105
TEST_TIME 26 8223
TEST_TIME 27 8395
TEST_TIME 28 8445
TEST_TIME 29 8471
TEST_TIME 30 8475
TEST_TIME 31 8954
TEST_TIME 32 9257
TEST_TIME 33 9244
TEST_TIME 34 9680
TEST_TIME 35 9866
TEST_TIME 36 10012
TEST_TIME 37 10179
TEST_TIME 38 10372
TEST_TIME 39 10269
TEST_TIME 40 10153
TEST_TIME 41 10097
TEST_TIME 42 9919
TEST_TIME 43 10032
TEST_TIME 44 9986
TEST_TIME 45 9951
TEST_TIME 46 10065
TEST_TIME 47 10083
TEST_TIME 48 10097
TEST_TIME 49 10057
TEST_TIME 50 9990
TEST_TIME 51 9812
TEST_TIME 52 9773
TEST_TIME 53 9619
TEST_TIME 54 9558
TEST_TIME 55 9473
TEST_TIME 56 9452
TEST_TIME 57 9462
TEST_TIME 58 9423
TEST_TIME 59 9408
TEST_TIME 60 9331
TEST_TIME 61 9271
TEST_TIME 62 9215
TEST_TIME 63 9255
TEST_TIME 64 9276
TEST_TIME 65 9361
TEST_TIME 66 9402
TEST_TIME 67 9357
TEST_TIME 68 9388
TEST_TIME 69 9411
TEST_TIME 70 9392
TEST_TIME 71 9440
TEST_TIME 72 9507
TEST_TIME 73 9486
TEST_TIME 74 9429
TEST_TIME 75 9553
TEST_TIME 76 9642
TEST_TIME 77 9535
TEST_TIME 78 9520
TEST_TIME 79 9462
TEST_TIME 80 9405
TEST_TIME 81 9397
TEST_TIME 82 9375
TEST_TIME 83 9329
TEST_TIME 84 9334
TEST_TIME 85 9312
TEST_TIME 86 9316
TEST_TIME 87 9247
TEST_TIME 88 9276
TEST_TIME 89 9220
TEST_TIME 90 9165
TEST_TIME 91 9084
TEST_TIME 92 9107
TEST_TIME 93 9117
TEST_TIME 94 9064
TEST_TIME 95 9088
TEST_TIME 96 9101
TEST_TIME 97 9117
TEST_TIME 98 9133
TEST_TIME 99 9117
TEST_TIME 100 9082
TEST_TIME 101 9101
TEST_TIME 102 9178
TEST_TIME 103 9149
TEST_TIME 104 9246
TEST_TIME 105 9246
TEST_TIME 106 9197
TEST_TIME 107 9154
TEST_TIME 108 9116
TEST_TIME 109 9092
TEST_TIME 110 9030
TEST_TIME 111 9019
TEST_TIME 112 8999
TEST_TIME 113 9025
TEST_TIME 114 9058
TEST_TIME 115 9033
TEST_TIME 116 9011
TEST_TIME 117 8985
TEST_TIME 118 8978
TEST_TIME 119 8985
TEST_TIME 120 8935

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-10-2022 04:38:45 to 26-10-2022 04:38:48.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1493668 1493668 0 0.00 0 24374 14 0 0
doc_list_biud 1493656 1493656 0 0.00 0 39376 41 0 0
sp_add_invoice_to_stock 5048 4905 143 2.83 65 184607 28034 0 143
sp_cancel_adding_invoice 5271 5271 0 0.00 5 78133 1285 0 0
sp_cancel_client_order 1759 1759 0 0.00 7 26882 500 0 0
sp_cancel_customer_reserve 521150 521150 0 0.00 1 30030 99 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 4 7774 142 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 1413 134 0 0
sp_cancel_supplier_invoice 5804 5793 11 0.18 2 18458 1013 0 11
sp_cancel_supplier_order 406 406 0 0.00 10 1433657 161633 0 0
sp_cancel_write_off 7527 7527 0 0.00 2 20389 200 0 0
sp_client_order 20286 20286 0 0.00 5 34077 505 0 0
sp_customer_reserve 465224 465224 0 0.00 0 104332 260 0 0
sp_fill_shopping_cart 30965 30965 0 0.00 1 12638 86 0 0
sp_fill_shopping_cart_clo_res 465189 465189 0 0.00 0 22922 19 0 0
sp_get_clo_for_invoice 4854 4854 0 0.00 0 26643 285 0 0
sp_kill_cost_storno 13874 13874 0 0.00 0 20385 88 0 0
sp_kill_qstorno_ret_qs2qd 1056026 1056026 0 0.00 0 31080 44 0 0
sp_kill_qty_storno 556487 556487 0 0.00 0 39375 101 0 0
sp_lock_selected_doc 20268 20268 0 0.00 0 5922 6 0 0
sp_make_cost_storno 27371 27371 0 0.00 0 18025 89 0 0
sp_multiply_rows_for_pdistr 27371 27371 0 0.00 0 24320 41 0 0
sp_multiply_rows_for_qdistr 20286 20286 0 0.00 0 13167 165 0 0
sp_pay_from_customer 6964 6964 0 0.00 5 11754 192 0 0
sp_pay_to_supplier 5826 5826 0 0.00 7 24379 222 0 0
sp_payment_common 12790 12790 0 0.00 2 6554 48 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 1 26870 411 0 0
sp_qd_handle_on_invoice_upd_sts 10124 10124 0 0.00 0 39374 544 0 0
sp_qd_handle_on_reserve_upd_sts 9728 9728 0 0.00 0 16996 78 0 0
sp_reserve_write_off 9728 9728 0 0.00 8 17022 307 0 0
sp_supplier_invoice 5276 5276 0 0.00 9 105789 3336 0 0
sp_supplier_order 5415 5415 0 0.00 7 40099 1483 0 0
srv_aggregate_perf_data 118 118 0 0.00 2300 264352 16317 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 805 152386 32567 0 0
srv_find_qd_qs_mism 1018156 1018156 0 0.00 0 11425 11 0 0
srv_make_invnt_saldo 482 482 0 0.00 67 81880 4188 0 0
srv_make_money_saldo 878 878 0 0.00 1 16431 425 0 0
srv_recalc_idx_stat 4 4 0 0.00 80042 498611 285001 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4911 4911 0 0.00 1 19053 1082 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 1 8057 557 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 1932 33 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 1592 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 1885 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 2428 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 1913 39 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 102180 7582 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 3740 29 0 0
t$perf-norm:sp_client_order 20287 20287 0 0.00 0 8563 44 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1134 18 0 0
t$perf-norm:sp_pay_from_customer 6964 6964 0 0.00 0 2902 20 0 0
t$perf-norm:sp_pay_to_supplier 5826 5826 0 0.00 0 8002 24 0 0
t$perf-norm:sp_reserve_write_off 9728 9728 0 0.00 0 10928 31 0 0
t$perf-norm:sp_supplier_invoice 5276 5276 0 0.00 0 9816 45 0 0
t$perf-norm:sp_supplier_order 5415 5415 0 0.00 0 10128 37 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 967 32 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 351 12 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 774 18 0 0
t$perf-norm:srv_make_money_saldo 878 878 0 0.00 0 789 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 13 486 145 0 0
v_add_invoice_to_stock 9982 9696 286 2.86 0 13629 27 0 286
v_all_customers 37296 37296 0 0.00 0 67 4 0 0
v_all_suppliers 29982 29982 0 0.00 0 83 5 0 0
v_all_wares 40568 40568 0 0.00 0 110 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 4531 22 0 0
v_cancel_client_order 3518 3518 0 0.00 0 17686 19 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 1952 15 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 385 11 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 1386 14 0 0
v_cancel_supplier_invoice 836 814 22 2.63 0 5967 36 0 22
v_cancel_supplier_order 812 812 0 0.00 0 755 13 0 0
v_cancel_write_off 2020 2020 0 0.00 0 6431 27 0 0
v_max_id_clo_ord 5408 5408 0 0.00 0 501 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 1025 11 0 0
v_max_id_ord_sup 5270 5270 0 0.00 0 4606 8 0 0
v_max_non_paid_invoice 1513 1513 0 0.00 0 1317 9 0 0
v_max_non_paid_realizn 3639 3639 0 0.00 0 1014 8 0 0
v_min_id_clo_ord 5408 5408 0 0.00 0 12631 11 0 0
v_min_id_clo_res 989 989 0 0.00 0 4449 14 0 0
v_min_id_ord_sup 5270 5270 0 0.00 0 4316 8 0 0
v_min_non_paid_invoice 5826 5826 0 0.00 0 6550 19 0 0
v_min_non_paid_realizn 6964 6964 0 0.00 0 4246 17 0 0
v_our_firm 9920 9920 0 0.00 0 107 5 0 0
v_reserve_write_off 19456 19456 0 0.00 0 6128 12 0 0
x_lock_depdocs_on_canc_invoice 10657 10657 0 0.00 0 21397 136 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 12874 384 0 0
x_make_qstorno_1000_1200 5415 5415 0 0.00 2 40084 1306 0 0
x_make_qstorno_1200_2000 5276 5276 0 0.00 5 105760 3094 0 0
x_make_qstorno_2100_3300 465223 465223 0 0.00 0 104330 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-10-2022 04:38:49 to 26-10-2022 04:38:53.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:27 6033 8192000000 9726284096 10022682624 813345696 9.031 100 87 174 0 841135472 7307648 20482128
02:09:40 17525 8192000000 9756132752 10063323136 832013200 9.219 100 86 172 0 862765584 10280528 20471856
02:11:07 13092 8192000000 9784886576 10100867072 857403824 9.474 100 86 171 0 893207024 15885120 19918080
02:12:26 13364 8192000000 9830651856 10161442816 892332272 9.822 100 77 154 0 941693136 31945504 17415360
02:13:43 5964 8192000000 9879116992 10217029632 932772784 10.222 100 88 171 1 995219168 43587888 18858496
02:14:59 5663 8192000000 9951716816 10301337600 966770864 10.555 100 85 169 1 1066132064 81605360 17755840
02:16:08 3029 8192000000 9977169968 10334724096 998409712 10.863 100 82 153 0 1089818032 75106048 16302272
02:17:13 1496 8192000000 9982489808 10346844160 1028813152 11.157 100 90 170 0 1105976144 60081424 17081568
02:18:17 1356 8192000000 10031425216 10401239040 1047285920 11.335 100 96 187 0 1151668144 85642864 18739360
02:19:20 979 8192000000 10035286912 10406973440 1064117072 11.496 100 95 185 1 1155299216 71991824 19190320
02:20:26 1096 8192000000 10045089392 10424213504 1071491728 11.566 100 96 180 1 1161049344 70465104 19092512
02:21:30 1229 8192000000 10050167376 10431098880 1081888224 11.665 100 97 180 3 1167026016 66148400 18989392
02:22:34 1069 8192000000 10055248992 10441826304 1086007760 11.705 100 98 189 1 1185902656 79854448 20040448
02:23:36 1374 8192000000 10077591600 10465792000 1092683392 11.768 100 97 182 3 1206413472 94326528 19403552
02:24:39 1070 8192000000 10084316352 10472837120 1093932832 11.780 100 98 194 2 1207736112 95166544 18636736
02:25:41 1217 8192000000 10056198080 10446962688 1098175584 11.820 100 98 184 0 1178870864 62743984 17951296
02:26:43 1155 8192000000 10076228064 10461134848 1100792688 11.845 100 95 185 0 1198261328 78608032 18860608
02:27:47 1323 8192000000 10081962480 10474295296 1107118528 11.905 100 97 191 1 1204905184 77211280 20575376
02:28:49 1275 8192000000 10074541120 10465308672 1110671456 11.939 100 94 171 1 1193650832 65342144 17637232
02:29:52 988 8192000000 10094868384 10486652928 1095937536 11.799 100 91 180 1 1216222976 102106272 18179168
02:30:55 1523 8192000000 10063404432 10458210304 1096327728 11.803 100 76 144 2 1186807312 76643184 13836400
02:31:58 1004 8192000000 10049247856 10437963776 1102374208 11.860 100 97 181 2 1171681072 50757376 18549488
02:33:00 1055 8192000000 10030916416 10417299456 1075021328 11.600 100 84 156 2 1152373440 60719376 16632736
02:34:03 1134 8192000000 10016264592 10395820032 1053090336 11.390 99 81 158 2 1139148672 69626016 16432320
02:35:05 991 8192000000 10019467024 10404872192 1056304304 11.421 100 85 166 1 1144630064 70398224 17927536
02:36:07 931 8192000000 9963687520 10340495360 1036573984 11.232 99 83 151 4 1087852560 33773488 17505088
02:37:09 3500 8192000000 9987220384 10368090112 1038673808 11.252 99 78 149 3 1105575440 52629120 14272512
02:38:21 6591 8192000000 10020069296 10402054144 1055162544 11.410 100 71 137 1 1142160704 72943216 14054944
02:39:34 6128 8192000000 9999882240 10382135296 1069371152 11.546 100 79 146 1 1126139552 42550992 14217408
02:40:46 1209 8192000000 10038410128 10415783936 1074009168 11.590 100 88 164 5 1157455056 68106048 15339840
02:41:50 4743 8192000000 10057455680 10448211968 1083081600 11.677 100 84 167 1 1177264160 77016736 17165824
02:42:59 8628 8192000000 10088268736 10488356864 1099962496 11.837 100 72 140 0 1205711296 91429008 14319792
02:44:21 1028 8192000000 10113782416 10510749696 1102557456 11.862 100 90 171 3 1230452928 109484864 18410608
02:45:26 1227 8192000000 10129767712 10519867392 1111556160 11.947 100 90 170 0 1243444912 112733104 19155648
02:46:35 4849 8192000000 10128744400 10526883840 1109904992 11.932 100 70 137 1 1236190208 110847088 15438128
02:47:46 5570 8192000000 10163633328 10562863104 1127263152 12.096 100 75 146 1 1277570112 134062512 16244448
02:48:56 949 8192000000 10193659408 10586759168 1139122832 12.207 100 86 165 0 1302329728 145046672 18160224
02:50:02 1202 8192000000 10239565824 10633752576 1156841056 12.374 100 89 165 0 1349159984 173350272 18968656
02:51:08 1290 8192000000 10295580144 10695725056 1166050352 12.460 100 95 189 2 1414970992 227882592 21038048
02:52:14 1144 8192000000 10333189936 10741977088 1172334144 12.519 100 97 190 1 1447444720 252504064 22606512
02:53:17 879 8192000000 10308707216 10735116288 1151296224 12.322 98 92 179 1 1421667360 249836144 20534992
02:54:22 802 8192000000 10318972240 10738188288 1177286176 12.565 100 92 175 2 1426848784 231335184 18227424
02:55:26 1181 8192000000 10358958736 10768482304 1184099312 12.628 100 90 177 1 1470716272 266391216 20225744
02:56:31 1519 8192000000 10362744016 10806464512 1182092656 12.610 100 98 188 2 1470295760 265256160 22946944
02:57:36 1475 8192000000 10362339184 10778882048 1169678304 12.494 99 91 180 2 1472016288 281017376 21320608
02:58:45 1699 8192000000 10386167536 10791440384 1183545296 12.623 100 86 165 1 1490103088 287709104 18848688
02:59:49 859 8192000000 10429033856 10862354432 1195730928 12.737 100 88 170 0 1536597168 321445408 19420832
03:00:54 1335 8192000000 10377924464 10793263104 1162876784 12.430 100 89 173 0 1483139456 299902544 20360128
03:01:57 1293 8192000000 10446986384 10880024576 1191546144 12.698 100 90 174 0 1550606592 337997728 21062720
03:03:05 1104 8192000000 10454208592 10904297472 1201478672 12.790 100 98 194 1 1558288112 335129136 21680304
03:04:08 1530 8192000000 10471848368 10916151296 1189974768 12.683 100 95 184 0 1576675968 364032992 22668208
03:05:14 3670 8192000000 10450869808 10886713344 1185619664 12.643 100 65 124 0 1556843488 356493536 14730288
03:06:25 5925 8192000000 10491512208 10929266688 1178938736 12.580 100 48 95 0 1595947392 404622624 12386032
03:07:41 1114 8192000000 10454500800 10937131008 1184067840 12.628 100 67 127 0 1555955552 357292624 14595088
03:08:43 21414 8192000000 10399765024 10861465600 1158562288 12.390 98 36 72 0 1511688400 344172816 8953296
03:10:23 27840 8192000000 10304178512 10805477376 1157708752 12.382 99 31 60 1 1463356944 298114016 7534176
03:12:05 28557 8192000000 10178261616 10634678272 1121712112 12.043 98 28 54 0 1297785184 168956160 7116912
03:14:05 26595 8192000000 10145690688 10606596096 1124582032 12.070 100 31 58 0 1239583920 107417248 7584640
03:15:36 80784 8192000000 10029208016 10521350144 1061565104 11.471 95 5 10 0 1123409472 61029872 814496
03:17:57 66658 8192000000 10109521072 10572128256 1110433152 11.937 99 24 48 1 1193457344 78182832 4841360
03:20:06 39638 8192000000 10106956560 10591690752 1133988928 12.159 100 43 80 1 1206567312 62451440 10126944
03:21:55 60924 8192000000 10121034256 10590928896 1116768128 11.996 99 8 16 0 1187729696 69378992 1582576
03:24:22 113687 8192000000 10111549856 10574934016 1126752592 12.091 99 14 26 0 1179451392 50178960 2519840
03:27:25 152386 8192000000 10072464544 10546139136 1137049856 12.188 100 23 46 0 1180257568 38676192 4531520
03:31:06 4877 8192000000 10080967616 10514415616 1136685024 12.184 100 52 99 2 1181102384 34052496 10364864
03:32:14 25277 8192000000 10111915856 10542948352 1147709520 12.288 100 64 116 3 1214567616 53413296 13444800
03:33:45 25579 8192000000 10128107664 10574983168 1139413632 12.210 99 37 72 0 1210130368 63345104 7371632
03:35:15 53492 8192000000 10105842640 10551930880 1118795616 12.016 98 17 32 0 1188077808 65502784 3779408
03:37:08 40544 8192000000 10119818176 10588598272 1127117648 12.094 99 16 32 0 1207563152 76821968 3623536
03:38:53 67006 8192000000 10148657296 10596343808 1141050064 12.225 100 20 40 0 1234619984 88808832 4761088
03:41:00 50494 8192000000 10119705920 10599469056 1138142480 12.198 99 27 50 1 1223016096 78991152 5882464
03:43:48 39842 8192000000 10094968064 10534809600 1103980928 11.875 96 17 34 1 1162798720 55630256 3187536
03:45:30 32868 8192000000 10159115360 10590711808 1132281792 12.143 99 36 68 0 1245262720 106012560 6968368
03:47:09 48905 8192000000 10151289520 10600103936 1120250128 12.029 98 20 40 0 1230501536 105641712 4609696
03:49:00 41222 8192000000 10116572176 10591223808 1128127824 12.104 99 18 36 0 1251177552 118916528 4133200
03:50:42 16446 8192000000 10152951408 10574147584 1138304624 12.200 99 55 101 2 1223610928 75055120 10251184
03:52:03 75602 8192000000 10058121680 10519306240 1075953040 11.609 95 27 55 0 1213495488 132085472 5456976
03:54:19 91385 8192000000 10084914048 10565832704 1126231632 12.086 99 60 112 0 1199236592 61151840 11853120
03:57:00 49617 8192000000 10123108544 10576793600 1144172176 12.255 100 37 72 0 1210725936 58933040 7620720
03:58:50 51938 8192000000 10087555200 10565992448 1146895968 12.280 100 14 26 0 1203668864 53564848 3208048
04:00:42 62775 8192000000 10102800720 10561564672 1137679680 12.194 99 19 39 1 1196709536 54374224 4655632
04:02:45 33911 8192000000 10107592752 10592780288 1145767152 12.270 100 12 24 0 1211008752 62212416 3029184
04:04:26 89340 8192000000 10065790432 10544521216 1097450352 11.813 96 22 44 0 1158282880 56505040 4327488
04:07:11 56260 8192000000 10046485488 10485882880 1097057104 11.810 96 12 24 0 1131879968 32552064 2270800
04:09:07 99109 8192000000 10065098848 10544852992 1086475952 11.709 96 10 20 0 1159616272 70987472 2152848
04:11:47 20069 8192000000 10097558000 10528440320 1093398192 11.775 96 48 96 0 1151869872 48262192 10209488
04:13:08 46230 8192000000 10081975840 10548551680 1088675552 11.730 96 35 70 0 1181948384 86195040 7077792
04:14:54 20376 8192000000 10103499648 10538999808 1115736000 11.987 100 40 80 0 1201560192 77999472 7824720
04:16:38 5910 8192000000 10086564288 10501095424 1112278352 11.954 99 72 139 0 1169799136 42595472 14925312
04:17:45 12953 8192000000 10138764688 10560458752 1125580320 12.080 100 67 132 1 1218187008 78668656 13938032
04:18:59 49852 8192000000 10086825440 10529091584 1128063920 12.103 100 27 54 0 1204362960 70843696 5455344
04:20:49 47983 8192000000 10080999680 10562822144 1109227072 11.925 98 26 52 1 1197615600 83482880 4905648
04:22:40 77007 8192000000 10101489872 10571497472 1125183984 12.076 100 37 74 0 1197403216 64147984 8071248
04:26:26 58408 8192000000 10116603344 10594091008 1138193584 12.199 100 9 18 0 1209968112 69761440 2013088
04:28:28 64793 8192000000 10130156864 10638548992 1133397696 12.153 99 11 22 0 1239557168 103608544 2550928
04:30:34 83986 8192000000 10050682800 10505592832 1092883088 11.770 96 18 36 0 1144312592 47903152 3526352
04:33:03 10388 8192000000 10098461616 10531434496 1140150752 12.217 100 58 110 1 1197515360 45461504 11903104
04:34:14 34610 8192000000 10156975552 10602733568 1140367232 12.219 99 37 71 0 1230657872 82918608 7372032
04:35:49 68086 8192000000 10141709104 10600669184 1095348192 11.793 95 25 48 0 1203491056 102373072 5769792

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 26-10-2022 04:38:55 to 26-10-2022 04:39:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 143 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-10-2022 04:39:13 to 26-10-2022 04:39:15.

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          214612
MON$OLDEST_ACTIVE               214613
MON$OLDEST_SNAPSHOT             214613
MON$NEXT_TRANSACTION            214613
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                       2063776
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2862 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2862 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2862 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2862 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Oct 26 04:37:45 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
--- end of diff output ---

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

26.10.22 04:39:16. 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-10-26 04:39:19 No severe PSQL-related problems occured
26.10.22 04:39:20. End of report.