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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16163448 kB
MemAvailable 31434448 kB
Buffers 0 kB
Cached 15517156 kB
SwapCached 2652 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-14 13:37:14
DTS_END                         2022-05-14 13:37:14.9680
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40990
TRN_ID                          215065
ATT_ID                          636
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: 8985.17 22327 1078220
customer order: creation 167.33 496 20080
customer order: refuse 14.13 382 1695
order to supplier: creation 43.43 1480 5211
order to supplier: removal 3.47 152593 416
invoice (draft): creation 43.00 3546 5160
invoice (draft): removal 51.93 931 6232
invoice accept: apply 40.06 25818 4807
invoice accept: cancel 47.03 1037 5644
customer reserve: creation 3731.18 244 447741
customer reserve: removal 4552.31 91 546277
realization accept: apply 81.38 282 9765
realization accept: cancel 87.02 150 10442
payment from customer: creation 55.98 176 6718
payment from customer: removal 5.60 123 672
payment to supplier: creation 46.44 196 5573
payment to supplier: removal 3.48 130 417
service: total inventory turnovers 3.58 4242 430
service: total monetary turnovers 6.94 343 833
service: aggregate perf. data 0.86 18685 103
service: refresh index statistics 0.03 235588 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-05-2022 13:38:32 to 14-05-2022 13:38:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12322
TEST_TIME 2 8564
TEST_TIME 3 6726
TEST_TIME 4 6715
TEST_TIME 5 6714
TEST_TIME 6 6383
TEST_TIME 7 6406
TEST_TIME 8 6833
TEST_TIME 9 6850
TEST_TIME 10 6957
TEST_TIME 11 6884
TEST_TIME 12 6938
TEST_TIME 13 7169
TEST_TIME 14 7402
TEST_TIME 15 7637
TEST_TIME 16 7621
TEST_TIME 17 7817
TEST_TIME 18 8161
TEST_TIME 19 8559
TEST_TIME 20 8429
TEST_TIME 21 8459
TEST_TIME 22 8698
TEST_TIME 23 8881
TEST_TIME 24 8805
TEST_TIME 25 8893
TEST_TIME 26 9161
TEST_TIME 27 9342
TEST_TIME 28 9268
TEST_TIME 29 9998
TEST_TIME 30 10065
TEST_TIME 31 10394
TEST_TIME 32 10840
TEST_TIME 33 11122
TEST_TIME 34 11238
TEST_TIME 35 10949
TEST_TIME 36 10916
TEST_TIME 37 10772
TEST_TIME 38 10686
TEST_TIME 39 10651
TEST_TIME 40 10641
TEST_TIME 41 10481
TEST_TIME 42 10332
TEST_TIME 43 10286
TEST_TIME 44 10204
TEST_TIME 45 10249
TEST_TIME 46 10206
TEST_TIME 47 10171
TEST_TIME 48 10058
TEST_TIME 49 9882
TEST_TIME 50 9896
TEST_TIME 51 9740
TEST_TIME 52 9627
TEST_TIME 53 9564
TEST_TIME 54 9457
TEST_TIME 55 9587
TEST_TIME 56 9560
TEST_TIME 57 9518
TEST_TIME 58 9390
TEST_TIME 59 9293
TEST_TIME 60 9197
TEST_TIME 61 9256
TEST_TIME 62 9334
TEST_TIME 63 9419
TEST_TIME 64 9380
TEST_TIME 65 9373
TEST_TIME 66 9329
TEST_TIME 67 9482
TEST_TIME 68 9523
TEST_TIME 69 9573
TEST_TIME 70 9551
TEST_TIME 71 9609
TEST_TIME 72 9547
TEST_TIME 73 9523
TEST_TIME 74 9635
TEST_TIME 75 9629
TEST_TIME 76 9537
TEST_TIME 77 9424
TEST_TIME 78 9484
TEST_TIME 79 9406
TEST_TIME 80 9394
TEST_TIME 81 9383
TEST_TIME 82 9340
TEST_TIME 83 9324
TEST_TIME 84 9250
TEST_TIME 85 9233
TEST_TIME 86 9164
TEST_TIME 87 9158
TEST_TIME 88 9209
TEST_TIME 89 9217
TEST_TIME 90 9162
TEST_TIME 91 9151
TEST_TIME 92 9209
TEST_TIME 93 9171
TEST_TIME 94 9231
TEST_TIME 95 9287
TEST_TIME 96 9290
TEST_TIME 97 9247
TEST_TIME 98 9343
TEST_TIME 99 9301
TEST_TIME 100 9322
TEST_TIME 101 9346
TEST_TIME 102 9386
TEST_TIME 103 9309
TEST_TIME 104 9267
TEST_TIME 105 9243
TEST_TIME 106 9221
TEST_TIME 107 9180
TEST_TIME 108 9163
TEST_TIME 109 9133
TEST_TIME 110 9111
TEST_TIME 111 9069
TEST_TIME 112 9056
TEST_TIME 113 9042
TEST_TIME 114 9044
TEST_TIME 115 9036
TEST_TIME 116 9019
TEST_TIME 117 9008
TEST_TIME 118 9016
TEST_TIME 119 9060
TEST_TIME 120 9001

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-05-2022 13:38:36 to 14-05-2022 13:38:39.

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 1489490 1489490 0 0.00 0 25528 13 0 0
doc_list_biud 1489476 1489476 0 0.00 0 30468 39 0 0
sp_add_invoice_to_stock 4934 4807 127 2.57 32 181214 25819 0 127
sp_cancel_adding_invoice 5644 5644 0 0.00 5 61191 1038 0 0
sp_cancel_client_order 1695 1695 0 0.00 6 11325 382 0 0
sp_cancel_customer_reserve 546277 546277 0 0.00 1 30247 91 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 3 4204 124 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 4476 131 0 0
sp_cancel_supplier_invoice 6241 6232 9 0.14 2 30511 931 0 9
sp_cancel_supplier_order 416 416 0 0.00 12 1318871 152593 0 0
sp_cancel_write_off 10442 10442 0 0.00 2 9363 151 0 0
sp_client_order 20080 20080 0 0.00 6 20936 496 0 0
sp_customer_reserve 447741 447741 0 0.00 1 49118 245 0 0
sp_fill_shopping_cart 30441 30441 0 0.00 1 20592 90 0 0
sp_fill_shopping_cart_clo_res 447705 447705 0 0.00 0 11305 19 0 0
sp_get_clo_for_invoice 4759 4759 0 0.00 0 22654 348 0 0
sp_kill_cost_storno 17175 17175 0 0.00 0 7792 66 0 0
sp_kill_qstorno_ret_qs2qd 1109644 1109644 0 0.00 0 30462 40 0 0
sp_kill_qty_storno 585228 585228 0 0.00 0 30467 92 0 0
sp_lock_selected_doc 20126 20126 0 0.00 0 7459 8 0 0
sp_make_cost_storno 26814 26814 0 0.00 0 22724 83 0 0
sp_multiply_rows_for_pdistr 26813 26813 0 0.00 0 12530 39 0 0
sp_multiply_rows_for_qdistr 20080 20080 0 0.00 0 14991 188 0 0
sp_pay_from_customer 6718 6718 0 0.00 6 10431 177 0 0
sp_pay_to_supplier 5573 5573 0 0.00 7 12541 197 0 0
sp_payment_common 12290 12290 0 0.00 2 7097 46 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 1 11318 318 0 0
sp_qd_handle_on_invoice_upd_sts 10402 10402 0 0.00 0 30280 492 0 0
sp_qd_handle_on_reserve_upd_sts 9764 9764 0 0.00 0 20342 61 0 0
sp_reserve_write_off 9765 9765 0 0.00 8 22739 282 0 0
sp_supplier_invoice 5160 5160 0 0.00 8 88615 3546 0 0
sp_supplier_order 5211 5211 0 0.00 8 45447 1481 0 0
srv_aggregate_perf_data 103 103 0 0.00 2509 136390 18686 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 750 130235 35912 0 0
srv_find_qd_qs_mism 1030683 1030683 0 0.00 0 25526 10 0 0
srv_make_invnt_saldo 430 430 0 0.00 50 89773 4242 0 0
srv_make_money_saldo 833 833 0 0.00 1 16012 344 0 0
srv_recalc_idx_stat 4 4 0 0.00 46957 421288 235588 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4809 4809 0 0.00 1 11186 978 0 0
t$perf-norm:sp_cancel_adding_invoice 353 353 0 0.00 1 9672 491 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 6008 35 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 884 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 655 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 1480 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 2243 36 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 90491 6770 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 3695 20 0 0
t$perf-norm:sp_client_order 20080 20080 0 0.00 0 15377 40 0 0
t$perf-norm:sp_customer_reserve 928 928 0 0.00 0 1737 13 0 0
t$perf-norm:sp_pay_from_customer 6719 6719 0 0.00 0 11476 22 0 0
t$perf-norm:sp_pay_to_supplier 5573 5573 0 0.00 0 14426 25 0 0
t$perf-norm:sp_reserve_write_off 9765 9765 0 0.00 0 8384 27 0 0
t$perf-norm:sp_supplier_invoice 5160 5160 0 0.00 0 8868 40 0 0
t$perf-norm:sp_supplier_order 5211 5211 0 0.00 0 8967 33 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 647 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 500 18 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 3287 26 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 368 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 14 195 104 0 0
v_add_invoice_to_stock 9754 9500 254 2.60 0 10858 28 0 254
v_all_customers 36548 36548 0 0.00 0 71 4 0 0
v_all_suppliers 28822 28822 0 0.00 0 131 4 0 0
v_all_wares 40154 40154 0 0.00 0 56 4 0 0
v_cancel_adding_invoice 706 706 0 0.00 0 4177 25 0 0
v_cancel_client_order 3390 3390 0 0.00 0 4341 9 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 4160 15 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 1096 13 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 2128 16 0 0
v_cancel_supplier_invoice 830 812 18 2.16 0 4337 34 0 18
v_cancel_supplier_order 832 832 0 0.00 0 2225 13 0 0
v_cancel_write_off 1944 1944 0 0.00 0 1163 22 0 0
v_max_id_clo_ord 5209 5209 0 0.00 0 1497 5 0 0
v_max_id_clo_res 928 928 0 0.00 0 1485 15 0 0
v_max_id_ord_sup 5154 5154 0 0.00 0 7620 10 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 319 7 0 0
v_max_non_paid_realizn 3535 3535 0 0.00 0 6826 10 0 0
v_min_id_clo_ord 5209 5209 0 0.00 0 3567 9 0 0
v_min_id_clo_res 928 928 0 0.00 0 6410 16 0 0
v_min_id_ord_sup 5154 5154 0 0.00 0 4105 12 0 0
v_min_non_paid_invoice 5573 5573 0 0.00 0 7063 15 0 0
v_min_non_paid_realizn 6717 6717 0 0.00 0 7089 16 0 0
v_our_firm 9966 9966 0 0.00 0 58 4 0 0
v_reserve_write_off 19526 19526 0 0.00 0 5306 12 0 0
x_lock_depdocs_on_canc_invoice 11470 11470 0 0.00 0 11858 118 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 6211 330 0 0
x_make_qstorno_1000_1200 5211 5211 0 0.00 3 45421 1301 0 0
x_make_qstorno_1200_2000 5160 5160 0 0.00 4 88563 3294 0 0
x_make_qstorno_2100_3300 447740 447740 0 0.00 0 49109 213 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:18 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 30035 8192000000 9741037040 10045030400 819189232 9.090 100 89 178 1 847885312 7485968 21210112
11:09:55 25994 8192000000 9773329408 10085347328 844219152 9.342 100 88 176 0 877903840 12827424 20857264
11:11:24 9442 8192000000 9820506528 10141327360 883269216 9.732 100 84 168 0 928227328 25763824 19194288
11:12:37 8648 8192000000 9874234320 10211004416 927757760 10.173 100 83 166 0 978073872 32132512 18183600
11:13:51 9200 8192000000 9926680016 10277363712 968484240 10.572 100 81 161 0 1044578512 59085328 17008944
11:15:02 2220 8192000000 9983659344 10338017280 1001887616 10.897 100 89 170 1 1095129072 76144624 17096832
11:16:08 2179 8192000000 10004997904 10370252800 1025190960 11.122 100 93 181 1 1121357952 78058128 18108864
11:17:12 1045 8192000000 10013676960 10387312640 1049520960 11.356 100 93 184 1 1137723472 68551856 19650656
11:18:15 1036 8192000000 10034800752 10406424576 1066524000 11.519 100 90 177 1 1155108928 70779120 17805808
11:19:18 1163 8192000000 10060331760 10440593408 1071553184 11.567 100 98 195 0 1186036176 95546608 18936384
11:20:20 1761 8192000000 10048872144 10431201280 1078254480 11.631 100 95 174 2 1170482752 74258240 17970032
11:21:22 1326 8192000000 10060874768 10443411456 1081766416 11.664 100 96 187 1 1184186560 82457632 19962512
11:22:25 1104 8192000000 10064894672 10447925248 1089897968 11.742 100 92 182 1 1180158560 71477440 18783152
11:23:27 2753 8192000000 10032122432 10413678592 1091242352 11.754 100 91 170 2 1148850016 41924304 15683360
11:24:31 1116 8192000000 10058189696 10453557248 1095642144 11.796 100 80 155 1 1182307552 71033984 15631424
11:25:33 1356 8192000000 10072993024 10463813632 1106081632 11.895 100 89 164 1 1196706320 75441712 15182976
11:26:36 1280 8192000000 10095901520 10484539392 1103518272 11.871 100 96 186 0 1214331856 91671520 19142064
11:27:38 1093 8192000000 10072265040 10466287616 1106419360 11.899 100 95 188 1 1195271968 69706848 19145760
11:28:40 1420 8192000000 10084352800 10469011456 1098885168 11.827 100 96 189 1 1204600896 86408304 19307424
11:29:42 2431 8192000000 10068976944 10458574848 1093605872 11.777 100 85 159 1 1195832032 85616752 16609408
11:30:46 1158 8192000000 10050373264 10438336512 1083166784 11.678 100 96 186 0 1172306448 70114128 19025536
11:31:48 1391 8192000000 9963177904 10333933568 1029049456 11.159 99 98 181 3 1085798816 39398400 17350960
11:32:50 3046 8192000000 9950543296 10329878528 1011375680 10.989 96 55 103 2 1078531088 56132624 11022784
11:33:54 1666 8192000000 9992718560 10371436544 1050039296 11.361 100 73 136 4 1112401328 48444928 13917104
11:34:56 921 8192000000 9985314432 10360750080 1040496608 11.269 100 96 177 1 1105523792 46208688 18818496
11:35:58 1476 8192000000 10010027504 10389921792 1042154640 11.285 99 74 149 0 1130731376 74037264 14539472
11:37:00 1911 8192000000 9971330080 10347773952 1038619552 11.251 99 58 102 1 1086712736 37380656 10712528
11:38:06 23960 8192000000 10037548480 10424930304 1070424832 11.556 100 73 139 2 1162521536 78087808 14008896
11:39:33 1178 8192000000 10064240240 10448896000 1086308960 11.708 100 91 176 0 1179002272 73810656 18882656
11:40:41 21471 8192000000 10093952048 10488246272 1098990864 11.828 100 67 132 1 1203146896 90751008 13405024
11:42:06 8574 8192000000 10097190480 10499813376 1117759712 12.006 100 61 114 2 1219941552 90699840 11482000
11:43:25 1398 8192000000 10182078912 10572345344 1137673760 12.194 100 92 172 2 1297218272 141484544 18059968
11:44:31 894 8192000000 10218801824 10615140352 1146333632 12.275 100 93 182 2 1337526784 171039072 20154080
11:45:36 1596 8192000000 10205744128 10611924992 1156706768 12.372 100 91 174 0 1320049312 144003728 19338816
11:46:43 1160 8192000000 10259993440 10658238464 1167735008 12.476 100 91 174 3 1368051264 181397504 18918752
11:47:50 1762 8192000000 10269369088 10674556928 1181014832 12.600 100 77 154 1 1380612688 182796800 16801056
11:48:57 908 8192000000 10317869824 10721185792 1181580672 12.605 100 96 185 0 1429018368 226307024 21130672
11:50:00 2004 8192000000 10340684752 10746765312 1173536528 12.530 100 83 164 1 1455214576 262808880 18869168
11:51:05 876 8192000000 10364171568 10781990912 1180381760 12.594 100 91 178 0 1471059888 269833040 20845088
11:52:07 1342 8192000000 10392243504 10815377408 1185802976 12.644 100 97 193 3 1499653232 291865504 21984752
11:53:12 2470 8192000000 10390137728 10805694464 1180389856 12.594 100 85 164 0 1493029296 294355456 18283984
11:54:19 1059 8192000000 10386538000 10844688384 1192886768 12.710 100 86 172 0 1494822176 281447984 20487424
11:55:25 896 8192000000 10407701216 10836086784 1191716480 12.699 100 95 186 0 1520180576 306123072 22341024
11:56:28 9353 8192000000 10409843824 10856636416 1190678352 12.690 100 55 104 0 1525770016 322177616 12914048
11:57:44 5041 8192000000 10398926944 10832998400 1162952944 12.431 98 54 106 2 1497281024 321046224 13281856
11:58:55 740 8192000000 10464203568 10893250560 1192876064 12.710 100 96 190 0 1565701776 350094064 22731648
11:59:59 1080 8192000000 10439348080 10862280704 1174180608 12.536 100 97 194 0 1542212672 344897216 23134848
12:01:03 11835 8192000000 10467088656 10889846784 1180384480 12.594 100 57 114 0 1570075344 375072368 14618496
12:02:19 3018 8192000000 10508928416 10946981888 1167492368 12.473 100 58 114 0 1616314720 433597056 15225296
12:03:30 7831 8192000000 10457558928 10947768320 1167630000 12.475 100 60 120 0 1566192256 383578352 14983904
12:04:40 19999 8192000000 10473269200 10916151296 1168289984 12.481 100 42 84 1 1565640320 385775072 11575264
12:06:02 12483 8192000000 10399313360 10908590080 1163552016 12.437 100 35 68 0 1543710784 370718560 9440208
12:07:16 29444 8192000000 10360584304 10823766016 1128812688 12.110 97 22 45 1 1439450480 304859120 5778672
12:08:57 47008 8192000000 10192825696 10700107776 1124074304 12.065 99 20 40 0 1348738272 219865392 4798576
12:10:46 1514 8192000000 10110967216 10589892608 1126867376 12.092 100 81 155 1 1211595360 68201536 16526448
12:11:48 69922 8192000000 10156604192 10568056832 1142071792 12.235 100 78 151 3 1249726320 90757744 16896784
12:13:59 21284 8192000000 10122873776 10568450048 1131925664 12.140 99 23 46 2 1210467728 73623696 4918368
12:15:27 101408 8192000000 10099707936 10632957952 1137822192 12.195 100 13 26 0 1237042384 96188672 3031520
12:19:10 27389 8192000000 10112115664 10550874112 1152116976 12.329 100 37 74 2 1209174656 48385552 8672128
12:21:04 74779 8192000000 10092462640 10598666240 1139220880 12.208 100 14 28 0 1222879216 79849216 3809120
12:23:22 102169 8192000000 10083704272 10558021632 1128865008 12.111 99 12 22 3 1214459472 83245680 2348784
12:26:08 49432 8192000000 10075853520 10525196288 1138013184 12.197 100 41 80 3 1173656960 26338416 9305360
12:28:11 22129 8192000000 10072214368 10499141632 1123741680 12.062 99 55 98 3 1162869200 28358896 10768624
12:29:34 23094 8192000000 10085697136 10539696128 1112048240 11.952 98 27 54 0 1181877152 64428208 5400704
12:31:00 20101 8192000000 10098588480 10543251456 1125315792 12.077 99 30 58 0 1208856112 76797328 6742992
12:32:39 75713 8192000000 10080943008 10559635456 1131009584 12.131 100 20 41 1 1190825968 55705664 4110720
12:34:56 61215 8192000000 10068769152 10513330176 1121329008 12.040 98 19 38 0 1159281552 32652272 5300272
12:37:11 38850 8192000000 10172790624 10623172608 1135679392 12.175 99 28 56 0 1232592576 91044256 5868928
12:38:54 79918 8192000000 10129712432 10577321984 1126008112 12.084 98 12 24 0 1224406576 95847744 2550720
12:41:17 46832 8192000000 10123924816 10606604288 1128402864 12.106 98 31 57 2 1212596912 78217488 5976560
12:43:06 42375 8192000000 10199748416 10643976192 1135827056 12.176 98 34 68 0 1272663760 129316192 7520512
12:44:55 75136 8192000000 10125140800 10586456064 1111579584 11.947 97 23 46 0 1212679088 95925648 5173856
12:47:16 42926 8192000000 10080184512 10505871360 1062859936 11.484 94 48 92 1 1127392544 53879296 10653312
12:49:03 22051 8192000000 10057187264 10483900416 1074063680 11.591 96 33 66 0 1107352816 26337040 6952096
12:50:25 46256 8192000000 10162420128 10615345152 1116797696 11.997 99 42 84 0 1238085536 113152704 8135136
12:52:18 94703 8192000000 10110767936 10564575232 1124477776 12.069 100 12 24 1 1201261088 73840112 2943200
12:56:07 20479 8192000000 10058031104 10494828544 1111763440 11.949 99 34 67 1 1158375328 40655552 5956336
12:57:28 45862 8192000000 10070912112 10559881216 1128786784 12.110 100 14 26 0 1211977936 79941728 3249424
12:59:15 45388 8192000000 10071356464 10520604672 1135443200 12.173 100 11 22 0 1180076240 42602432 2030608
13:01:11 100731 8192000000 10104228896 10578944000 1129656736 12.118 99 15 30 1 1201506880 68516656 3333488
13:03:55 80532 8192000000 10114762544 10591748096 1112066688 11.952 98 13 26 1 1197305936 82082624 3156624
13:06:16 38728 8192000000 10081512848 10549035008 1132667376 12.147 100 21 40 0 1202706144 65502240 4536528
13:07:57 40034 8192000000 10124118128 10574565376 1119333360 12.021 98 31 62 0 1198233568 72483488 6416720
13:09:41 51222 8192000000 10112369648 10564096000 1124816352 12.072 99 38 76 1 1215307120 82249520 8241248
13:11:35 35399 8192000000 10107811488 10554277888 1082415520 11.670 96 31 62 0 1163866832 75347536 6103776
13:13:29 49667 8192000000 10074671920 10510024704 1069756512 11.550 96 48 90 2 1114998720 34838464 10403744
13:15:20 54929 8192000000 10097043888 10604302336 1122487680 12.050 100 20 38 1 1235154656 108352576 4314400
13:17:15 33078 8192000000 10089930224 10514456576 1136420544 12.182 100 46 90 0 1206166880 59094096 10652240
13:18:49 130235 8192000000 10067450048 10509680640 1120272640 12.030 99 22 44 0 1183575456 58122816 5180000
13:22:01 63099 8192000000 10056176480 10528272384 1123291456 12.058 99 26 50 2 1170859360 42716096 4851808
13:24:28 76061 8192000000 10044503536 10516086784 1131165712 12.132 100 9 18 0 1183873856 50606304 2101840
13:26:47 64338 8192000000 10074545232 10553434112 1130179792 12.123 99 14 28 0 1173995328 40768832 3046704
13:28:53 66898 8192000000 10093921232 10565480448 1110288480 11.935 97 14 28 0 1177807920 64831088 2688352
13:31:05 46938 8192000000 10085023168 10533892096 1126308752 12.087 99 13 25 2 1183662400 54361536 2992112
13:32:53 30477 8192000000 10112816464 10554982400 1124137568 12.066 99 30 60 1 1202608928 71823776 6647584
13:34:27 41882 8192000000 10165898208 10612146176 1107373584 11.908 97 36 73 1 1236865456 122164512 7327360
13:36:14 38976 8192000000 10149215264 10582769664 1121148384 12.038 99 38 76 0 1238646720 108896432 8601904

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

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

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          213670
MON$OLDEST_ACTIVE               218187
MON$OLDEST_SNAPSHOT             218187
MON$NEXT_TRANSACTION            218187
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                       2073568
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:120: 
:121: mscs-test-161.budzdorov.ru    Sat May 14 13:37:16 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Sat May 14 13:37:20 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
:128: 
:129: mscs-test-161.budzdorov.ru    Sat May 14 13:37:24 2022
:130:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:131: 
:132: 
:133: mscs-test-161.budzdorov.ru    Sat May 14 13:37:25 2022
:134:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:135: 
:136: 
:137: mscs-test-161.budzdorov.ru    Sat May 14 13:37:26 2022
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Sat May 14 13:37:26 2022
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Sat May 14 13:37:26 2022
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:147: 
--- end of diff output ---

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

14.05.22 13:39:06. 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-14 13:39:09 No severe PSQL-related problems occured
14.05.22 13:39:10. End of report.