Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.08.2021 13: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 15879056 kB
MemAvailable 30897552 kB
Buffers 0 kB
Cached 15771520 kB
SwapCached 336 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-08-29 13:37:07
DTS_END                         2021-08-29 13:37:07.0530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58540
TRN_ID                          240990
ATT_ID                          708
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: 9135.35 21010 1096242
customer order: creation 175.57 392 21068
customer order: refuse 15.41 317 1849
order to supplier: creation 47.11 1430 5653
order to supplier: removal 3.43 116415 411
invoice (draft): creation 46.75 3466 5610
invoice (draft): removal 53.86 997 6463
invoice accept: apply 40.37 19615 4844
invoice accept: cancel 48.46 1016 5815
customer reserve: creation 3724.58 194 446950
customer reserve: removal 4681.68 67 561801
realization accept: apply 84.96 181 10195
realization accept: cancel 83.33 121 10000
payment from customer: creation 60.30 103 7236
payment from customer: removal 5.53 100 664
payment to supplier: creation 50.06 125 6007
payment to supplier: removal 3.27 88 392
service: total inventory turnovers 3.68 3885 441
service: total monetary turnovers 6.06 563 727
service: aggregate perf. data 0.92 13361 110
service: refresh index statistics 0.05 257766 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-08-2021 13:38:20 to 29-08-2021 13:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4967
TEST_TIME 2 5030
TEST_TIME 3 4778
TEST_TIME 4 4513
TEST_TIME 5 4448
TEST_TIME 6 4803
TEST_TIME 7 5203
TEST_TIME 8 5351
TEST_TIME 9 5763
TEST_TIME 10 6106
TEST_TIME 11 6529
TEST_TIME 12 6407
TEST_TIME 13 6806
TEST_TIME 14 6874
TEST_TIME 15 7390
TEST_TIME 16 7652
TEST_TIME 17 7731
TEST_TIME 18 8251
TEST_TIME 19 8345
TEST_TIME 20 8327
TEST_TIME 21 8550
TEST_TIME 22 8424
TEST_TIME 23 8550
TEST_TIME 24 9226
TEST_TIME 25 9209
TEST_TIME 26 9600
TEST_TIME 27 10477
TEST_TIME 28 11032
TEST_TIME 29 10822
TEST_TIME 30 10883
TEST_TIME 31 11040
TEST_TIME 32 10713
TEST_TIME 33 10578
TEST_TIME 34 10612
TEST_TIME 35 10466
TEST_TIME 36 10352
TEST_TIME 37 10277
TEST_TIME 38 10172
TEST_TIME 39 10250
TEST_TIME 40 10098
TEST_TIME 41 10161
TEST_TIME 42 10224
TEST_TIME 43 10167
TEST_TIME 44 10075
TEST_TIME 45 10001
TEST_TIME 46 9821
TEST_TIME 47 9891
TEST_TIME 48 9860
TEST_TIME 49 9808
TEST_TIME 50 9716
TEST_TIME 51 9687
TEST_TIME 52 9610
TEST_TIME 53 9721
TEST_TIME 54 9704
TEST_TIME 55 9707
TEST_TIME 56 9726
TEST_TIME 57 9761
TEST_TIME 58 9750
TEST_TIME 59 9805
TEST_TIME 60 9769
TEST_TIME 61 9806
TEST_TIME 62 9890
TEST_TIME 63 9999
TEST_TIME 64 9844
TEST_TIME 65 9733
TEST_TIME 66 9700
TEST_TIME 67 9623
TEST_TIME 68 9645
TEST_TIME 69 9642
TEST_TIME 70 9631
TEST_TIME 71 9600
TEST_TIME 72 9570
TEST_TIME 73 9544
TEST_TIME 74 9570
TEST_TIME 75 9624
TEST_TIME 76 9658
TEST_TIME 77 9575
TEST_TIME 78 9668
TEST_TIME 79 9807
TEST_TIME 80 9788
TEST_TIME 81 9722
TEST_TIME 82 9772
TEST_TIME 83 9678
TEST_TIME 84 9629
TEST_TIME 85 9553
TEST_TIME 86 9537
TEST_TIME 87 9490
TEST_TIME 88 9427
TEST_TIME 89 9412
TEST_TIME 90 9343
TEST_TIME 91 9360
TEST_TIME 92 9357
TEST_TIME 93 9331
TEST_TIME 94 9351
TEST_TIME 95 9379
TEST_TIME 96 9424
TEST_TIME 97 9421
TEST_TIME 98 9415
TEST_TIME 99 9423
TEST_TIME 100 9462
TEST_TIME 101 9507
TEST_TIME 102 9502
TEST_TIME 103 9496
TEST_TIME 104 9483
TEST_TIME 105 9441
TEST_TIME 106 9391
TEST_TIME 107 9425
TEST_TIME 108 9380
TEST_TIME 109 9324
TEST_TIME 110 9310
TEST_TIME 111 9264
TEST_TIME 112 9218
TEST_TIME 113 9210
TEST_TIME 114 9200
TEST_TIME 115 9177
TEST_TIME 116 9143
TEST_TIME 117 9135
TEST_TIME 118 9161
TEST_TIME 119 9213
TEST_TIME 120 9141

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-08-2021 13:38:23 to 29-08-2021 13: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 1513358 1513358 0 0.00 0 39188 7 0 0
doc_list_biud 1513357 1513357 0 0.00 0 31560 32 0 0
sp_add_invoice_to_stock 4938 4844 94 1.90 32 194855 19616 0 94
sp_cancel_adding_invoice 5815 5815 0 0.00 4 86422 1017 0 0
sp_cancel_client_order 1849 1849 0 0.00 4 10696 317 0 0
sp_cancel_customer_reserve 561801 561801 0 0.00 1 31562 68 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 11204 101 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 2620 89 0 0
sp_cancel_supplier_invoice 6467 6463 4 0.06 2 14385 998 0 4
sp_cancel_supplier_order 411 411 0 0.00 7 1129135 116416 0 0
sp_cancel_write_off 10000 10000 0 0.00 1 7764 122 0 0
sp_client_order 21068 21068 0 0.00 4 23904 392 0 0
sp_customer_reserve 446950 446950 0 0.00 0 54521 195 0 0
sp_fill_shopping_cart 32328 32328 0 0.00 1 9992 37 0 0
sp_fill_shopping_cart_clo_res 446948 446948 0 0.00 0 13734 17 0 0
sp_get_clo_for_invoice 4840 4840 0 0.00 0 25739 358 0 0
sp_kill_cost_storno 16871 16871 0 0.00 0 39176 67 0 0
sp_kill_qstorno_ret_qs2qd 1140476 1140476 0 0.00 0 31555 37 0 0
sp_kill_qty_storno 601374 601374 0 0.00 0 31560 80 0 0
sp_lock_selected_doc 20860 20860 0 0.00 0 7557 3 0 0
sp_make_cost_storno 28277 28277 0 0.00 0 33538 54 0 0
sp_multiply_rows_for_pdistr 28277 28277 0 0.00 0 18704 30 0 0
sp_multiply_rows_for_qdistr 21068 21068 0 0.00 0 13368 217 0 0
sp_pay_from_customer 7236 7236 0 0.00 4 18389 104 0 0
sp_pay_to_supplier 6007 6007 0 0.00 5 17907 126 0 0
sp_payment_common 13242 13242 0 0.00 1 8314 21 0 0
sp_qd_handle_on_cancel_clo 1849 1849 0 0.00 0 10689 277 0 0
sp_qd_handle_on_invoice_upd_sts 10655 10655 0 0.00 0 17118 380 0 0
sp_qd_handle_on_reserve_upd_sts 10195 10195 0 0.00 0 17687 56 0 0
sp_reserve_write_off 10195 10195 0 0.00 7 18907 181 0 0
sp_supplier_invoice 5610 5610 0 0.00 7 83426 3466 0 0
sp_supplier_order 5653 5653 0 0.00 5 25505 1430 0 0
srv_aggregate_perf_data 110 110 0 0.00 2870 130793 13361 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1517 116317 41817 0 0
srv_find_qd_qs_mism 1050219 1050219 0 0.00 0 13839 6 0 0
srv_make_invnt_saldo 441 441 0 0.00 53 112482 3886 0 0
srv_make_money_saldo 727 727 0 0.00 1 10919 564 0 0
srv_recalc_idx_stat 6 6 0 0.00 148549 467146 257767 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4846 4846 0 0.00 0 11531 178 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 5506 137 0 0
t$perf-norm:sp_cancel_client_order 1849 1849 0 0.00 0 18404 24 0 0
t$perf-norm:sp_cancel_customer_reserve 668 668 0 0.00 0 3899 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 2267 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 3358 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 706 15 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 24525 1627 0 0
t$perf-norm:sp_cancel_write_off 1020 1020 0 0.00 0 1424 8 0 0
t$perf-norm:sp_client_order 21068 21068 0 0.00 0 18752 20 0 0
t$perf-norm:sp_customer_reserve 957 957 0 0.00 0 3718 14 0 0
t$perf-norm:sp_pay_from_customer 7236 7236 0 0.00 0 3657 8 0 0
t$perf-norm:sp_pay_to_supplier 6007 6007 0 0.00 0 4820 8 0 0
t$perf-norm:sp_reserve_write_off 10195 10195 0 0.00 0 18525 13 0 0
t$perf-norm:sp_supplier_invoice 5610 5610 0 0.00 0 10937 22 0 0
t$perf-norm:sp_supplier_order 5653 5653 0 0.00 0 4800 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 398 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 323 8 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 384 7 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 295 3 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 2 10 4 0 0
v_add_invoice_to_stock 9866 9678 188 1.90 0 14436 16 0 188
v_all_customers 38374 38374 0 0.00 0 101 1 0 0
v_all_suppliers 31264 31264 0 0.00 0 22 1 0 0
v_all_wares 42136 42136 0 0.00 0 107 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 3375 10 0 0
v_cancel_client_order 3698 3698 0 0.00 0 6353 7 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 6951 9 0 0
v_cancel_customer_reserve 1336 1336 0 0.00 0 8535 9 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1663 5 0 0
v_cancel_supplier_invoice 818 810 8 0.97 0 1623 15 0 8
v_cancel_supplier_order 822 822 0 0.00 0 459 4 0 0
v_cancel_write_off 2040 2040 0 0.00 0 6991 19 0 0
v_max_id_clo_ord 5652 5652 0 0.00 0 1003 1 0 0
v_max_id_clo_res 957 957 0 0.00 0 2546 12 0 0
v_max_id_ord_sup 5607 5607 0 0.00 0 9966 6 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 76 2 0 0
v_max_non_paid_realizn 3839 3839 0 0.00 0 4709 4 0 0
v_min_id_clo_ord 5652 5652 0 0.00 0 5954 7 0 0
v_min_id_clo_res 957 957 0 0.00 0 4061 9 0 0
v_min_id_ord_sup 5607 5607 0 0.00 0 6351 7 0 0
v_min_non_paid_invoice 6007 6007 0 0.00 0 8309 12 0 0
v_min_non_paid_realizn 7235 7235 0 0.00 0 6338 16 0 0
v_our_firm 10554 10554 0 0.00 0 25 1 0 0
v_reserve_write_off 20390 20390 0 0.00 0 14438 6 0 0
x_lock_depdocs_on_canc_invoice 11873 11873 0 0.00 0 8689 113 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3907 318 0 0
x_make_qstorno_1000_1200 5653 5653 0 0.00 2 25447 1346 0 0
x_make_qstorno_1200_2000 5610 5610 0 0.00 4 83064 3342 0 0
x_make_qstorno_2100_3300 446950 446950 0 0.00 0 49074 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-08-2021 13:38:28 to 29-08-2021 13: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
11:07:10 8 8192000000 8801229968 8813338624 5768432 0.070 3 1 2 0 5869600 65392 35776
11:08:11 28708 8192000000 9722694128 10018992128 792013024 8.815 100 90 179 3 822033216 7782736 22237456
11:09:44 21420 8192000000 9765007504 10073415680 837827584 9.278 100 91 182 0 872575696 13113216 21634896
11:11:12 14940 8192000000 9804926480 10123886592 873214672 9.632 100 84 168 0 913437648 21336192 18886784
11:12:33 11163 8192000000 9880261904 10213605376 927037552 10.165 100 81 162 0 986116432 41703840 17375040
11:13:49 10994 8192000000 9933106448 10279817216 960350480 10.492 100 81 158 2 1038168480 61464960 16353040
11:15:02 4042 8192000000 9968550192 10326159360 997767984 10.857 100 85 170 1 1083608032 68372800 17467248
11:16:09 2557 8192000000 10031496128 10393018368 1019123488 11.064 100 88 176 0 1144291168 108268992 16898688
11:17:13 2090 8192000000 10026049408 10392526848 1046800848 11.330 100 90 179 2 1134121712 68662432 18658432
11:18:16 4409 8192000000 10029632080 10401202176 1063438960 11.489 100 81 163 1 1132325744 52479952 16406832
11:19:21 3374 8192000000 10009924224 10392690688 1069751664 11.550 100 54 110 1 1115351888 34453648 11146576
11:20:28 4451 8192000000 9998536832 10387681280 1078893008 11.637 100 42 82 2 1123011856 35851520 8267328
11:21:33 24423 8192000000 9987746608 10397540352 1080554464 11.653 100 23 46 1 1127996096 42998720 4442912
11:22:58 6956 8192000000 10013082496 10418753536 1091079744 11.753 100 36 71 2 1122708976 24182784 7446448
11:24:05 8506 8192000000 10012719104 10415456256 1099901808 11.837 100 29 58 1 1120623344 14241488 6480048
11:25:16 65235 8192000000 10013693904 10410164224 1084791840 11.693 98 29 56 2 1114787520 23272816 6722864
11:27:23 2728 8192000000 10014276064 10411978752 1087766768 11.721 100 52 105 3 1131045440 32434352 10844320
11:28:26 4988 8192000000 9961692560 10345246720 1050415632 11.365 100 50 101 3 1071785552 9393184 11976736
11:29:32 10097 8192000000 9961218832 10359877632 1014614304 11.020 98 31 60 2 1045930288 24966800 6349184
11:30:42 37159 8192000000 9944936176 10345439232 1002723696 10.905 97 37 73 1 1050168016 39164528 8279792
11:32:20 4321 8192000000 9943992016 10319466496 1018471392 11.057 99 40 78 0 1049984592 22667280 8845920
11:33:24 7245 8192000000 9980255920 10366758912 1053783776 11.397 100 51 100 4 1082128256 17014768 11329712
11:34:34 5431 8192000000 9992437728 10392838144 1068261824 11.535 100 38 77 0 1097062432 20859600 7941008
11:35:45 39074 8192000000 9995518672 10408828928 1074811536 11.598 100 36 73 1 1109446048 27400800 7233712
11:37:27 15313 8192000000 10026460768 10419994624 1099494304 11.833 100 58 113 2 1123557136 12253744 11809088
11:38:49 36725 8192000000 10092165968 10493095936 1112827888 11.959 100 59 118 1 1192818688 68141040 11849760
11:40:31 23709 8192000000 10102449456 10510307328 1136577552 12.183 100 32 64 2 1202948544 59193040 7177952
11:41:55 61412 8192000000 10169831312 10584076288 1149682736 12.307 100 37 73 0 1268309472 110133680 8493056
11:44:00 17417 8192000000 10185677904 10624708608 1162020880 12.422 100 37 73 0 1300713712 130744816 7948016
11:45:24 12876 8192000000 10215073104 10632007680 1165816224 12.458 100 62 122 4 1316614352 136960400 13837728
11:46:43 7450 8192000000 10227609008 10641448960 1163795248 12.439 99 72 144 0 1324104096 143550096 16758752
11:47:56 15189 8192000000 10252375360 10677161984 1178823680 12.579 100 57 113 0 1351329200 160041728 12463792
11:49:18 4626 8192000000 10234399040 10644652032 1163857280 12.439 100 76 148 0 1345378704 164830768 16690656
11:50:26 1546 8192000000 10293855728 10699862016 1161138624 12.414 99 89 177 1 1389885840 208283104 20464112
11:51:31 2763 8192000000 10306328000 10712711168 1166397968 12.463 100 88 174 0 1405213968 217901440 20914560
11:52:38 2578 8192000000 10297928144 10710093824 1150079200 12.310 100 77 154 2 1395163072 227579152 17504720
11:53:44 2461 8192000000 10287856080 10694520832 1156504864 12.371 100 72 142 0 1391470032 218996096 15969072
11:54:50 1513 8192000000 10338678608 10740899840 1150709472 12.316 100 87 172 2 1436372752 266739120 18924160
11:55:56 4401 8192000000 10306585616 10720260096 1153210560 12.340 100 59 118 0 1404316256 236626672 14479024
11:57:04 17611 8192000000 10356494224 10779467776 1138612880 12.202 99 40 80 1 1446330512 297387200 10330432
11:58:26 7971 8192000000 10380005904 10811482112 1143758288 12.251 100 39 79 1 1492229632 338589776 9881568
11:59:36 19434 8192000000 10420270240 10854522880 1134931760 12.168 100 40 78 3 1522616896 377242128 10443008
12:00:58 15559 8192000000 10446046928 10896101376 1138494736 12.201 100 36 72 0 1548540176 400028016 10017424
12:02:40 16775 8192000000 10388029328 10833174528 1124473520 12.069 100 26 52 1 1481775936 350190560 7111856
12:04:27 19658 8192000000 10202733376 10734215168 1104562160 11.881 100 21 42 1 1402135472 292130352 5442960
12:06:01 49156 8192000000 10113707056 10544586752 1114357456 11.974 99 21 42 2 1200650896 81477552 4815888
12:08:08 116317 8192000000 10071694736 10502369280 1099823680 11.836 98 18 36 1 1132784176 28647568 4312928
12:11:17 90072 8192000000 10097851248 10543525888 1125195840 12.076 98 37 72 1 1172421760 39390768 7835152
12:13:52 30272 8192000000 10107214448 10548084736 1143604448 12.249 100 33 65 2 1211142784 60897408 6640928
12:15:24 33689 8192000000 10181577920 10608590848 1156604000 12.371 100 53 104 0 1269595536 101055888 11935648
12:17:03 61718 8192000000 10156178672 10638397440 1157908016 12.384 100 19 38 2 1278222048 115851456 4462576
12:19:11 22523 8192000000 10097603120 10557939712 1157812160 12.383 100 9 18 3 1214458688 54387680 2258848
12:20:34 28747 8192000000 10105655568 10541047808 1143084944 12.245 99 21 40 1 1179850240 32561520 4203776
12:22:08 80470 8192000000 10113702704 10598608896 1151802768 12.326 100 14 28 0 1206485504 52048528 2634208
12:24:32 12952 8192000000 10121361104 10625974272 1153186160 12.339 100 30 55 0 1256509712 97625248 5698304
12:25:46 65717 8192000000 10096690368 10576420864 1157901616 12.384 100 15 30 1 1208352880 46974336 3476928
12:28:28 59603 8192000000 10122993216 10581200896 1149747488 12.307 99 11 22 0 1220032304 68087552 2197264
12:30:35 18570 8192000000 10087447744 10548592640 1138975216 12.206 99 25 49 1 1199954800 55808048 5171536
12:31:54 35806 8192000000 10069383248 10522255360 1100765952 11.845 96 20 40 0 1143859056 39195312 3897792
12:33:32 35351 8192000000 10075696880 10531827712 1086340608 11.708 95 23 46 2 1128644144 37304960 4998576
12:35:11 23230 8192000000 10089956624 10530287616 1111616704 11.948 99 48 96 1 1165448528 43272656 10559168
12:36:36 49065 8192000000 10073815760 10510176256 1073694944 11.587 95 27 54 0 1144935024 65711008 5529072
12:38:26 51533 8192000000 10113495296 10570444800 1086202848 11.707 97 27 54 0 1164839344 72954080 5682416
12:40:25 98788 8192000000 10032136912 10501808128 1086622512 11.711 98 21 42 0 1128397568 37292048 4483008
12:43:10 31686 8192000000 10069626064 10510438400 1110250656 11.935 99 25 44 1 1169618992 55476448 3891888
12:44:43 63436 8192000000 10097310288 10561413120 1113080016 11.962 99 7 14 1 1184520048 70095216 1344816
12:46:54 63223 8192000000 10041537040 10544615424 1098866320 11.827 98 18 35 0 1141665200 39465696 3333184
12:48:57 72930 8192000000 10091791600 10531602432 1073479760 11.585 95 14 28 2 1117099376 40996320 2623296
12:51:11 41917 8192000000 10130371968 10606718976 1134981952 12.168 100 24 48 2 1250638560 110520032 5136576
12:52:53 71599 8192000000 10108640416 10550562816 1131722656 12.138 100 14 28 0 1206053872 71324912 3006304
12:55:05 37466 8192000000 10168878736 10652790784 1112582304 11.957 98 24 48 2 1229532704 111691760 5258640
12:56:45 86761 8192000000 10087437248 10550407168 1107561472 11.909 98 17 34 0 1156523648 45586224 3375952
12:59:14 92183 8192000000 10093322528 10585415680 1130537984 12.126 100 43 86 2 1247210576 107966064 8706528
13:01:56 30991 8192000000 10062070384 10506072064 1130643136 12.127 100 24 47 1 1152016000 16225120 5147744
13:03:29 51595 8192000000 10093799248 10561085440 1127819728 12.101 99 20 39 0 1200809152 68464896 4524528
13:05:21 76390 8192000000 10144473776 10603716608 1144200912 12.255 100 15 30 0 1230441056 83021920 3218224
13:07:44 45100 8192000000 10138449024 10630705152 1149820800 12.308 100 23 47 0 1270639808 115455728 5363280
13:09:36 39555 8192000000 10133442912 10619170816 1140630224 12.221 100 24 48 1 1226961136 80509392 5821520
13:11:46 36244 8192000000 10077060720 10560815104 1130896032 12.130 99 17 34 1 1198430464 63987008 3547424
13:13:28 42272 8192000000 10082373904 10544009216 1154801248 12.355 100 15 29 1 1186284032 28752416 2730368
13:15:11 43979 8192000000 10133728768 10597085184 1147386432 12.285 100 26 52 0 1229918768 77443456 5088880
13:17:21 45887 8192000000 10102463152 10608807936 1141503328 12.230 99 26 52 1 1212608992 65798752 5306912
13:19:12 63795 8192000000 10083767424 10542460928 1070792304 11.560 93 26 49 1 1114609968 38407664 5410000
13:21:16 29229 8192000000 10123980400 10557624320 1098413088 11.823 97 40 80 2 1171635040 65198912 8023040
13:22:48 108020 8192000000 10097509088 10547585024 1125439072 12.078 99 30 60 1 1221513968 89170752 6904144
13:25:44 53162 8192000000 10057838560 10518069248 1123770720 12.063 99 9 19 0 1147819328 22312480 1736128
13:27:39 70178 8192000000 10055048016 10545094656 1124231536 12.067 99 13 26 0 1165743440 38682960 2828944
13:29:55 62947 8192000000 10065212032 10554777600 1117490128 12.003 99 13 26 0 1159581232 39326496 2764608
13:31:59 62285 8192000000 10101332608 10562289664 1139406144 12.210 100 21 41 1 1197250560 53484432 4359984
13:34:09 46284 8192000000 10162282800 10613149696 1138682800 12.203 99 22 44 0 1233324112 89697424 4943888
13:36:17 61988 8192000000 9324828768 9692987392 426938192 4.953 39 4 8 0 482886416 55086976 861248

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-08-2021 13:38:34 to 29-08-2021 13:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-08-2021 13:38:50 to 29-08-2021 13:38:52.

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          241256
MON$OLDEST_ACTIVE               241257
MON$OLDEST_SNAPSHOT             241257
MON$NEXT_TRANSACTION            241257
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                       2063264
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             814
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2574 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2574 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2574 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2574 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 ---
:96: 
:97: mscs-test-161.budzdorov.ru    Sun Aug 29 13:37:24 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Sun Aug 29 13:37:26 2021
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
--- end of diff output ---

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

29.08.21 13: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
2021-08-29 13:38:56 No severe PSQL-related problems occured
29.08.21 13:38:57. End of report.