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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15884636 kB
MemAvailable 30826060 kB
Buffers 0 kB
Cached 15788460 kB
SwapCached 4792 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.1
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 2021-10-29 10:37:09
DTS_END                         2021-10-29 10:37:09.6870
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/50878
TRN_ID                          223448
ATT_ID                          676
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: 8908.74 26077 1069049
customer order: creation 156.19 469 18743
customer order: refuse 12.86 328 1543
order to supplier: creation 41.07 1605 4928
order to supplier: removal 3.28 124017 394
invoice (draft): creation 41.63 3921 4996
invoice (draft): removal 53.62 993 6434
invoice accept: apply 38.36 17789 4603
invoice accept: cancel 48.24 884 5789
customer reserve: creation 3557.48 172 426898
customer reserve: removal 4680.94 68 561713
realization accept: apply 75.99 210 9119
realization accept: cancel 84.27 111 10112
payment from customer: creation 53.65 133 6438
payment from customer: removal 4.86 93 583
payment to supplier: creation 43.03 137 5163
payment to supplier: removal 3.19 87 383
service: total inventory turnovers 3.40 3729 408
service: total monetary turnovers 5.83 542 700
service: aggregate perf. data 0.82 15367 98
service: refresh index statistics 0.03 350875 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2021 10:38:11 to 29-10-2021 10:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7217
TEST_TIME 2 5147
TEST_TIME 3 4665
TEST_TIME 4 5029
TEST_TIME 5 5509
TEST_TIME 6 5202
TEST_TIME 7 5258
TEST_TIME 8 5513
TEST_TIME 9 5321
TEST_TIME 10 5741
TEST_TIME 11 6067
TEST_TIME 12 6419
TEST_TIME 13 6655
TEST_TIME 14 6625
TEST_TIME 15 7025
TEST_TIME 16 7554
TEST_TIME 17 8111
TEST_TIME 18 8144
TEST_TIME 19 8320
TEST_TIME 20 8858
TEST_TIME 21 8720
TEST_TIME 22 9454
TEST_TIME 23 9613
TEST_TIME 24 9845
TEST_TIME 25 9694
TEST_TIME 26 11137
TEST_TIME 27 11429
TEST_TIME 28 11124
TEST_TIME 29 11131
TEST_TIME 30 10832
TEST_TIME 31 10678
TEST_TIME 32 10500
TEST_TIME 33 10356
TEST_TIME 34 10323
TEST_TIME 35 10181
TEST_TIME 36 10149
TEST_TIME 37 10018
TEST_TIME 38 9881
TEST_TIME 39 9783
TEST_TIME 40 9742
TEST_TIME 41 9804
TEST_TIME 42 9679
TEST_TIME 43 9563
TEST_TIME 44 9458
TEST_TIME 45 9485
TEST_TIME 46 9469
TEST_TIME 47 9343
TEST_TIME 48 9369
TEST_TIME 49 9282
TEST_TIME 50 9281
TEST_TIME 51 9195
TEST_TIME 52 9169
TEST_TIME 53 9208
TEST_TIME 54 9232
TEST_TIME 55 9287
TEST_TIME 56 9505
TEST_TIME 57 9444
TEST_TIME 58 9465
TEST_TIME 59 9482
TEST_TIME 60 9483
TEST_TIME 61 9389
TEST_TIME 62 9299
TEST_TIME 63 9286
TEST_TIME 64 9240
TEST_TIME 65 9231
TEST_TIME 66 9174
TEST_TIME 67 9084
TEST_TIME 68 9090
TEST_TIME 69 9109
TEST_TIME 70 9089
TEST_TIME 71 9135
TEST_TIME 72 9110
TEST_TIME 73 9159
TEST_TIME 74 9151
TEST_TIME 75 9155
TEST_TIME 76 9321
TEST_TIME 77 9271
TEST_TIME 78 9319
TEST_TIME 79 9438
TEST_TIME 80 9383
TEST_TIME 81 9271
TEST_TIME 82 9219
TEST_TIME 83 9173
TEST_TIME 84 9203
TEST_TIME 85 9185
TEST_TIME 86 9119
TEST_TIME 87 9113
TEST_TIME 88 9060
TEST_TIME 89 9031
TEST_TIME 90 8981
TEST_TIME 91 9022
TEST_TIME 92 9036
TEST_TIME 93 9005
TEST_TIME 94 9036
TEST_TIME 95 9022
TEST_TIME 96 8999
TEST_TIME 97 9022
TEST_TIME 98 9098
TEST_TIME 99 9130
TEST_TIME 100 9247
TEST_TIME 101 9230
TEST_TIME 102 9227
TEST_TIME 103 9339
TEST_TIME 104 9285
TEST_TIME 105 9230
TEST_TIME 106 9191
TEST_TIME 107 9158
TEST_TIME 108 9145
TEST_TIME 109 9126
TEST_TIME 110 9090
TEST_TIME 111 9075
TEST_TIME 112 9031
TEST_TIME 113 9006
TEST_TIME 114 8967
TEST_TIME 115 8955
TEST_TIME 116 8970
TEST_TIME 117 8964
TEST_TIME 118 8950
TEST_TIME 119 8977
TEST_TIME 120 8914

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2021 10:38:14 to 29-10-2021 10:38:17.

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 1463630 1463630 0 0.00 0 19046 6 0 0
doc_list_biud 1463630 1463630 0 0.00 0 74199 33 0 0
sp_add_invoice_to_stock 4748 4603 145 3.05 40 206180 17790 0 145
sp_cancel_adding_invoice 5789 5789 0 0.00 5 58235 885 0 0
sp_cancel_client_order 1543 1543 0 0.00 4 8567 328 0 0
sp_cancel_customer_reserve 561713 561713 0 0.00 1 26510 68 0 0
sp_cancel_pay_from_customer 583 583 0 0.00 3 3644 93 0 0
sp_cancel_pay_to_supplier 383 383 0 0.00 3 4608 87 0 0
sp_cancel_supplier_invoice 6446 6434 12 0.18 2 74205 993 0 12
sp_cancel_supplier_order 394 394 0 0.00 10 1175919 124018 0 0
sp_cancel_write_off 10112 10112 0 0.00 1 9441 111 0 0
sp_client_order 18743 18743 0 0.00 4 40780 470 0 0
sp_customer_reserve 426898 426898 0 0.00 0 77311 173 0 0
sp_fill_shopping_cart 28664 28664 0 0.00 1 24176 54 0 0
sp_fill_shopping_cart_clo_res 426893 426893 0 0.00 0 24553 19 0 0
sp_get_clo_for_invoice 4598 4598 0 0.00 0 33672 501 0 0
sp_kill_cost_storno 16867 16867 0 0.00 0 12319 64 0 0
sp_kill_qstorno_ret_qs2qd 1140366 1140366 0 0.00 0 74198 37 0 0
sp_kill_qty_storno 599702 599702 0 0.00 0 74199 80 0 0
sp_lock_selected_doc 18903 18903 0 0.00 0 8843 6 0 0
sp_make_cost_storno 25318 25318 0 0.00 0 13830 63 0 0
sp_multiply_rows_for_pdistr 25318 25318 0 0.00 0 10844 35 0 0
sp_multiply_rows_for_qdistr 18743 18743 0 0.00 0 32293 247 0 0
sp_pay_from_customer 6438 6438 0 0.00 4 12667 134 0 0
sp_pay_to_supplier 5163 5163 0 0.00 4 14776 138 0 0
sp_payment_common 11601 11601 0 0.00 1 8973 34 0 0
sp_qd_handle_on_cancel_clo 1543 1543 0 0.00 0 8558 299 0 0
sp_qd_handle_on_invoice_upd_sts 10387 10387 0 0.00 0 36879 369 0 0
sp_qd_handle_on_reserve_upd_sts 9119 9119 0 0.00 0 37449 65 0 0
sp_reserve_write_off 9119 9119 0 0.00 7 37469 210 0 0
sp_supplier_invoice 4996 4996 0 0.00 7 109117 3921 0 0
sp_supplier_order 4928 4928 0 0.00 6 93371 1605 0 0
srv_aggregate_perf_data 98 98 0 0.00 3513 120391 15368 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 869 179484 51575 0 0
srv_find_qd_qs_mism 1025386 1025386 0 0.00 0 19045 5 0 0
srv_make_invnt_saldo 408 408 0 0.00 29 106730 3730 0 0
srv_make_money_saldo 700 700 0 0.00 1 15075 543 0 0
srv_recalc_idx_stat 4 4 0 0.00 239138 429091 350876 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4604 4604 0 0.00 0 8480 176 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 2657 97 0 0
t$perf-norm:sp_cancel_client_order 1543 1543 0 0.00 0 4288 17 0 0
t$perf-norm:sp_cancel_customer_reserve 626 626 0 0.00 0 6277 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 583 583 0 0.00 0 2836 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 383 383 0 0.00 0 186 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1721 19 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 20103 1702 0 0
t$perf-norm:sp_cancel_write_off 874 874 0 0.00 0 2292 9 0 0
t$perf-norm:sp_client_order 18743 18743 0 0.00 0 9636 21 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 5850 9 0 0
t$perf-norm:sp_pay_from_customer 6438 6438 0 0.00 0 14195 14 0 0
t$perf-norm:sp_pay_to_supplier 5164 5164 0 0.00 0 6914 14 0 0
t$perf-norm:sp_reserve_write_off 9119 9119 0 0.00 0 19475 15 0 0
t$perf-norm:sp_supplier_invoice 4996 4996 0 0.00 0 4346 19 0 0
t$perf-norm:sp_supplier_order 4928 4928 0 0.00 0 6462 20 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 347 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 41 1 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 4484 26 0 0
t$perf-norm:srv_make_money_saldo 700 700 0 0.00 0 1405 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 2 0 0
v_add_invoice_to_stock 9486 9196 290 3.05 0 8584 18 0 290
v_all_customers 34238 34238 0 0.00 0 20 1 0 0
v_all_suppliers 27062 27062 0 0.00 0 32 1 0 0
v_all_wares 37486 37486 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1398 9 0 0
v_cancel_client_order 3086 3086 0 0.00 0 4044 8 0 0
v_cancel_customer_prepayment 1166 1166 0 0.00 0 1373 7 0 0
v_cancel_customer_reserve 1252 1252 0 0.00 0 1942 6 0 0
v_cancel_payment_to_supplier 766 766 0 0.00 0 1579 10 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 987 17 0 24
v_cancel_supplier_order 788 788 0 0.00 0 168 3 0 0
v_cancel_write_off 1748 1748 0 0.00 0 3705 15 0 0
v_max_id_clo_ord 4928 4928 0 0.00 0 1177 2 0 0
v_max_id_clo_res 914 914 0 0.00 0 2846 10 0 0
v_max_id_ord_sup 4993 4993 0 0.00 0 5511 7 0 0
v_max_non_paid_invoice 1553 1553 0 0.00 0 3172 6 0 0
v_max_non_paid_realizn 3340 3340 0 0.00 0 3106 6 0 0
v_min_id_clo_ord 4928 4928 0 0.00 0 2016 6 0 0
v_min_id_clo_res 914 914 0 0.00 0 5689 26 0 0
v_min_id_ord_sup 4993 4993 0 0.00 0 8485 11 0 0
v_min_non_paid_invoice 5163 5163 0 0.00 0 6856 24 0 0
v_min_non_paid_realizn 6438 6438 0 0.00 0 6889 28 0 0
v_our_firm 9444 9444 0 0.00 0 19 1 0 0
v_reserve_write_off 18238 18238 0 0.00 0 4654 6 0 0
x_lock_depdocs_on_canc_invoice 11823 11823 0 0.00 0 16163 118 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 7693 329 0 0
x_make_qstorno_1000_1200 4928 4928 0 0.00 2 93319 1518 0 0
x_make_qstorno_1200_2000 4996 4996 0 0.00 3 103915 3705 0 0
x_make_qstorno_2100_3300 426898 426898 0 0.00 0 71376 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2021 10:38:18 to 29-10-2021 10:38:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 30037 8192000000 9715744656 10012246016 794530096 8.841 100 91 182 0 825289136 7774928 22984112
08:09:49 23494 8192000000 9761966576 10068480000 837146720 9.271 100 89 178 0 872471200 13918016 21406464
08:11:20 16559 8192000000 9811215120 10131058688 868249696 9.583 100 83 165 1 916271136 29360880 18660560
08:12:42 16597 8192000000 9867017312 10200555520 923325584 10.129 100 79 159 0 971865440 30904208 17635648
08:14:02 7737 8192000000 9918824544 10262114304 955747568 10.447 100 82 164 1 1034207520 61447408 17012544
08:15:13 7539 8192000000 9966578352 10319347712 996077328 10.840 100 85 169 0 1083562048 70204976 17279744
08:16:22 2950 8192000000 9995604000 10353651712 1021087328 11.083 100 89 176 0 1104188176 65413968 17686880
08:17:28 2136 8192000000 10014971152 10384068608 1045422544 11.317 100 84 168 1 1130372800 67711360 17238896
08:18:31 1041 8192000000 10037913024 10412355584 1067011488 11.524 100 86 173 0 1152502464 67560864 17930112
08:19:32 4075 8192000000 9992487920 10393403392 1067660736 11.530 100 37 72 1 1115707344 40461072 7585536
08:20:38 6858 8192000000 10005765424 10393309184 1075758752 11.607 100 46 94 0 1107438976 21647680 10032544
08:21:46 6261 8192000000 9992541632 10391699456 1085693808 11.702 100 32 64 0 1119006080 26667392 6644880
08:22:53 59923 8192000000 10008492928 10410319872 1090732656 11.750 100 36 72 0 1112272176 13255312 8284208
08:24:57 6160 8192000000 10035455248 10442641408 1099315728 11.831 100 48 94 0 1147147536 37950064 9881744
08:26:04 9057 8192000000 10002840304 10409897984 1101103728 11.848 100 32 62 3 1127239600 17900160 8235712
08:27:13 8248 8192000000 10028187120 10424651776 1086450960 11.709 99 24 48 1 1112372480 20699888 5221632
08:28:26 50904 8192000000 9991587984 10394542080 1056364816 11.422 97 30 60 0 1086309552 23629136 6315600
08:30:17 6805 8192000000 9956818832 10347270144 995208544 10.832 96 39 78 1 1031327120 27574928 8543648
08:31:24 4212 8192000000 9927231152 10295717888 995072736 10.831 98 60 121 2 1028287888 19389904 13825248
08:32:29 7146 8192000000 9947334576 10331348992 1021349312 11.085 99 36 71 2 1041180912 11521568 8310032
08:33:37 30583 8192000000 9954273136 10348982272 1020665184 11.078 98 32 63 3 1042361744 14934608 6761952
08:35:08 3969 8192000000 9966697296 10368917504 1064975664 11.504 100 49 97 1 1093693952 17094512 11623776
08:36:12 11247 8192000000 9983337296 10383032320 1075557312 11.605 100 43 84 0 1100664048 15509072 9597664
08:37:28 22869 8192000000 10038096784 10425450496 1098905728 11.827 100 53 104 0 1123886512 14017008 10963776
08:38:55 59993 8192000000 10061470160 10457800704 1111618784 11.948 100 49 99 1 1162266320 40067328 10580208
08:41:01 28240 8192000000 10101830960 10508574720 1133670720 12.156 100 39 78 0 1221430528 78911408 8848400
08:42:37 26076 8192000000 10132021632 10539081728 1145692000 12.269 100 36 72 1 1244804832 90705184 8407648
08:44:09 8837 8192000000 10195276416 10603163648 1159888336 12.402 100 57 114 2 1295030000 122325680 12815984
08:45:23 9590 8192000000 10243048672 10649878528 1170253248 12.499 100 57 112 2 1344902320 161863968 12785104
08:46:38 12735 8192000000 10257402112 10662178816 1173676464 12.531 100 59 117 0 1351673456 165516576 12480416
08:47:58 5327 8192000000 10295909920 10701500416 1175974768 12.553 100 80 159 1 1400384336 206171408 18238160
08:49:09 856 8192000000 10342168752 10739400704 1181662528 12.606 100 94 186 0 1447133440 244790512 20680400
08:50:14 7095 8192000000 10346352560 10759299072 1159197632 12.396 99 52 103 1 1443651008 271770096 12683280
08:51:25 17595 8192000000 10363315056 10774888448 1140285536 12.218 99 56 111 1 1461973856 308237936 13450384
08:52:48 3373 8192000000 10378607792 10798338048 1147391280 12.285 99 73 146 0 1468260944 303735568 17134096
08:53:58 2782 8192000000 10363888864 10771492864 1143642256 12.250 98 85 169 0 1450825568 287914672 19268640
08:55:06 2097 8192000000 10346436368 10745339904 1141532816 12.230 99 84 163 3 1442630800 281716592 19381392
08:56:10 4131 8192000000 10399638224 10807808000 1138843760 12.205 100 56 113 2 1494901440 341707616 14350064
08:57:17 10230 8192000000 10366889920 10779848704 1145742304 12.270 100 42 82 1 1479341600 323543568 10055728
08:58:29 14556 8192000000 10429320480 10851516416 1132369760 12.144 99 36 72 1 1516729712 374589920 9770032
08:59:50 19015 8192000000 10366681408 10871943168 1140727776 12.222 99 29 58 1 1478042176 329424256 7890144
09:01:22 18102 8192000000 10357572272 10839506944 1133665648 12.156 100 23 44 1 1481578960 341523952 6389360
09:02:45 20619 8192000000 10341079920 10836951040 1117736480 12.006 100 21 42 0 1459136224 335348960 6050784
09:04:12 29845 8192000000 10075373856 10493497344 1098957920 11.828 98 33 66 0 1146276992 39740608 7578464
09:05:44 36420 8192000000 10111838304 10559860736 1112367904 11.955 99 34 68 1 1216966736 96905456 7693376
09:07:57 179460 8192000000 10103160736 10536771584 1083981744 11.685 96 22 44 0 1152847392 63921216 4944432
09:11:56 14941 8192000000 10078409856 10508832768 1121856000 12.045 99 27 53 1 1176883600 49503296 5524304
09:13:20 30863 8192000000 10114464992 10586689536 1133666016 12.156 100 21 42 0 1211003456 71963440 5374000
09:15:14 71468 8192000000 10108858944 10536235008 1114233200 11.972 98 37 75 1 1181137600 58632032 8272368
09:17:52 61066 8192000000 10118600736 10558033920 1129988784 12.121 99 38 75 1 1231220624 93666048 7565792
09:19:57 125897 8192000000 10121064304 10581610496 1136793888 12.185 100 22 44 0 1203789936 62653936 4342112
09:23:23 55742 8192000000 10094549024 10626207744 1137117280 12.188 100 4 8 0 1191666576 54051072 498224
09:25:20 121511 8192000000 10120413744 10569048064 1145705744 12.269 99 17 32 1 1198275600 49815072 2754784
09:28:22 44115 8192000000 10134367344 10575089664 1117938288 12.008 97 24 48 1 1179409584 56328784 5142512
09:30:07 62175 8192000000 10195293456 10637959168 1158614560 12.390 100 18 36 2 1299758320 136861200 4282560
09:32:21 70390 8192000000 10120460352 10645741568 1124133440 12.066 98 9 18 0 1246858512 120705328 2019744
09:34:33 50411 8192000000 10084514960 10532327424 1125440528 12.078 98 32 64 0 1167103824 35842544 5820752
09:36:32 169451 8192000000 10058053312 10487631872 1077348656 11.622 95 29 58 2 1127012496 44149360 5514480
09:40:35 37949 8192000000 10080771472 10518065152 1115290592 11.982 99 43 86 0 1158556224 34322336 8943296
09:42:15 99952 8192000000 10089871440 10541891584 1103087744 11.867 97 15 31 0 1150431056 44438384 2904928
09:45:16 53518 8192000000 10107757792 10548133888 1106548784 11.900 98 28 56 1 1182376944 69540832 6287328
09:47:17 57758 8192000000 10073445360 10532806656 1105231984 11.887 98 21 41 1 1160152864 50302624 4618256
09:49:16 65099 8192000000 10105425856 10549932032 1091823424 11.760 97 32 64 0 1151123280 52047792 7252064
09:51:49 62017 8192000000 10157358736 10607329280 1104040640 11.876 98 21 42 0 1262671328 153844352 4786336
09:53:53 52932 8192000000 10108943616 10547032064 1118120288 12.009 99 23 46 0 1183727680 60696272 4911120
09:55:55 49596 8192000000 10078379824 10642509824 1115440016 11.984 99 9 18 0 1212115424 94615024 2060384
09:57:52 135231 8192000000 10082486016 10522062848 1094260928 11.783 96 33 66 1 1149365648 48436992 6667728
10:01:08 77842 8192000000 10074600800 10567131136 1129667520 12.118 100 16 32 0 1206410320 73162512 3580288
10:03:28 65246 8192000000 10086027600 10560622592 1116834880 11.997 98 10 20 0 1194199344 74790560 2573904
10:05:34 51505 8192000000 10085761728 10544091136 1135401088 12.172 100 17 34 0 1205517024 66121440 3994496
10:07:44 94914 8192000000 10067259168 10595688448 1126564800 12.089 99 16 33 0 1211337680 81339472 3433408
10:10:19 67776 8192000000 10055383472 10531860480 1079971472 11.647 95 18 36 0 1131583056 47741136 3870448
10:12:27 67325 8192000000 10116534896 10559037440 1119739952 12.025 98 24 48 0 1167760352 42549136 5471264
10:14:42 54751 8192000000 10138888416 10571456512 1111371520 11.945 97 29 58 0 1186373104 68995632 6005952
10:16:58 49342 8192000000 10096753200 10642903040 1142409616 12.238 100 10 20 0 1267818992 122895280 2514096
10:18:48 76940 8192000000 10183530080 10643922944 1140390496 12.219 99 29 58 1 1198337936 52094304 5853136
10:21:18 50955 8192000000 10091424800 10524684288 1108791200 11.921 96 34 68 0 1151202352 35674864 6736288
10:23:53 82756 8192000000 10092104896 10528587776 1117627072 12.005 98 37 74 0 1176432928 51469968 7335888
10:27:30 69350 8192000000 10095372656 10547912704 1127878384 12.101 99 17 34 1 1182149712 50609056 3662272
10:29:40 77747 8192000000 10088908496 10564251648 1146940448 12.281 100 17 34 1 1209478608 58232928 4305232
10:32:12 69692 8192000000 10088744432 10544824320 1121900336 12.045 98 16 32 0 1152972736 27930208 3142192
10:34:25 62827 8192000000 10076641952 10533761024 1132284144 12.143 99 11 23 0 1178889952 44203104 2402704
10:36:35 43435 8192000000 9292084688 9615626240 419641808 4.872 37 9 17 0 453812112 32593120 1577184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2021 10:38:24 to 29-10-2021 10:38:39.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2021 10:38:39 to 29-10-2021 10:38:41.

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          224226
MON$OLDEST_ACTIVE               224227
MON$OLDEST_SNAPSHOT             224227
MON$NEXT_TRANSACTION            224227
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                       2057760
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 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 ---
:75: 
:76: mscs-test-161.budzdorov.ru    Fri Oct 29 10:37:24 2021
:77:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:78: 
--- end of diff output ---

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

29.10.21 10:38:42. 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
2021-10-29 10:38:45 No severe PSQL-related problems occured
29.10.21 10:38:46. End of report.