Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 07: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 15881860 kB
MemAvailable 31137312 kB
Buffers 0 kB
Cached 15854720 kB
SwapCached 2524 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-07-13 07:37:16
DTS_END                         2022-07-13 07:37:16.6010
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/49698
TRN_ID                          225145
ATT_ID                          614
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: 9108.45 26139 1093014
customer order: creation 172.76 461 20731
customer order: refuse 14.57 407 1748
order to supplier: creation 46.33 1299 5559
order to supplier: removal 3.41 161840 409
invoice (draft): creation 45.84 3024 5501
invoice (draft): removal 51.36 921 6163
invoice accept: apply 42.26 26736 5071
invoice accept: cancel 46.86 1293 5623
customer reserve: creation 3818.53 262 458223
customer reserve: removal 4571.18 95 548542
realization accept: apply 83.16 291 9979
realization accept: cancel 81.93 169 9832
payment from customer: creation 60.62 174 7274
payment from customer: removal 5.64 160 677
payment to supplier: creation 47.71 191 5725
payment to supplier: removal 3.20 151 384
service: total inventory turnovers 4.19 4014 503
service: total monetary turnovers 7.94 345 953
service: aggregate perf. data 0.93 14864 112
service: refresh index statistics 0.04 306085 5

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10083
TEST_TIME 2 6742
TEST_TIME 3 7755
TEST_TIME 4 7440
TEST_TIME 5 7785
TEST_TIME 6 7908
TEST_TIME 7 7402
TEST_TIME 8 7159
TEST_TIME 9 6964
TEST_TIME 10 6987
TEST_TIME 11 7150
TEST_TIME 12 7072
TEST_TIME 13 7189
TEST_TIME 14 7433
TEST_TIME 15 7447
TEST_TIME 16 7441
TEST_TIME 17 7541
TEST_TIME 18 7489
TEST_TIME 19 7656
TEST_TIME 20 7772
TEST_TIME 21 7808
TEST_TIME 22 8334
TEST_TIME 23 8377
TEST_TIME 24 8361
TEST_TIME 25 8491
TEST_TIME 26 8745
TEST_TIME 27 9033
TEST_TIME 28 9259
TEST_TIME 29 9464
TEST_TIME 30 9899
TEST_TIME 31 10014
TEST_TIME 32 10277
TEST_TIME 33 11149
TEST_TIME 34 11301
TEST_TIME 35 11220
TEST_TIME 36 11086
TEST_TIME 37 10907
TEST_TIME 38 10860
TEST_TIME 39 10705
TEST_TIME 40 10596
TEST_TIME 41 10559
TEST_TIME 42 10349
TEST_TIME 43 10306
TEST_TIME 44 10233
TEST_TIME 45 10272
TEST_TIME 46 10209
TEST_TIME 47 10119
TEST_TIME 48 10042
TEST_TIME 49 10031
TEST_TIME 50 10077
TEST_TIME 51 9977
TEST_TIME 52 9915
TEST_TIME 53 10032
TEST_TIME 54 9942
TEST_TIME 55 9945
TEST_TIME 56 9927
TEST_TIME 57 9991
TEST_TIME 58 9953
TEST_TIME 59 9901
TEST_TIME 60 9879
TEST_TIME 61 9970
TEST_TIME 62 9974
TEST_TIME 63 9952
TEST_TIME 64 9925
TEST_TIME 65 10009
TEST_TIME 66 9956
TEST_TIME 67 9810
TEST_TIME 68 9812
TEST_TIME 69 9823
TEST_TIME 70 9782
TEST_TIME 71 9697
TEST_TIME 72 9639
TEST_TIME 73 9659
TEST_TIME 74 9588
TEST_TIME 75 9678
TEST_TIME 76 9752
TEST_TIME 77 9751
TEST_TIME 78 9770
TEST_TIME 79 9722
TEST_TIME 80 9766
TEST_TIME 81 9785
TEST_TIME 82 9749
TEST_TIME 83 9789
TEST_TIME 84 9803
TEST_TIME 85 9762
TEST_TIME 86 9791
TEST_TIME 87 9825
TEST_TIME 88 9752
TEST_TIME 89 9758
TEST_TIME 90 9677
TEST_TIME 91 9648
TEST_TIME 92 9643
TEST_TIME 93 9618
TEST_TIME 94 9564
TEST_TIME 95 9521
TEST_TIME 96 9483
TEST_TIME 97 9445
TEST_TIME 98 9478
TEST_TIME 99 9475
TEST_TIME 100 9439
TEST_TIME 101 9478
TEST_TIME 102 9467
TEST_TIME 103 9512
TEST_TIME 104 9463
TEST_TIME 105 9498
TEST_TIME 106 9489
TEST_TIME 107 9473
TEST_TIME 108 9510
TEST_TIME 109 9519
TEST_TIME 110 9534
TEST_TIME 111 9524
TEST_TIME 112 9471
TEST_TIME 113 9428
TEST_TIME 114 9403
TEST_TIME 115 9367
TEST_TIME 116 9308
TEST_TIME 117 9272
TEST_TIME 118 9239
TEST_TIME 119 9201
TEST_TIME 120 9139

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-07-2022 07:38:57 to 13-07-2022 07:39:00.

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 1516923 1516923 0 0.00 0 32624 14 0 0
doc_list_biud 1516911 1516911 0 0.00 0 35878 40 0 0
sp_add_invoice_to_stock 5211 5071 140 2.68 51 210365 26737 0 140
sp_cancel_adding_invoice 5623 5623 0 0.00 5 100657 1293 0 0
sp_cancel_client_order 1748 1748 0 0.00 6 12796 408 0 0
sp_cancel_customer_reserve 548542 548542 0 0.00 1 35900 95 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 3 9285 160 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 3 5308 151 0 0
sp_cancel_supplier_invoice 6179 6163 16 0.25 2 21966 921 0 16
sp_cancel_supplier_order 409 409 0 0.00 8 1577815 161841 0 0
sp_cancel_write_off 9832 9832 0 0.00 2 10521 170 0 0
sp_client_order 20731 20731 0 0.00 5 36056 462 0 0
sp_customer_reserve 458223 458223 0 0.00 0 118456 262 0 0
sp_fill_shopping_cart 31778 31778 0 0.00 1 47958 85 0 0
sp_fill_shopping_cart_clo_res 458174 458174 0 0.00 0 20365 16 0 0
sp_get_clo_for_invoice 5012 5012 0 0.00 0 20604 244 0 0
sp_kill_cost_storno 16516 16516 0 0.00 0 32616 76 0 0
sp_kill_qstorno_ret_qs2qd 1113488 1113488 0 0.00 0 35751 41 0 0
sp_kill_qty_storno 587308 587308 0 0.00 0 35877 94 0 0
sp_lock_selected_doc 20776 20776 0 0.00 0 7670 7 0 0
sp_make_cost_storno 27990 27990 0 0.00 0 24088 84 0 0
sp_multiply_rows_for_pdistr 27990 27990 0 0.00 0 9248 37 0 0
sp_multiply_rows_for_qdistr 20729 20729 0 0.00 0 16795 173 0 0
sp_pay_from_customer 7274 7274 0 0.00 4 11079 175 0 0
sp_pay_to_supplier 5725 5725 0 0.00 6 10973 192 0 0
sp_payment_common 12999 12999 0 0.00 2 11069 45 0 0
sp_qd_handle_on_cancel_clo 1748 1748 0 0.00 0 12772 329 0 0
sp_qd_handle_on_invoice_upd_sts 10635 10635 0 0.00 0 31054 502 0 0
sp_qd_handle_on_reserve_upd_sts 9978 9978 0 0.00 0 18021 75 0 0
sp_reserve_write_off 9979 9979 0 0.00 8 18055 292 0 0
sp_supplier_invoice 5501 5501 0 0.00 9 112922 3025 0 0
sp_supplier_order 5559 5559 0 0.00 8 45545 1300 0 0
srv_aggregate_perf_data 112 112 0 0.00 2862 124908 14865 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 680 124135 34156 0 0
srv_find_qd_qs_mism 1045097 1045097 0 0.00 0 30246 11 0 0
srv_make_invnt_saldo 503 503 0 0.00 46 66144 4015 0 0
srv_make_money_saldo 953 953 0 0.00 1 11305 346 0 0
srv_recalc_idx_stat 5 5 0 0.00 175723 485618 306085 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5074 5074 0 0.00 0 14125 936 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 6628 457 0 0
t$perf-norm:sp_cancel_client_order 1748 1748 0 0.00 0 3586 27 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 843 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 452 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 5988 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 797 35 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 89212 6562 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 720 17 0 0
t$perf-norm:sp_client_order 20731 20731 0 0.00 0 36274 38 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 678 15 0 0
t$perf-norm:sp_pay_from_customer 7274 7274 0 0.00 0 7837 21 0 0
t$perf-norm:sp_pay_to_supplier 5726 5726 0 0.00 0 7082 19 0 0
t$perf-norm:sp_reserve_write_off 9980 9980 0 0.00 0 20613 27 0 0
t$perf-norm:sp_supplier_invoice 5501 5501 0 0.00 0 20346 37 0 0
t$perf-norm:sp_supplier_order 5559 5559 0 0.00 0 3066 32 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 886 33 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 25 3 0 0
t$perf-norm:srv_make_invnt_saldo 503 503 0 0.00 0 1190 15 0 0
t$perf-norm:srv_make_money_saldo 953 953 0 0.00 0 1271 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 215 75 0 0
v_add_invoice_to_stock 10298 10018 280 2.71 0 5958 26 0 280
v_all_customers 38476 38476 0 0.00 0 150 4 0 0
v_all_suppliers 30442 30442 0 0.00 0 89 4 0 0
v_all_wares 41450 41450 0 0.00 0 186 4 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2292 20 0 0
v_cancel_client_order 3496 3496 0 0.00 0 2761 12 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 5744 20 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 1073 13 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 5295 26 0 0
v_cancel_supplier_invoice 892 860 32 3.58 0 3477 36 0 32
v_cancel_supplier_order 818 818 0 0.00 0 490 11 0 0
v_cancel_write_off 1962 1962 0 0.00 0 2577 29 0 0
v_max_id_clo_ord 5558 5558 0 0.00 0 1145 5 0 0
v_max_id_clo_res 997 997 0 0.00 0 2386 14 0 0
v_max_id_ord_sup 5492 5492 0 0.00 0 4489 8 0 0
v_max_non_paid_invoice 1554 1554 0 0.00 0 620 9 0 0
v_max_non_paid_realizn 3702 3702 0 0.00 0 1264 8 0 0
v_min_id_clo_ord 5558 5558 0 0.00 0 1816 9 0 0
v_min_id_clo_res 997 997 0 0.00 0 2592 11 0 0
v_min_id_ord_sup 5492 5492 0 0.00 0 7382 11 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 3296 14 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 11064 17 0 0
v_our_firm 10116 10116 0 0.00 0 80 5 0 0
v_reserve_write_off 19954 19954 0 0.00 0 5889 14 0 0
x_lock_depdocs_on_canc_invoice 11356 11356 0 0.00 0 23060 140 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 7599 352 0 0
x_make_qstorno_1000_1200 5559 5559 0 0.00 3 45527 1123 0 0
x_make_qstorno_1200_2000 5501 5501 0 0.00 4 112773 2797 0 0
x_make_qstorno_2100_3300 458223 458223 0 0.00 0 118453 233 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-07-2022 07:39:01 to 13-07-2022 07:39:05.

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:20 2 8192000000 8797568336 8808484864 3901344 0.047 2 1 2 0 3998960 61840 35776
05:08:21 22163 8192000000 9721193760 10019115008 795511664 8.851 100 90 180 0 825823472 8095680 22216128
05:09:48 15797 8192000000 9755447136 10066624512 837789728 9.278 100 86 170 0 870083072 12128176 20165168
05:11:09 18276 8192000000 9812029008 10133245952 879159648 9.691 100 83 166 0 918461344 20692368 18609328
05:12:33 5392 8192000000 9864907136 10199191552 919164608 10.088 100 91 177 0 980409520 41890928 19353984
05:13:41 5262 8192000000 9922967136 10261946368 950018432 10.391 100 86 170 0 1035944112 67617264 18308416
05:14:54 1720 8192000000 9948170080 10299994112 993370048 10.814 100 91 168 0 1074023584 64092096 16561440
05:16:00 1182 8192000000 9996378080 10356178944 1020672512 11.079 100 94 182 0 1109488288 70531712 18284064
05:17:03 1146 8192000000 10012488272 10382176256 1045791744 11.320 100 97 191 2 1139353360 74840672 18720944
05:18:06 1040 8192000000 10041701776 10420899840 1063349008 11.489 100 92 176 1 1159349536 78493568 17506960
05:19:08 1241 8192000000 10056949872 10437345280 1072092352 11.572 100 98 188 1 1178545632 87373648 19079632
05:20:10 1094 8192000000 10045416848 10428166144 1076278672 11.612 100 81 158 1 1164336992 70487776 17570544
05:21:12 3681 8192000000 10036207568 10424795136 1078102112 11.629 100 74 147 3 1162455552 69269760 15083680
05:22:17 1473 8192000000 10052815680 10436829184 1081961200 11.666 100 94 177 2 1173867952 74192912 17713840
05:23:19 1198 8192000000 10056662128 10443022336 1086378784 11.708 100 98 189 0 1178807040 73441856 18986400
05:24:21 1187 8192000000 10056060112 10442899456 1096923376 11.808 100 90 165 0 1176698496 63392352 16382768
05:25:24 863 8192000000 10073686144 10462973952 1099690240 11.835 100 88 170 1 1193881760 76980048 17211472
05:26:26 1642 8192000000 10073765872 10464997376 1103189472 11.868 100 88 171 0 1193039840 71943344 17907024
05:27:28 997 8192000000 10064004624 10458550272 1099595984 11.834 100 86 167 3 1190148608 72819504 17733120
05:28:30 1201 8192000000 10063779712 10451881984 1091910608 11.761 100 93 176 1 1182225120 72865952 17448560
05:29:32 1134 8192000000 10032277392 10409107456 1057543952 11.433 99 93 183 3 1151733968 75454848 18735168
05:30:34 966 8192000000 9981106720 10356903936 1047255072 11.334 99 88 164 1 1104214528 39170720 17788736
05:31:38 1365 8192000000 10009398256 10388566016 1064301136 11.498 100 80 155 3 1134552768 55087264 15164368
05:32:42 2326 8192000000 9970864320 10355834880 1025045456 11.121 97 66 129 1 1102015248 63250608 13719184
05:33:45 879 8192000000 9991924624 10364846080 1045173696 11.314 100 88 167 2 1113280896 51493568 16613632
05:34:47 1591 8192000000 9974392816 10346848256 1028723744 11.156 100 78 152 1 1099507040 55862768 14920528
05:35:49 949 8192000000 9997966768 10375942144 1040184848 11.266 100 89 171 3 1119592064 61796736 17610480
05:36:51 1206 8192000000 9992516016 10369130496 1036762016 11.234 99 90 174 3 1116511680 62996416 16753248
05:37:57 831 8192000000 10023736912 10396921856 1048908208 11.350 100 92 178 2 1139733472 72044240 18781024
05:39:14 9183 8192000000 10009826800 10394898432 1062219184 11.478 100 63 123 0 1133801408 59298416 12283808
05:40:26 922 8192000000 10031836544 10409304064 1063177312 11.487 99 95 177 0 1154214432 70390288 20646832
05:41:30 4021 8192000000 10065085168 10467917824 1088326240 11.727 100 59 118 0 1187059168 86758656 11974272
05:42:38 1275 8192000000 10093959376 10483961856 1106296112 11.897 100 83 162 3 1207378144 83614880 17467152
05:43:43 1772 8192000000 10093666384 10487140352 1113961408 11.970 100 87 161 1 1208319216 77564720 16793088
05:44:49 18830 8192000000 10138657792 10542596096 1116944688 11.998 100 63 123 0 1245299072 115509232 12845152
05:46:15 6637 8192000000 10163760272 10559303680 1125298640 12.077 100 80 153 2 1269830128 127522448 17009040
05:47:31 2602 8192000000 10225452800 10620272640 1148706768 12.297 100 88 165 1 1334872848 168012128 18153952
05:48:39 2808 8192000000 10245862144 10646786048 1167531168 12.474 100 89 175 0 1358406256 171941776 18933312
05:49:50 1594 8192000000 10281269376 10687840256 1178994096 12.581 100 95 188 3 1391837904 192420688 20423120
05:50:54 958 8192000000 10294954912 10710032384 1180701760 12.597 100 91 168 3 1405791776 206842864 18247152
05:51:57 1228 8192000000 10328661664 10744061952 1179162928 12.582 99 94 180 3 1436981552 237306096 20512528
05:53:05 1462 8192000000 10373470880 10774413312 1200434176 12.780 100 95 187 1 1482578944 260162048 21982720
05:54:10 973 8192000000 10422202912 10823462912 1189503776 12.679 99 93 183 0 1531642368 319390096 22748496
05:55:13 742 8192000000 10464639376 10861867008 1185769088 12.644 100 96 189 0 1569128816 361975904 21383824
05:56:17 1489 8192000000 10512826752 10926555136 1192710240 12.709 100 97 191 1 1618239040 401953296 23575504
05:57:21 1104 8192000000 10535292704 10952630272 1177732816 12.569 100 98 194 1 1642072912 441775712 22564384
05:58:24 699 8192000000 10508601776 10957803520 1174537056 12.539 100 92 181 0 1617462704 420951696 21973952
05:59:28 1001 8192000000 10514959392 10959941632 1178275344 12.574 100 93 177 3 1619591376 420003376 21312656
06:00:31 808 8192000000 10551349264 10965516288 1179198816 12.583 100 95 185 1 1656525984 453409744 23917424
06:01:36 1573 8192000000 10586239408 11007295488 1177665536 12.568 100 85 163 0 1692804000 494493760 20644704
06:02:43 669 8192000000 10585433392 11048255488 1182634464 12.615 100 85 166 1 1684044144 480895888 20513792
06:03:47 3015 8192000000 10607628224 11033694208 1184720160 12.634 100 70 136 1 1709325088 507563232 17041696
06:04:53 11485 8192000000 10554870912 11008442368 1177497888 12.567 100 44 87 1 1654195008 465129296 11567824
06:06:07 10111 8192000000 10510870288 10965712896 1172209632 12.517 100 52 100 1 1613825824 428476928 13139264
06:07:19 19125 8192000000 10420524704 10884055040 1160685168 12.410 99 27 55 0 1524695920 356873760 7136992
06:08:45 28845 8192000000 10325093824 10881671168 1132350256 12.144 99 16 32 1 1478824176 341759952 4713968
06:10:15 33270 8192000000 10138220544 10662121472 1124372016 12.068 99 13 26 0 1289731360 161759776 3599568
06:11:49 67652 8192000000 10059548816 10521604096 1082613360 11.672 95 31 58 3 1123063504 34344304 6105840
06:14:28 124135 8192000000 10065794080 10567991296 1102349168 11.860 97 30 61 1 1163097536 54372672 6375696
06:17:51 45856 8192000000 10046178304 10488197120 1118440624 12.012 98 29 58 0 1151050928 27230000 5380304
06:19:39 32792 8192000000 10110874224 10536042496 1130177776 12.123 98 53 91 3 1183756480 43288464 10290240
06:21:13 7333 8192000000 10121195040 10552033280 1144548848 12.258 100 51 96 1 1215882880 60217744 11116288
06:22:24 37263 8192000000 10138189216 10604032000 1108891840 11.922 98 30 60 0 1224741936 109230400 6619696
06:24:53 26285 8192000000 10117320288 10569850880 1125289984 12.077 100 17 32 0 1227550512 98831712 3428816
06:26:19 26145 8192000000 10132663952 10577424384 1135627488 12.174 100 25 46 1 1232150480 90677168 5845824
06:27:53 53947 8192000000 10137816672 10591887360 1126258480 12.086 99 20 40 0 1220078256 89242784 4576992
06:29:51 67886 8192000000 10072842416 10530590720 1122074144 12.047 99 22 44 0 1171783744 44117168 5592432
06:32:02 63310 8192000000 10116971760 10572865536 1127177472 12.095 99 22 41 0 1190677392 58720608 4779312
06:34:06 29666 8192000000 10103928992 10543005696 1102829376 11.864 98 48 94 0 1171343856 59613808 8900672
06:35:37 15257 8192000000 10041037760 10475675648 1110112256 11.933 99 34 63 0 1135468512 19379792 5976464
06:36:53 28280 8192000000 10117991744 10545119232 1118472848 12.013 99 42 85 0 1203927584 76870592 8584144
06:38:32 46434 8192000000 10067774832 10515079168 1091191648 11.754 97 19 38 0 1131164192 35783568 4188976
06:40:20 50881 8192000000 10119392352 10584031232 1125134320 12.075 99 25 50 0 1186329600 56253200 4942080
06:42:13 54235 8192000000 10114984640 10556469248 1110064144 11.933 98 12 24 0 1170880768 58229152 2587472
06:44:08 108694 8192000000 10107913504 10617823232 1109362992 11.926 98 36 69 0 1251798048 134857824 7577232
06:46:58 86151 8192000000 10148567936 10629500928 1132104880 12.141 99 22 44 0 1206266080 69515104 4646096
06:49:54 85031 8192000000 10156099776 10660401152 1127115920 12.094 99 17 34 0 1272268816 141044144 4108752
06:52:22 61861 8192000000 10114155024 10592579584 1111941216 11.951 97 16 33 1 1218449408 102761984 3746208
06:54:23 61478 8192000000 10104459136 10548932608 1142371984 12.238 100 24 49 0 1194126512 46743808 5010720
06:56:27 25662 8192000000 10129261760 10568806400 1152525488 12.333 100 50 99 1 1229328992 66326240 10477264
06:57:57 47754 8192000000 10102118608 10581221376 1091549952 11.757 96 29 58 0 1167456752 70152752 5754048
06:59:54 87132 8192000000 10112283536 10573508608 1115549408 11.985 98 25 50 1 1178500736 57797248 5154080
07:02:23 19897 8192000000 10094925088 10510536704 1121226432 12.039 99 59 114 2 1176033584 43471424 11335728
07:03:43 97277 8192000000 10120225184 10573975552 1092997616 11.771 96 20 39 0 1135307584 38653024 3656944
07:06:28 85986 8192000000 10095749472 10539925504 1137437680 12.191 100 27 54 0 1216043488 73315424 5290384
07:09:01 44007 8192000000 10064355696 10546065408 1125536112 12.079 99 7 12 0 1169870944 43321392 1013440
07:10:45 58535 8192000000 10088267632 10536964096 1133560848 12.155 99 21 39 1 1169902576 32510496 3831232
07:13:18 59485 8192000000 10137344800 10616733696 1138779200 12.204 99 13 25 0 1232893264 91232544 2881520
07:15:23 67241 8192000000 10099256400 10599886848 1139642848 12.212 100 13 26 0 1221801360 79065760 3092752
07:17:54 81565 8192000000 10071021280 10527211520 1079630560 11.644 95 19 37 2 1124785248 41158768 3995920
07:20:17 37393 8192000000 10069328400 10533847040 1122243776 12.048 99 23 41 1 1167655824 41460048 3952000
07:21:55 46955 8192000000 10068131008 10536697856 1093829136 11.779 98 34 66 1 1164934560 64396400 6709024
07:23:42 25187 8192000000 10073659200 10508009472 1090249088 11.745 97 47 93 0 1132810016 33259088 9301840
07:25:09 42538 8192000000 10111257552 10590466048 1107504400 11.909 99 49 96 0 1228568912 110961088 10103424
07:26:54 22421 8192000000 10091587872 10523262976 1120863408 12.035 100 51 104 1 1173525152 42146720 10515024
07:28:20 25977 8192000000 10112953360 10561351680 1120221008 12.029 100 49 98 0 1208563168 78787968 9554192
07:29:46 95606 8192000000 10030273120 10483437568 1091132144 11.753 98 25 48 1 1134528816 37897520 5499152
07:32:22 53442 8192000000 10043718656 10507173888 1115444112 11.984 100 8 16 0 1146365808 29169200 1752496
07:34:16 46011 8192000000 10046681360 10523717632 1103120944 11.867 99 7 14 0 1152456496 48018192 1317360
07:36:03 39204 8192000000 10052311520 10502524928 1112585216 11.957 99 15 30 1 1156848624 40823952 3439456

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-07-2022 07:39:07 to 13-07-2022 07:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

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

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          222187
MON$OLDEST_ACTIVE               225419
MON$OLDEST_SNAPSHOT             225419
MON$NEXT_TRANSACTION            225419
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                       2062880
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:126: 
:127: mscs-test-161.budzdorov.ru    Wed Jul 13 07:37:18 2022
:128:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:129: 
:130: 
:131: mscs-test-161.budzdorov.ru    Wed Jul 13 07:37:24 2022
:132:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:133: 
--- end of diff output ---

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

13.07.22 07:39:27. 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-07-13 07:39:29 No severe PSQL-related problems occured
13.07.22 07:39:31. End of report.