Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.06.2022 04:39
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 16071888 kB
MemAvailable 31294896 kB
Buffers 20 kB
Cached 15611460 kB
SwapCached 2440 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-12 04:37:18
DTS_END                         2022-06-12 04:37:18.7460
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/56634
TRN_ID                          212065
ATT_ID                          589
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: 8775.53 21319 1053064
customer order: creation 160.24 510 19229
customer order: refuse 13.35 435 1602
order to supplier: creation 42.49 1441 5099
order to supplier: removal 3.37 166627 404
invoice (draft): creation 42.69 3326 5123
invoice (draft): removal 50.42 1041 6050
invoice accept: apply 39.43 27040 4732
invoice accept: cancel 45.69 1208 5483
customer reserve: creation 3619.43 259 434331
customer reserve: removal 4476.13 97 537136
realization accept: apply 78.30 301 9396
realization accept: cancel 82.08 152 9849
payment from customer: creation 56.08 196 6730
payment from customer: removal 5.18 127 622
payment to supplier: creation 45.58 263 5469
payment to supplier: removal 3.19 114 383
service: total inventory turnovers 3.92 3862 470
service: total monetary turnovers 7.01 385 841
service: aggregate perf. data 0.92 16729 110
service: refresh index statistics 0.04 202263 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-06-2022 04:40:02 to 12-06-2022 04:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6097
TEST_TIME 2 6541
TEST_TIME 3 6268
TEST_TIME 4 6546
TEST_TIME 5 6485
TEST_TIME 6 6317
TEST_TIME 7 6373
TEST_TIME 8 6473
TEST_TIME 9 6405
TEST_TIME 10 6464
TEST_TIME 11 7041
TEST_TIME 12 7573
TEST_TIME 13 7599
TEST_TIME 14 7676
TEST_TIME 15 7926
TEST_TIME 16 8008
TEST_TIME 17 8269
TEST_TIME 18 8544
TEST_TIME 19 8646
TEST_TIME 20 8540
TEST_TIME 21 8439
TEST_TIME 22 8440
TEST_TIME 23 8665
TEST_TIME 24 8614
TEST_TIME 25 8685
TEST_TIME 26 8887
TEST_TIME 27 9084
TEST_TIME 28 9095
TEST_TIME 29 9235
TEST_TIME 30 9806
TEST_TIME 31 10424
TEST_TIME 32 10736
TEST_TIME 33 10994
TEST_TIME 34 11260
TEST_TIME 35 11273
TEST_TIME 36 11007
TEST_TIME 37 10782
TEST_TIME 38 10941
TEST_TIME 39 10708
TEST_TIME 40 10542
TEST_TIME 41 10489
TEST_TIME 42 10391
TEST_TIME 43 10276
TEST_TIME 44 10231
TEST_TIME 45 10135
TEST_TIME 46 10085
TEST_TIME 47 9942
TEST_TIME 48 9909
TEST_TIME 49 9769
TEST_TIME 50 9709
TEST_TIME 51 9532
TEST_TIME 52 9441
TEST_TIME 53 9400
TEST_TIME 54 9408
TEST_TIME 55 9405
TEST_TIME 56 9370
TEST_TIME 57 9295
TEST_TIME 58 9310
TEST_TIME 59 9346
TEST_TIME 60 9250
TEST_TIME 61 9309
TEST_TIME 62 9277
TEST_TIME 63 9271
TEST_TIME 64 9247
TEST_TIME 65 9282
TEST_TIME 66 9278
TEST_TIME 67 9306
TEST_TIME 68 9333
TEST_TIME 69 9289
TEST_TIME 70 9321
TEST_TIME 71 9354
TEST_TIME 72 9348
TEST_TIME 73 9398
TEST_TIME 74 9355
TEST_TIME 75 9266
TEST_TIME 76 9177
TEST_TIME 77 9115
TEST_TIME 78 9087
TEST_TIME 79 9115
TEST_TIME 80 9093
TEST_TIME 81 9058
TEST_TIME 82 9044
TEST_TIME 83 9019
TEST_TIME 84 9006
TEST_TIME 85 9001
TEST_TIME 86 8977
TEST_TIME 87 8981
TEST_TIME 88 8973
TEST_TIME 89 8953
TEST_TIME 90 9027
TEST_TIME 91 9031
TEST_TIME 92 9022
TEST_TIME 93 9066
TEST_TIME 94 9025
TEST_TIME 95 9090
TEST_TIME 96 9113
TEST_TIME 97 9033
TEST_TIME 98 8966
TEST_TIME 99 8979
TEST_TIME 100 8914
TEST_TIME 101 8916
TEST_TIME 102 8884
TEST_TIME 103 8831
TEST_TIME 104 8898
TEST_TIME 105 8873
TEST_TIME 106 8878
TEST_TIME 107 8872
TEST_TIME 108 8841
TEST_TIME 109 8836
TEST_TIME 110 8874
TEST_TIME 111 8864
TEST_TIME 112 8867
TEST_TIME 113 8908
TEST_TIME 114 8983
TEST_TIME 115 8968
TEST_TIME 116 8927
TEST_TIME 117 8901
TEST_TIME 118 8900
TEST_TIME 119 8864
TEST_TIME 120 8805

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-06-2022 04:40:06 to 12-06-2022 04:40:09.

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 1453899 1453899 0 0.00 0 25684 14 0 0
doc_list_biud 1453883 1453883 0 0.00 0 67145 42 0 0
sp_add_invoice_to_stock 4856 4732 124 2.55 47 208110 27040 0 124
sp_cancel_adding_invoice 5483 5483 0 0.00 5 76481 1209 0 0
sp_cancel_client_order 1602 1602 0 0.00 5 18108 436 0 0
sp_cancel_customer_reserve 537136 537136 0 0.00 1 67148 97 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 4 4918 127 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 4 4441 115 0 0
sp_cancel_supplier_invoice 6057 6050 7 0.11 3 37726 1041 0 7
sp_cancel_supplier_order 404 404 0 0.00 7 1208565 166628 0 0
sp_cancel_write_off 9849 9849 0 0.00 2 8293 152 0 0
sp_client_order 19229 19229 0 0.00 6 27827 511 0 0
sp_customer_reserve 434331 434331 0 0.00 1 57197 259 0 0
sp_fill_shopping_cart 29438 29438 0 0.00 1 31892 85 0 0
sp_fill_shopping_cart_clo_res 434298 434298 0 0.00 0 21040 21 0 0
sp_get_clo_for_invoice 4685 4685 0 0.00 0 39181 308 0 0
sp_kill_cost_storno 16337 16337 0 0.00 0 20756 71 0 0
sp_kill_qstorno_ret_qs2qd 1090575 1090575 0 0.00 0 67116 42 0 0
sp_kill_qty_storno 574604 574604 0 0.00 0 67145 98 0 0
sp_lock_selected_doc 19501 19501 0 0.00 0 7310 7 0 0
sp_make_cost_storno 26280 26280 0 0.00 0 22732 89 0 0
sp_multiply_rows_for_pdistr 26279 26279 0 0.00 0 10874 40 0 0
sp_multiply_rows_for_qdistr 19228 19228 0 0.00 0 14128 181 0 0
sp_pay_from_customer 6730 6730 0 0.00 5 15433 196 0 0
sp_pay_to_supplier 5469 5469 0 0.00 6 22174 264 0 0
sp_payment_common 12197 12197 0 0.00 2 14633 51 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 0 18098 332 0 0
sp_qd_handle_on_invoice_upd_sts 10167 10167 0 0.00 0 11651 532 0 0
sp_qd_handle_on_reserve_upd_sts 9396 9396 0 0.00 0 13490 69 0 0
sp_reserve_write_off 9396 9396 0 0.00 8 18710 301 0 0
sp_supplier_invoice 5123 5123 0 0.00 8 116758 3326 0 0
sp_supplier_order 5099 5099 0 0.00 6 40760 1442 0 0
srv_aggregate_perf_data 110 110 0 0.00 2159 120254 16729 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 840 158760 42935 0 0
srv_find_qd_qs_mism 1007663 1007663 0 0.00 0 25680 11 0 0
srv_make_invnt_saldo 470 470 0 0.00 68 64223 3862 0 0
srv_make_money_saldo 841 841 0 0.00 1 15387 386 0 0
srv_recalc_idx_stat 5 5 0 0.00 76751 455050 202263 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4733 4733 0 0.00 1 12521 1021 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 6812 501 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 2778 27 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 1655 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 567 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 1499 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 3047 57 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 91395 8150 0 0
t$perf-norm:sp_cancel_write_off 927 927 0 0.00 0 5178 29 0 0
t$perf-norm:sp_client_order 19229 19229 0 0.00 0 9737 38 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 3751 23 0 0
t$perf-norm:sp_pay_from_customer 6730 6730 0 0.00 0 10002 23 0 0
t$perf-norm:sp_pay_to_supplier 5469 5469 0 0.00 0 11489 21 0 0
t$perf-norm:sp_reserve_write_off 9396 9396 0 0.00 0 10180 25 0 0
t$perf-norm:sp_supplier_invoice 5123 5123 0 0.00 0 17420 32 0 0
t$perf-norm:sp_supplier_order 5099 5099 0 0.00 0 5368 35 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 1619 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1486 36 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 4035 33 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 1375 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 128 64 0 0
v_add_invoice_to_stock 9608 9360 248 2.58 0 17238 36 0 248
v_all_customers 35648 35648 0 0.00 0 56 4 0 0
v_all_suppliers 28508 28508 0 0.00 0 61 4 0 0
v_all_wares 38456 38456 0 0.00 0 101 4 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 879 15 0 0
v_cancel_client_order 3204 3204 0 0.00 0 15409 22 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 4853 17 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 1244 11 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1663 14 0 0
v_cancel_supplier_invoice 814 800 14 1.71 0 14778 62 0 14
v_cancel_supplier_order 808 808 0 0.00 0 1419 11 0 0
v_cancel_write_off 1854 1854 0 0.00 0 8065 29 0 0
v_max_id_clo_ord 5095 5095 0 0.00 0 934 5 0 0
v_max_id_clo_res 940 940 0 0.00 0 1349 13 0 0
v_max_id_ord_sup 5115 5115 0 0.00 0 5115 7 0 0
v_max_non_paid_invoice 1424 1424 0 0.00 0 61 7 0 0
v_max_non_paid_realizn 3428 3428 0 0.00 0 7385 11 0 0
v_min_id_clo_ord 5095 5095 0 0.00 0 1904 8 0 0
v_min_id_clo_res 940 940 0 0.00 0 2378 12 0 0
v_min_id_ord_sup 5115 5115 0 0.00 0 4922 10 0 0
v_min_non_paid_invoice 5468 5468 0 0.00 0 14599 20 0 0
v_min_non_paid_realizn 6729 6729 0 0.00 0 14626 25 0 0
v_our_firm 9410 9410 0 0.00 0 56 4 0 0
v_reserve_write_off 18792 18792 0 0.00 0 18450 17 0 0
x_lock_depdocs_on_canc_invoice 11133 11133 0 0.00 0 11698 115 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 4290 316 0 0
x_make_qstorno_1000_1200 5099 5099 0 0.00 2 34415 1276 0 0
x_make_qstorno_1200_2000 5123 5123 0 0.00 4 85616 3082 0 0
x_make_qstorno_2100_3300 434329 434329 0 0.00 0 54050 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-06-2022 04:40:10 to 12-06-2022 04:40:14.

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:22 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 29057 8192000000 9730825376 10028154880 803979456 8.937 100 88 176 0 833218304 7930128 21308720
02:09:57 21032 8192000000 9761554896 10070700032 836414864 9.264 100 91 182 0 871901920 13248896 22238160
02:11:29 13881 8192000000 9799928544 10118242304 869113296 9.591 100 85 170 0 909405680 21164464 19127920
02:12:47 8874 8192000000 9856339808 10189926400 912335360 10.020 100 81 162 1 967157008 37240672 17580976
02:13:58 6843 8192000000 9924854464 10266984448 951568464 10.406 100 85 166 0 1029588432 60553664 17466304
02:15:08 2119 8192000000 9949233200 10302300160 991844864 10.799 100 86 167 0 1070277040 61903920 16528256
02:16:12 1219 8192000000 10007643984 10366185472 1020973616 11.081 100 96 185 0 1126892032 87350064 18568352
02:17:15 973 8192000000 10040077248 10407653376 1042376080 11.287 100 99 185 1 1156888576 95394288 19118208
02:18:18 1045 8192000000 10025569600 10403590144 1057447216 11.432 100 97 183 0 1145683008 68411456 19824336
02:19:21 1085 8192000000 10045165680 10420031488 1067993936 11.533 100 95 182 1 1161138144 74441584 18702624
02:20:23 6641 8192000000 10037892992 10427973632 1070469456 11.557 100 68 128 1 1159355024 76529632 12355936
02:21:32 1018 8192000000 10063112064 10451562496 1078302336 11.631 100 95 180 2 1186965856 89781056 18882464
02:22:34 1195 8192000000 10059393168 10446229504 1080613008 11.653 100 94 181 0 1184125424 85382256 18130160
02:23:36 1075 8192000000 10075145504 10460266496 1084936608 11.694 100 88 177 1 1193536752 89075488 19524656
02:24:38 3077 8192000000 10053706640 10442993664 1088694112 11.730 100 75 146 0 1175737856 72295456 14748288
02:25:42 7466 8192000000 10033806128 10426765312 1093694416 11.778 100 64 115 2 1158651504 53090848 11866240
02:26:51 1197 8192000000 10046554064 10433871872 1085664176 11.701 99 89 174 1 1167856336 64343072 17849088
02:27:53 1044 8192000000 10039445424 10426552320 1088644464 11.730 100 86 162 2 1160083680 54729968 16709248
02:28:55 1978 8192000000 10044207824 10433929216 1081680944 11.663 100 75 138 2 1157528096 60979520 14867632
02:29:58 1215 8192000000 10043352640 10431291392 1082895040 11.675 100 91 178 0 1166595440 64269536 19430864
02:31:01 1203 8192000000 9997016400 10368741376 1035540272 11.222 99 96 186 2 1121304560 65651392 20112896
02:32:02 1231 8192000000 10011446928 10382479360 1046329200 11.325 100 96 186 1 1133181360 69351904 17500256
02:33:04 1038 8192000000 9976697216 10348081152 1030614176 11.174 99 85 153 3 1097416592 51880784 14921632
02:34:06 5751 8192000000 9983952848 10360766464 1040115456 11.266 99 69 126 1 1098421856 44371408 13934992
02:35:13 4689 8192000000 10007983888 10392276992 1043960000 11.303 100 63 124 0 1126115952 70152160 12003792
02:36:18 985 8192000000 9995593088 10370793472 1053447392 11.394 100 95 181 2 1116641648 45056848 18137408
02:37:20 22309 8192000000 10015350256 10409615360 1063144656 11.487 100 64 125 3 1143159104 67681248 12333200
02:38:49 1610 8192000000 10039211760 10423132160 1085497952 11.700 100 74 144 1 1157933744 57362560 15073232
02:39:55 14073 8192000000 10088600416 10467479552 1100720512 11.844 100 82 156 3 1207315024 90102944 16491568
02:41:14 2297 8192000000 10100935040 10494009344 1114666480 11.977 100 79 143 1 1222992768 93319680 15006608
02:42:19 2699 8192000000 10134437040 10530615296 1130749024 12.128 100 77 150 0 1252626736 107427936 14449776
02:43:25 2427 8192000000 10162152480 10561245184 1140678672 12.222 100 72 140 1 1281016656 125464336 14873648
02:44:32 2086 8192000000 10205585536 10598395904 1141514736 12.230 100 93 180 0 1317980800 156982928 19483136
02:45:38 4565 8192000000 10216382208 10618261504 1150776912 12.317 100 77 146 1 1332465280 165878240 15810128
02:46:49 1072 8192000000 10273388448 10677833728 1161857328 12.421 100 96 192 1 1385384768 202030800 21496640
02:47:55 1116 8192000000 10286689072 10698838016 1165876032 12.458 100 93 180 0 1400040272 213484128 20680112
02:48:58 1040 8192000000 10277603760 10706448384 1177717552 12.569 100 98 191 2 1395522208 194472928 23331728
02:50:02 1648 8192000000 10285931488 10700623872 1163979536 12.441 99 95 182 2 1407356640 221348208 22028896
02:51:07 1633 8192000000 10336435616 10743996416 1188957104 12.674 100 91 179 1 1445403520 235353200 21093216
02:52:12 926 8192000000 10360907792 10789527552 1199799440 12.774 100 98 193 2 1473354912 250695952 22859520
02:53:17 942 8192000000 10376138688 10785038336 1203064864 12.805 100 94 176 0 1486308512 263323792 19919856
02:54:22 832 8192000000 10389770032 10837118976 1205295424 12.825 100 98 188 0 1495247360 268261072 21690864
02:55:25 899 8192000000 10392130064 10813030400 1205819600 12.830 100 95 190 0 1499771552 272582256 21369696
02:56:32 1783 8192000000 10415825856 10840363008 1209847248 12.868 100 79 154 1 1523111040 295109680 18154112
02:57:36 7348 8192000000 10448942400 10883137536 1204024064 12.814 100 57 111 1 1554034224 335380080 14630080
02:58:49 3601 8192000000 10458290176 10902122496 1189934608 12.683 99 60 120 1 1560428208 355609680 14883920
02:59:55 1062 8192000000 10505991504 10947203072 1191821792 12.700 100 95 185 4 1609116816 396247376 21047648
03:00:59 890 8192000000 10488190032 10916306944 1174253856 12.537 100 86 166 1 1592862800 398035264 20573680
03:02:02 1093 8192000000 10534396256 10963398656 1176206688 12.555 100 93 182 1 1637039952 437865024 22968240
03:03:06 3536 8192000000 10515165744 10983907328 1180057184 12.591 100 72 137 2 1635426672 437795936 17573552
03:04:26 3077 8192000000 10532511696 10982764544 1177972000 12.571 100 62 123 1 1637423152 443483312 15967840
03:05:38 8122 8192000000 10525746304 10996117504 1179476272 12.585 100 72 141 0 1633507376 435373264 18657840
03:06:48 23324 8192000000 10418639488 10937929728 1129634320 12.118 97 50 96 1 1506298528 363813024 12851184
03:08:40 22990 8192000000 10310564400 10773454848 1142479424 12.239 100 24 48 0 1430007888 280836752 6691712
03:10:03 28803 8192000000 10250678112 10727956480 1145065280 12.263 100 21 42 1 1342449872 192229344 5155248
03:11:46 138239 8192000000 10128354512 10611736576 1129978064 12.121 98 28 56 0 1219448112 83509168 5960880
03:15:08 30771 8192000000 10091978864 10586411008 1118412384 12.012 98 23 45 0 1195624496 71348800 5863312
03:16:40 99121 8192000000 10138107136 10605572096 1137883456 12.196 99 17 34 0 1224973216 83355120 3734640
03:19:22 107166 8192000000 10143077312 10611859456 1144049200 12.254 99 15 31 0 1224398592 77113504 3235888
03:22:10 126441 8192000000 10105574832 10582396928 1094706592 11.787 95 26 53 0 1150518048 50569024 5242432
03:25:18 97082 8192000000 10086604512 10528661504 1114621488 11.976 98 24 48 0 1155748096 36225056 4901552
03:27:59 38746 8192000000 10096870208 10544922624 1147667584 12.288 100 22 41 0 1200040272 47657008 4715680
03:29:42 57715 8192000000 10164419360 10636533760 1154746256 12.354 100 32 64 0 1267601584 106129120 6726208
03:31:44 54018 8192000000 10137705296 10645073920 1154914784 12.356 100 25 49 0 1256283136 96207072 5161280
03:34:45 61240 8192000000 10091475968 10613506048 1151370864 12.322 100 9 18 2 1227144592 73758240 2015488
03:37:26 120774 8192000000 10078047584 10587971584 1078951920 11.637 94 10 20 0 1151615664 70727280 1936464
03:40:28 92677 8192000000 10114564768 10557435904 1132529168 12.145 98 27 54 0 1188384288 50351184 5503936
03:43:19 48778 8192000000 10097358544 10577928192 1152500608 12.333 100 39 78 0 1224637232 64087248 8049376
03:45:08 92488 8192000000 10139614192 10599927808 1123117680 12.056 97 21 42 0 1205922656 78498272 4306704
03:47:43 158760 8192000000 10091992048 10582495232 1103016400 11.866 96 8 16 0 1149084192 44470512 1597280
03:51:22 94395 8192000000 10098327152 10523541504 1108323312 11.917 97 24 48 1 1129131488 16016672 4791504
03:53:57 149107 8192000000 10093379392 10568146944 1090916544 11.751 95 26 52 0 1211082672 114445008 5721120
03:57:27 41600 8192000000 10078788048 10540572672 1083119264 11.677 96 24 48 0 1154971952 66688240 5164448
03:59:16 88863 8192000000 10061799728 10526359552 1064951424 11.504 95 17 33 0 1126897248 58644384 3301440
04:01:48 51923 8192000000 10053203088 10499461120 1121595488 12.042 100 13 26 0 1152470656 28172400 2702768
04:03:42 22165 8192000000 10065947088 10499690496 1085326304 11.698 96 45 87 1 1120518544 25843120 9349120
04:05:05 22035 8192000000 10102422496 10559868928 1125069920 12.075 100 42 83 0 1194972640 61652704 8250016
04:06:32 50146 8192000000 10082104848 10543259648 1079960608 11.647 96 33 66 0 1157790208 71499504 6330096
04:08:26 40514 8192000000 10076199872 10513518592 1101921984 11.856 98 32 64 0 1161936176 53450816 6563376
04:10:08 43881 8192000000 10078383920 10529058816 1098131792 11.820 98 44 86 1 1167370320 60739520 8499008
04:11:53 27078 8192000000 10132426688 10585600000 1124177536 12.066 100 50 98 1 1201795504 67439888 10178080
04:13:20 38644 8192000000 10090309984 10532438016 1135713200 12.175 100 38 76 1 1189971376 46457856 7800320
04:15:00 78680 8192000000 10071648080 10536263680 1129203472 12.114 100 35 70 0 1183768624 47138224 7426928
04:17:29 62559 8192000000 10066640688 10562166784 1132711488 12.147 100 11 22 0 1181985760 46524016 2750256
04:19:32 96770 8192000000 10085193984 10558521344 1097911104 11.818 96 14 28 0 1162234192 60698896 3624192
04:22:15 30039 8192000000 10066935920 10509041664 1115587520 11.985 98 24 48 1 1152364880 31195616 5581744
04:23:47 24779 8192000000 10096451520 10537680896 1138288544 12.199 100 31 60 1 1191766752 47692672 5785536
04:25:14 58817 8192000000 10148706608 10592616448 1159271888 12.396 100 32 64 1 1226148384 59712464 7164032
04:27:17 59858 8192000000 10149173632 10603765760 1137535904 12.192 98 32 64 0 1245701408 101444800 6720704
04:29:17 59511 8192000000 10160507872 10618687488 1128951248 12.111 98 20 40 1 1223658448 89943376 4763824
04:31:17 63438 8192000000 10090468992 10534182912 1099036368 11.828 96 14 28 1 1163165296 61418224 2710704
04:33:34 115456 8192000000 10088710272 10555711488 1125279376 12.077 99 38 76 0 1214835328 81439808 8116144
04:36:29 64285 8192000000 9807774912 10242265088 901037968 9.909 79 7 12 2 939861440 37260224 1563248

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-06-2022 04:40:16 to 12-06-2022 04:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-06-2022 04:40:33 to 12-06-2022 04:40:35.

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          212224
MON$OLDEST_ACTIVE               212225
MON$OLDEST_SNAPSHOT             212225
MON$NEXT_TRANSACTION            212225
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                       2063456
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             761
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:89: 
:90: mscs-test-161.budzdorov.ru    Sun Jun 12 04:37:54 2022
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Sun Jun 12 04:38:10 2022
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

12.06.22 04:40:36. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-06-12 04:40:38 No severe PSQL-related problems occured
12.06.22 04:40:40. End of report.