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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16531544 kB
MemAvailable 31460732 kB
Buffers 20 kB
Cached 15077972 kB
SwapCached 2348 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-04-11 04:37:15
DTS_END                         2022-04-11 04:37:15.3870
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/42210
TRN_ID                          215344
ATT_ID                          574
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: 8994.91 21239 1079389
customer order: creation 168.23 436 20187
customer order: refuse 14.82 397 1778
order to supplier: creation 44.14 1382 5297
order to supplier: removal 3.44 151125 413
invoice (draft): creation 44.84 3011 5381
invoice (draft): removal 50.56 905 6067
invoice accept: apply 40.28 26350 4834
invoice accept: cancel 45.78 1071 5494
customer reserve: creation 3786.17 250 454340
customer reserve: removal 4508.24 91 540989
realization accept: apply 81.00 263 9720
realization accept: cancel 82.53 159 9904
payment from customer: creation 56.98 170 6837
payment from customer: removal 5.28 120 633
payment to supplier: creation 47.02 188 5642
payment to supplier: removal 3.52 123 422
service: total inventory turnovers 3.97 3365 476
service: total monetary turnovers 7.17 263 860
service: aggregate perf. data 0.92 15585 110
service: refresh index statistics 0.04 219531 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-04-2022 04:38:20 to 11-04-2022 04:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5366
TEST_TIME 2 5393
TEST_TIME 3 5374
TEST_TIME 4 5986
TEST_TIME 5 5428
TEST_TIME 6 5450
TEST_TIME 7 5448
TEST_TIME 8 5498
TEST_TIME 9 5538
TEST_TIME 10 5566
TEST_TIME 11 5678
TEST_TIME 12 6003
TEST_TIME 13 6193
TEST_TIME 14 6299
TEST_TIME 15 6660
TEST_TIME 16 7466
TEST_TIME 17 7700
TEST_TIME 18 7763
TEST_TIME 19 8395
TEST_TIME 20 8420
TEST_TIME 21 8264
TEST_TIME 22 8306
TEST_TIME 23 8398
TEST_TIME 24 8851
TEST_TIME 25 9270
TEST_TIME 26 9099
TEST_TIME 27 9616
TEST_TIME 28 10235
TEST_TIME 29 10731
TEST_TIME 30 10965
TEST_TIME 31 11229
TEST_TIME 32 11049
TEST_TIME 33 10839
TEST_TIME 34 10665
TEST_TIME 35 10417
TEST_TIME 36 10346
TEST_TIME 37 10292
TEST_TIME 38 10201
TEST_TIME 39 10123
TEST_TIME 40 10134
TEST_TIME 41 9967
TEST_TIME 42 9833
TEST_TIME 43 9803
TEST_TIME 44 9724
TEST_TIME 45 9630
TEST_TIME 46 9495
TEST_TIME 47 9385
TEST_TIME 48 9275
TEST_TIME 49 9326
TEST_TIME 50 9233
TEST_TIME 51 9277
TEST_TIME 52 9341
TEST_TIME 53 9295
TEST_TIME 54 9248
TEST_TIME 55 9207
TEST_TIME 56 9179
TEST_TIME 57 9248
TEST_TIME 58 9239
TEST_TIME 59 9242
TEST_TIME 60 9402
TEST_TIME 61 9662
TEST_TIME 62 9633
TEST_TIME 63 9574
TEST_TIME 64 9575
TEST_TIME 65 9620
TEST_TIME 66 9648
TEST_TIME 67 9598
TEST_TIME 68 9535
TEST_TIME 69 9475
TEST_TIME 70 9473
TEST_TIME 71 9626
TEST_TIME 72 9652
TEST_TIME 73 9624
TEST_TIME 74 9637
TEST_TIME 75 9650
TEST_TIME 76 9606
TEST_TIME 77 9635
TEST_TIME 78 9662
TEST_TIME 79 9610
TEST_TIME 80 9524
TEST_TIME 81 9504
TEST_TIME 82 9445
TEST_TIME 83 9452
TEST_TIME 84 9440
TEST_TIME 85 9382
TEST_TIME 86 9362
TEST_TIME 87 9308
TEST_TIME 88 9310
TEST_TIME 89 9255
TEST_TIME 90 9261
TEST_TIME 91 9262
TEST_TIME 92 9232
TEST_TIME 93 9238
TEST_TIME 94 9268
TEST_TIME 95 9262
TEST_TIME 96 9332
TEST_TIME 97 9308
TEST_TIME 98 9315
TEST_TIME 99 9328
TEST_TIME 100 9380
TEST_TIME 101 9351
TEST_TIME 102 9346
TEST_TIME 103 9404
TEST_TIME 104 9358
TEST_TIME 105 9330
TEST_TIME 106 9268
TEST_TIME 107 9246
TEST_TIME 108 9234
TEST_TIME 109 9194
TEST_TIME 110 9155
TEST_TIME 111 9119
TEST_TIME 112 9069
TEST_TIME 113 9035
TEST_TIME 114 9000
TEST_TIME 115 9014
TEST_TIME 116 9009
TEST_TIME 117 9028
TEST_TIME 118 9028
TEST_TIME 119 9046
TEST_TIME 120 9015

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-04-2022 04:38:24 to 11-04-2022 04:38:27.

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 1497802 1497802 0 0.00 0 20648 13 0 0
doc_list_biud 1497802 1497802 0 0.00 0 28331 38 0 0
sp_add_invoice_to_stock 4940 4834 106 2.14 80 220085 26350 0 106
sp_cancel_adding_invoice 5494 5494 0 0.00 7 58318 1071 0 0
sp_cancel_client_order 1778 1778 0 0.00 5 17183 398 0 0
sp_cancel_customer_reserve 540989 540989 0 0.00 1 26711 91 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 4 5976 120 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 5220 123 0 0
sp_cancel_supplier_invoice 6073 6067 6 0.09 3 28427 906 0 6
sp_cancel_supplier_order 413 413 0 0.00 9 1312016 151126 0 0
sp_cancel_write_off 9904 9904 0 0.00 2 16741 160 0 0
sp_client_order 20187 20187 0 0.00 6 31040 437 0 0
sp_customer_reserve 454340 454340 0 0.00 0 93579 250 0 0
sp_fill_shopping_cart 30861 30861 0 0.00 2 22249 75 0 0
sp_fill_shopping_cart_clo_res 454315 454315 0 0.00 0 24667 18 0 0
sp_get_clo_for_invoice 4798 4798 0 0.00 0 22978 264 0 0
sp_kill_cost_storno 16453 16453 0 0.00 0 16722 67 0 0
sp_kill_qstorno_ret_qs2qd 1098362 1098362 0 0.00 0 28330 40 0 0
sp_kill_qty_storno 579163 579163 0 0.00 0 28331 92 0 0
sp_lock_selected_doc 20171 20171 0 0.00 0 8307 7 0 0
sp_make_cost_storno 26997 26997 0 0.00 0 19029 76 0 0
sp_multiply_rows_for_pdistr 26997 26997 0 0.00 0 8547 35 0 0
sp_multiply_rows_for_qdistr 20187 20187 0 0.00 0 11999 170 0 0
sp_pay_from_customer 6837 6837 0 0.00 6 23521 171 0 0
sp_pay_to_supplier 5642 5642 0 0.00 6 25931 189 0 0
sp_payment_common 12479 12479 0 0.00 2 7922 39 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 12312 318 0 0
sp_qd_handle_on_invoice_upd_sts 10292 10292 0 0.00 0 20316 479 0 0
sp_qd_handle_on_reserve_upd_sts 9720 9720 0 0.00 0 12396 63 0 0
sp_reserve_write_off 9720 9720 0 0.00 9 17387 263 0 0
sp_supplier_invoice 5381 5381 0 0.00 10 83641 3012 0 0
sp_supplier_order 5297 5297 0 0.00 8 59814 1382 0 0
srv_aggregate_perf_data 110 110 0 0.00 2748 166198 15586 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 832 111887 36919 0 0
srv_find_qd_qs_mism 1031721 1031721 0 0.00 0 20644 10 0 0
srv_make_invnt_saldo 476 476 0 0.00 73 68474 3365 0 0
srv_make_money_saldo 860 860 0 0.00 1 11124 264 0 0
srv_recalc_idx_stat 5 5 0 0.00 150148 399045 219531 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4834 4834 0 0.00 1 17106 783 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 1 6210 330 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 5225 30 0 0
t$perf-norm:sp_cancel_customer_reserve 692 692 0 0.00 0 1570 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 950 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 682 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 506 27 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 84607 6018 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 1635 17 0 0
t$perf-norm:sp_client_order 20187 20187 0 0.00 0 18586 34 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 2305 16 0 0
t$perf-norm:sp_pay_from_customer 6837 6837 0 0.00 0 8357 17 0 0
t$perf-norm:sp_pay_to_supplier 5642 5642 0 0.00 0 4035 16 0 0
t$perf-norm:sp_reserve_write_off 9720 9720 0 0.00 0 13870 21 0 0
t$perf-norm:sp_supplier_invoice 5381 5381 0 0.00 0 3770 28 0 0
t$perf-norm:sp_supplier_order 5297 5297 0 0.00 0 6582 29 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 245 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 408 12 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 676 14 0 0
t$perf-norm:srv_make_money_saldo 860 860 0 0.00 0 558 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 171 63 0 0
v_add_invoice_to_stock 9808 9596 212 2.16 0 18207 27 0 212
v_all_customers 37148 37148 0 0.00 0 119 3 0 0
v_all_suppliers 29652 29652 0 0.00 0 91 4 0 0
v_all_wares 40374 40374 0 0.00 0 126 3 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 3258 17 0 0
v_cancel_client_order 3556 3556 0 0.00 0 16848 13 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 4215 16 0 0
v_cancel_customer_reserve 1384 1384 0 0.00 0 21230 24 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 5209 18 0 0
v_cancel_supplier_invoice 784 772 12 1.53 0 3748 28 0 12
v_cancel_supplier_order 826 826 0 0.00 0 2167 13 0 0
v_cancel_write_off 1886 1886 0 0.00 0 1293 19 0 0
v_max_id_clo_ord 5297 5297 0 0.00 0 298 4 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 1515 11 0 0
v_max_id_ord_sup 5376 5376 0 0.00 0 3018 7 0 0
v_max_non_paid_invoice 1489 1489 0 0.00 0 91 7 0 0
v_max_non_paid_realizn 3357 3357 0 0.00 0 4139 7 0 0
v_min_id_clo_ord 5297 5297 0 0.00 0 1994 7 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 378 6 0 0
v_min_id_ord_sup 5376 5376 0 0.00 0 5137 8 0 0
v_min_non_paid_invoice 5642 5642 0 0.00 0 6307 14 0 0
v_min_non_paid_realizn 6837 6837 0 0.00 0 6096 13 0 0
v_our_firm 10186 10186 0 0.00 0 44 4 0 0
v_reserve_write_off 19440 19440 0 0.00 0 17116 10 0 0
x_lock_depdocs_on_canc_invoice 11175 11175 0 0.00 0 43883 116 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 5881 311 0 0
x_make_qstorno_1000_1200 5297 5297 0 0.00 2 59799 1234 0 0
x_make_qstorno_1200_2000 5381 5381 0 0.00 5 83314 2804 0 0
x_make_qstorno_2100_3300 454340 454340 0 0.00 0 93578 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-04-2022 04:38:28 to 11-04-2022 04:38:32.

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:19 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 25620 8192000000 9720251856 10014871552 790273616 8.798 100 90 180 0 820533760 7802400 22457744
02:09:56 24356 8192000000 9763356464 10072342528 835415936 9.254 100 90 180 0 869523520 12802576 21305008
02:11:28 10413 8192000000 9809320448 10132074496 880587632 9.706 100 77 154 1 919623680 21467424 17568624
02:12:44 9978 8192000000 9877923568 10212667392 920367360 10.100 100 83 165 0 988650528 50702192 17580976
02:13:58 5947 8192000000 9907018640 10253438976 951383840 10.405 100 84 158 1 1026245664 58293440 16568384
02:15:07 2367 8192000000 9959560720 10312245248 996072560 10.840 100 90 175 1 1076338608 62485776 17780272
02:16:13 1642 8192000000 10018524192 10383458304 1023351936 11.104 100 92 180 0 1139725392 97814976 18558480
02:17:16 1186 8192000000 10017233168 10382622720 1046882688 11.331 100 96 186 0 1136748000 70896544 18968768
02:18:19 1012 8192000000 10019735504 10394279936 1063753184 11.492 100 96 183 0 1142300640 60070864 18476592
02:19:22 1514 8192000000 10051238608 10431954944 1071113328 11.563 100 96 188 0 1173390064 81769616 20507120
02:20:24 1078 8192000000 10060273216 10442465280 1072609136 11.577 100 98 190 0 1180780848 88170032 20001680
02:21:27 1138 8192000000 10063047024 10443927552 1077897456 11.627 100 94 179 0 1184111600 87795520 18418624
02:22:29 929 8192000000 10049028960 10438135808 1079391744 11.642 100 95 187 0 1170337472 70531744 20413984
02:23:32 863 8192000000 10071019008 10460856320 1087043216 11.715 100 95 182 0 1191304976 85522704 18739056
02:24:34 3763 8192000000 10041034176 10432651264 1086215376 11.707 100 65 127 0 1162651376 63630320 12805680
02:25:39 1006 8192000000 10059585264 10446995456 1095033504 11.790 100 98 189 1 1182931568 68241616 19656448
02:26:42 1072 8192000000 10062315008 10451333120 1091606848 11.758 100 92 182 0 1182989488 71854432 19528208
02:27:44 1187 8192000000 10072234736 10459967488 1097207520 11.811 100 96 192 0 1193512160 76049168 20255472
02:28:46 1176 8192000000 10062642896 10451152896 1079499008 11.643 100 93 176 3 1185027584 88168688 17359888
02:29:48 1166 8192000000 10042448720 10425741312 1085808240 11.703 100 97 185 1 1162256320 56220496 20227584
02:30:51 1084 8192000000 10035447136 10415562752 1066753056 11.521 100 95 188 1 1152919056 66508384 19657616
02:31:53 917 8192000000 10035333360 10420469760 1077142784 11.620 100 94 181 2 1157058704 61604464 18311456
02:32:55 992 8192000000 10001042224 10377719808 1048278096 11.344 100 91 167 3 1122756400 56063952 18414352
02:33:57 1159 8192000000 10000588432 10378870784 1052585296 11.385 100 94 174 1 1124896624 54237936 18073392
02:34:59 1253 8192000000 10006794816 10378985472 1045028160 11.313 100 95 173 3 1126761248 63710528 18022560
02:36:01 1266 8192000000 9982196640 10352218112 1022466032 11.096 100 82 162 0 1104352480 64816784 17069664
02:37:04 6107 8192000000 9961937008 10339733504 1001117296 10.889 97 70 129 2 1063217792 49930448 12170048
02:38:25 17501 8192000000 9984083136 10365079552 1049838864 11.359 100 58 105 1 1108506992 47994064 10674064
02:39:46 884 8192000000 10049143184 10424365056 1070987584 11.562 100 82 162 0 1167777072 80118848 16670640
02:40:52 5620 8192000000 10073045312 10460123136 1085838848 11.703 100 69 134 0 1197314912 96948560 14527504
02:42:02 7034 8192000000 10091992336 10478665728 1103500144 11.871 100 78 151 1 1209397840 89967600 15930096
02:43:17 4687 8192000000 10148802624 10542514176 1120421584 12.031 100 62 117 1 1260438800 127632592 12384624
02:44:25 5069 8192000000 10218976976 10607579136 1126825232 12.091 100 87 173 0 1330535984 185636496 18074256
02:45:35 1119 8192000000 10261960736 10652655616 1142230560 12.237 100 91 176 0 1368161840 206786048 19145232
02:46:39 2797 8192000000 10275214832 10670731264 1153597024 12.343 100 87 164 3 1390064064 218256784 18210256
02:47:50 4120 8192000000 10311191408 10714640384 1150559952 12.315 100 80 158 1 1426527776 257063600 18904224
02:49:03 1089 8192000000 10372504128 10782347264 1168648928 12.484 100 96 191 1 1481307680 290990208 21668544
02:50:05 992 8192000000 10404312000 10808532992 1169918064 12.496 100 98 190 1 1514284096 322033024 22333008
02:51:11 1412 8192000000 10414670656 10818109440 1173289184 12.528 100 90 177 1 1523963968 329755616 20919168
02:52:17 1066 8192000000 10427213792 10885849088 1180596608 12.596 100 93 179 0 1535995536 334058432 21340496
02:53:21 917 8192000000 10401358256 10868195328 1176873488 12.561 100 89 173 2 1511244080 313183696 21186896
02:54:29 826 8192000000 10414663088 10843357184 1186296320 12.649 100 98 184 1 1519578912 312732800 20549792
02:55:34 4059 8192000000 10425999200 10857828352 1176453712 12.557 100 70 139 0 1535363872 342453648 16456512
02:56:42 16017 8192000000 10399961968 10838679552 1163387888 12.435 99 50 97 1 1486160176 310541744 12230544
02:58:12 6289 8192000000 10435214624 10862510080 1170513248 12.502 100 49 95 0 1542755120 360203504 12038368
02:59:21 2691 8192000000 10484091696 10934611968 1185851040 12.645 100 69 135 0 1590474208 388332608 16290560
03:00:31 3305 8192000000 10493673296 10940960768 1179642464 12.587 100 65 127 0 1598064160 402510432 15911264
03:01:46 18111 8192000000 10454573504 10946752512 1186081264 12.647 100 48 93 0 1612917920 414981648 11855008
03:03:11 21187 8192000000 10460824816 10914512896 1177271056 12.565 100 33 66 0 1576055584 390553728 8230800
03:04:33 32768 8192000000 10343717264 10829750272 1132408048 12.144 97 31 60 0 1458792848 318025024 8359776
03:06:09 29165 8192000000 10177543328 10683244544 1138259408 12.199 100 14 28 1 1357067552 214606848 4201296
03:07:40 99941 8192000000 10097025264 10579677184 1076307632 11.612 93 10 20 0 1121245088 43050592 1886864
03:10:31 44773 8192000000 10163138768 10625986560 1137677936 12.194 100 60 118 0 1225693488 75826192 12189360
03:12:49 31714 8192000000 10135498800 10574675968 1139713856 12.213 100 37 74 1 1235240160 87680400 7845904
03:14:23 33699 8192000000 10118791056 10586644480 1134135056 12.160 99 20 40 1 1210625024 71968160 4521808
03:16:11 101247 8192000000 10068133088 10585542656 1114712176 11.977 98 8 16 0 1197700640 81246368 1742096
03:18:56 61233 8192000000 10068261632 10525360128 1096668848 11.806 96 17 34 0 1148621632 48360480 3592304
03:20:58 56414 8192000000 10014369840 10451910656 1062767968 11.483 94 23 43 1 1083771248 16414496 4588784
03:22:55 84468 8192000000 10094526400 10561294336 1122014144 12.046 100 11 22 0 1171790832 47722320 2054368
03:25:27 28767 8192000000 10107013968 10548572160 1119815504 12.025 100 46 88 0 1194656208 65318144 9522560
03:26:59 39588 8192000000 10095481920 10558070784 1127084512 12.094 100 13 26 1 1178020880 48173776 2762592
03:28:52 32505 8192000000 10054460368 10503290880 1117807776 12.006 99 15 30 1 1157895472 36610416 3477280
03:30:25 27853 8192000000 10089796112 10535940096 1106767920 11.902 97 29 57 0 1154990400 42171040 6051440
03:31:59 36555 8192000000 10176176288 10620325888 1137488032 12.192 99 37 74 0 1255631472 109853728 8289712
03:33:38 24269 8192000000 10204644992 10662834176 1129017232 12.112 99 38 73 1 1319388768 182004992 8366544
03:35:04 46293 8192000000 10223883536 10662580224 1139332224 12.209 99 26 52 0 1295810192 150524672 5953296
03:37:24 48898 8192000000 10196495744 10651987968 1121347424 12.040 98 20 40 1 1284625008 158682992 4594592
03:39:14 55158 8192000000 10160081072 10593271808 1133139168 12.151 99 32 64 0 1212186384 72418384 6628832
03:41:09 57955 8192000000 10127241760 10630905856 1134109360 12.160 100 48 95 1 1293634432 148668816 10856256
03:43:14 56637 8192000000 10082778784 10587725824 1112338528 11.955 98 9 18 0 1152911280 38927328 1645424
03:45:11 49043 8192000000 10141226640 10586144768 1137739184 12.194 99 36 72 4 1224548704 78992608 7816912
03:47:03 34032 8192000000 10200991248 10671579136 1153123232 12.339 100 31 62 2 1282250816 122400992 6726592
03:48:39 56110 8192000000 10089547648 10578051072 1096824992 11.808 96 13 26 0 1138715152 39213936 2676224
03:50:39 18023 8192000000 10108595312 10533957632 1139276064 12.209 100 56 112 2 1192681328 42235648 11169616
03:52:04 24176 8192000000 10122248080 10563543040 1136023424 12.178 99 35 69 0 1189389728 46903488 6462816
03:53:34 36517 8192000000 10155008480 10620874752 1146417808 12.276 100 44 85 1 1244318256 88947584 8952864
03:55:17 38815 8192000000 10122251408 10553561088 1134515072 12.164 99 45 87 0 1194113120 50034880 9563168
03:56:57 79270 8192000000 10121388624 10568970240 1150726672 12.316 100 43 84 0 1204269248 44998992 8543584
03:59:17 14529 8192000000 10088106272 10544812032 1121636048 12.042 97 34 60 3 1178153104 48865216 7651840
04:00:32 68503 8192000000 10080011104 10589106176 1134896848 12.168 99 20 40 0 1200754192 61930800 3926544
04:02:46 61076 8192000000 10100741744 10563829760 1132191408 12.142 99 23 46 2 1193230032 56073952 4964672
04:04:48 51584 8192000000 10072763232 10554228736 1135661040 12.175 100 9 18 0 1193735840 56090096 1984704
04:06:40 49830 8192000000 10105328032 10558693376 1125530656 12.079 99 21 42 1 1189066096 59143440 4392000
04:08:44 65351 8192000000 10102122992 10549563392 1091126288 11.753 95 24 48 0 1139915808 43546592 5242928
04:10:50 67437 8192000000 10080922528 10525503488 1108332608 11.917 96 26 51 1 1148448400 35019216 5096576
04:13:00 51918 8192000000 10104750592 10587672576 1106347616 11.898 97 30 60 2 1197947552 85376928 6223008
04:14:58 40472 8192000000 10130083728 10579881984 1120440736 12.031 99 41 82 0 1209004736 80521248 8042752
04:16:41 79538 8192000000 10081125728 10565726208 1123161872 12.057 99 36 72 1 1208532928 78431760 6939296
04:19:02 63525 8192000000 10176760464 10644131840 1144357232 12.256 100 27 54 1 1246608112 96166320 6084560
04:21:07 111886 8192000000 10052798800 10520354816 1110302592 11.935 98 15 30 0 1139134800 25792112 3040096
04:24:08 15744 8192000000 10069065712 10501754880 1112866176 11.960 99 65 128 1 1160033264 33321888 13845200
04:26:20 58551 8192000000 10035525120 10483445760 1081032992 11.657 97 10 20 0 1106872832 23673600 2166240
04:28:19 108093 8192000000 10065367376 10514014208 1101500768 11.852 98 12 24 0 1143455872 39151808 2803296
04:31:24 73129 8192000000 10104185536 10582814720 1115081616 11.981 99 11 22 0 1197783984 80231952 2470416
04:33:39 34742 8192000000 10095257840 10537230336 1110812192 11.940 99 27 54 1 1171712432 55144432 5755808
04:35:17 23426 8192000000 10105364000 10539417600 1134789856 12.166 100 42 84 0 1195988640 52690096 8508688
04:36:41 52130 8192000000 9100980720 9359937536 241952864 2.868 22 5 10 0 267202352 24255840 993648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-04-2022 04:38:34 to 11-04-2022 04:38:50.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-04-2022 04:38:51 to 11-04-2022 04:38:53.

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          214707
MON$OLDEST_ACTIVE               216100
MON$OLDEST_SNAPSHOT             216100
MON$NEXT_TRANSACTION            216100
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                       2030880
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:141: 
:142: mscs-test-161.budzdorov.ru    Mon Apr 11 04:37:35 2022
:143:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:144: 
:145: 
:146: mscs-test-161.budzdorov.ru    Mon Apr 11 04:37:36 2022
:147:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:148: 
--- end of diff output ---

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

11.04.22 04:38:54. 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-04-11 04:38:57 No severe PSQL-related problems occured
11.04.22 04:38:58. End of report.