Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 04: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 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 15877756 kB
MemAvailable 31467264 kB
Buffers 20 kB
Cached 15733368 kB
SwapCached 2516 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-24 04:37:15
DTS_END                         2022-04-24 04:37:15.9530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43334
TRN_ID                          220257
ATT_ID                          628
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: 8561.20 25855 1027344
customer order: creation 171.17 505 20540
customer order: refuse 14.68 466 1762
order to supplier: creation 44.78 1361 5374
order to supplier: removal 3.16 185224 379
invoice (draft): creation 45.38 3410 5445
invoice (draft): removal 46.28 1169 5553
invoice accept: apply 40.97 28295 4916
invoice accept: cancel 41.57 1418 4988
customer reserve: creation 3780.42 270 453650
customer reserve: removal 4077.50 112 489300
realization accept: apply 82.28 284 9874
realization accept: cancel 83.17 191 9980
payment from customer: creation 59.45 189 7134
payment from customer: removal 5.81 123 697
payment to supplier: creation 48.88 204 5866
payment to supplier: removal 3.39 120 407
service: total inventory turnovers 3.83 4466 459
service: total monetary turnovers 7.65 419 918
service: aggregate perf. data 0.82 17165 98
service: refresh index statistics 0.03 271706 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-04-2022 04:38:12 to 24-04-2022 04:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6116
TEST_TIME 2 5338
TEST_TIME 3 5175
TEST_TIME 4 5360
TEST_TIME 5 5022
TEST_TIME 6 4925
TEST_TIME 7 4703
TEST_TIME 8 4775
TEST_TIME 9 5041
TEST_TIME 10 5344
TEST_TIME 11 5536
TEST_TIME 12 5628
TEST_TIME 13 5729
TEST_TIME 14 5947
TEST_TIME 15 6277
TEST_TIME 16 6374
TEST_TIME 17 6805
TEST_TIME 18 6819
TEST_TIME 19 7016
TEST_TIME 20 7004
TEST_TIME 21 7078
TEST_TIME 22 7353
TEST_TIME 23 7724
TEST_TIME 24 7649
TEST_TIME 25 7883
TEST_TIME 26 8396
TEST_TIME 27 8743
TEST_TIME 28 8747
TEST_TIME 29 9234
TEST_TIME 30 9548
TEST_TIME 31 9872
TEST_TIME 32 9927
TEST_TIME 33 9969
TEST_TIME 34 10011
TEST_TIME 35 10083
TEST_TIME 36 10145
TEST_TIME 37 10239
TEST_TIME 38 10232
TEST_TIME 39 10007
TEST_TIME 40 9984
TEST_TIME 41 9886
TEST_TIME 42 9779
TEST_TIME 43 9685
TEST_TIME 44 9516
TEST_TIME 45 9548
TEST_TIME 46 9964
TEST_TIME 47 9912
TEST_TIME 48 9755
TEST_TIME 49 9720
TEST_TIME 50 9577
TEST_TIME 51 9479
TEST_TIME 52 9443
TEST_TIME 53 9481
TEST_TIME 54 9419
TEST_TIME 55 9386
TEST_TIME 56 9297
TEST_TIME 57 9207
TEST_TIME 58 9152
TEST_TIME 59 9165
TEST_TIME 60 9054
TEST_TIME 61 8981
TEST_TIME 62 8933
TEST_TIME 63 8939
TEST_TIME 64 8869
TEST_TIME 65 8779
TEST_TIME 66 8739
TEST_TIME 67 8843
TEST_TIME 68 8813
TEST_TIME 69 8777
TEST_TIME 70 8762
TEST_TIME 71 8757
TEST_TIME 72 8861
TEST_TIME 73 8865
TEST_TIME 74 8891
TEST_TIME 75 8940
TEST_TIME 76 8914
TEST_TIME 77 8889
TEST_TIME 78 8988
TEST_TIME 79 8964
TEST_TIME 80 8989
TEST_TIME 81 8885
TEST_TIME 82 8861
TEST_TIME 83 8800
TEST_TIME 84 8782
TEST_TIME 85 8786
TEST_TIME 86 8767
TEST_TIME 87 8712
TEST_TIME 88 8669
TEST_TIME 89 8656
TEST_TIME 90 8628
TEST_TIME 91 8656
TEST_TIME 92 8653
TEST_TIME 93 8682
TEST_TIME 94 8654
TEST_TIME 95 8734
TEST_TIME 96 8726
TEST_TIME 97 8791
TEST_TIME 98 8754
TEST_TIME 99 8764
TEST_TIME 100 8757
TEST_TIME 101 8871
TEST_TIME 102 8835
TEST_TIME 103 8848
TEST_TIME 104 8833
TEST_TIME 105 8779
TEST_TIME 106 8724
TEST_TIME 107 8727
TEST_TIME 108 8740
TEST_TIME 109 8710
TEST_TIME 110 8677
TEST_TIME 111 8642
TEST_TIME 112 8609
TEST_TIME 113 8611
TEST_TIME 114 8588
TEST_TIME 115 8593
TEST_TIME 116 8629
TEST_TIME 117 8594
TEST_TIME 118 8652
TEST_TIME 119 8655
TEST_TIME 120 8588

Performance per minute, chart


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

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 1444234 1444234 0 0.00 0 23806 15 0 0
doc_list_biud 1444223 1444223 0 0.00 0 79415 45 0 0
sp_add_invoice_to_stock 5071 4916 155 3.05 72 212018 28295 0 155
sp_cancel_adding_invoice 4988 4988 0 0.00 7 70534 1419 0 0
sp_cancel_client_order 1762 1762 0 0.00 6 8709 466 0 0
sp_cancel_customer_reserve 489300 489300 0 0.00 1 23929 113 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 4 3545 124 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 1131 121 0 0
sp_cancel_supplier_invoice 5570 5553 17 0.30 4 79429 1170 0 17
sp_cancel_supplier_order 379 379 0 0.00 7 1415015 185225 0 0
sp_cancel_write_off 9980 9980 0 0.00 2 21611 192 0 0
sp_client_order 20540 20540 0 0.00 6 25846 506 0 0
sp_customer_reserve 453650 453650 0 0.00 1 73960 270 0 0
sp_fill_shopping_cart 31343 31343 0 0.00 1 18533 94 0 0
sp_fill_shopping_cart_clo_res 453612 453612 0 0.00 0 26218 21 0 0
sp_get_clo_for_invoice 4865 4865 0 0.00 0 46529 295 0 0
sp_kill_cost_storno 16072 16072 0 0.00 0 12308 83 0 0
sp_kill_qstorno_ret_qs2qd 994512 994512 0 0.00 0 79412 50 0 0
sp_kill_qty_storno 526697 526697 0 0.00 0 79415 114 0 0
sp_lock_selected_doc 20536 20536 0 0.00 0 6406 6 0 0
sp_make_cost_storno 27737 27737 0 0.00 0 12389 78 0 0
sp_multiply_rows_for_pdistr 27736 27736 0 0.00 0 19370 40 0 0
sp_multiply_rows_for_qdistr 20539 20539 0 0.00 0 14366 190 0 0
sp_pay_from_customer 7134 7134 0 0.00 5 23811 189 0 0
sp_pay_to_supplier 5866 5866 0 0.00 7 21312 204 0 0
sp_payment_common 13000 13000 0 0.00 2 21289 49 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 8701 387 0 0
sp_qd_handle_on_invoice_upd_sts 9850 9850 0 0.00 0 31895 604 0 0
sp_qd_handle_on_reserve_upd_sts 9873 9873 0 0.00 0 23195 69 0 0
sp_reserve_write_off 9874 9874 0 0.00 8 23267 284 0 0
sp_supplier_invoice 5445 5445 0 0.00 9 81184 3411 0 0
sp_supplier_order 5374 5374 0 0.00 7 56668 1361 0 0
srv_aggregate_perf_data 98 98 0 0.00 2777 177860 17165 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 732 203182 34274 0 0
srv_find_qd_qs_mism 978429 978429 0 0.00 0 20633 11 0 0
srv_make_invnt_saldo 459 459 0 0.00 55 75749 4466 0 0
srv_make_money_saldo 918 918 0 0.00 1 16026 419 0 0
srv_recalc_idx_stat 4 4 0 0.00 77097 551211 271707 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 1 12296 1035 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 9936 503 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 1584 28 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 817 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 643 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 303 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2502 35 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 133281 7806 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 10165 35 0 0
t$perf-norm:sp_client_order 20540 20540 0 0.00 0 7409 38 0 0
t$perf-norm:sp_customer_reserve 1061 1061 0 0.00 0 1309 17 0 0
t$perf-norm:sp_pay_from_customer 7134 7134 0 0.00 0 7283 20 0 0
t$perf-norm:sp_pay_to_supplier 5866 5866 0 0.00 0 11047 24 0 0
t$perf-norm:sp_reserve_write_off 9874 9874 0 0.00 0 7227 25 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 4328 30 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 7205 33 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 297 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 622 12 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 583 12 0 0
t$perf-norm:srv_make_money_saldo 918 918 0 0.00 0 179 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 176 83 0 0
v_add_invoice_to_stock 10026 9716 310 3.09 0 7280 26 0 310
v_all_customers 37806 37806 0 0.00 0 70 4 0 0
v_all_suppliers 30048 30048 0 0.00 0 86 4 0 0
v_all_wares 41067 41067 0 0.00 0 61 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 1676 16 0 0
v_cancel_client_order 3524 3524 0 0.00 0 2661 10 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3538 15 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 4027 17 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1029 15 0 0
v_cancel_supplier_invoice 860 826 34 3.95 0 3937 40 0 34
v_cancel_supplier_order 758 758 0 0.00 0 693 14 0 0
v_cancel_write_off 2060 2060 0 0.00 0 4055 27 0 0
v_max_id_clo_ord 5371 5371 0 0.00 0 337 5 0 0
v_max_id_clo_res 1061 1061 0 0.00 0 1211 13 0 0
v_max_id_ord_sup 5436 5436 0 0.00 0 5113 8 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 55 8 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 5748 9 0 0
v_min_id_clo_ord 5371 5371 0 0.00 0 6378 9 0 0
v_min_id_clo_res 1061 1061 0 0.00 0 4464 14 0 0
v_min_id_ord_sup 5436 5436 0 0.00 0 4881 9 0 0
v_min_non_paid_invoice 5866 5866 0 0.00 0 21281 19 0 0
v_min_non_paid_realizn 7134 7134 0 0.00 0 7166 17 0 0
v_our_firm 10206 10206 0 0.00 0 63 5 0 0
v_reserve_write_off 19744 19744 0 0.00 0 4547 12 0 0
x_lock_depdocs_on_canc_invoice 10128 10128 0 0.00 0 33595 141 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 5107 364 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 2 56643 1168 0 0
x_make_qstorno_1200_2000 5445 5445 0 0.00 4 80924 3157 0 0
x_make_qstorno_2100_3300 453648 453648 0 0.00 0 73959 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 04:38:20 to 24-04-2022 04:38:24.

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 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:20 31650 8192000000 9724209280 10021896192 798788160 8.884 100 86 172 1 827605392 7784528 21032704
02:10:00 23140 8192000000 9773713248 10084065280 843564752 9.336 100 86 172 0 877984768 13925360 20494656
02:11:24 23642 8192000000 9808994240 10128588800 873924048 9.639 100 87 173 2 916594928 22665776 20005104
02:12:53 12008 8192000000 9876965328 10212286464 912974432 10.027 100 80 157 0 990613824 59689936 17949456
02:14:09 7355 8192000000 9901818208 10247016448 953181424 10.422 100 79 154 1 1009707248 39745456 16780368
02:15:22 2559 8192000000 9967666464 10319847424 985634192 10.739 100 93 182 1 1088906496 84344656 18927648
02:16:28 1128 8192000000 9999214592 10359779328 1018435200 11.057 100 91 171 0 1112992320 76877808 17679312
02:17:31 763 8192000000 10021559216 10393419776 1039224848 11.257 100 99 185 4 1140371392 82357392 18789152
02:18:33 1096 8192000000 10036382160 10407559168 1056883696 11.427 100 97 190 2 1157016064 81249216 18883152
02:19:36 1215 8192000000 10057999216 10439426048 1072511120 11.576 100 96 185 1 1182578496 90719312 19348064
02:20:39 1181 8192000000 10058086240 10440052736 1080344496 11.651 100 95 182 1 1178570096 79472464 18753136
02:21:41 1212 8192000000 10049105168 10427379712 1081875552 11.665 100 92 171 0 1165946608 68156944 15914112
02:22:44 990 8192000000 10063143808 10451730432 1091152688 11.754 100 86 170 2 1182403200 74305600 16944912
02:23:46 994 8192000000 10054483616 10440445952 1093165808 11.773 100 90 174 2 1173566240 61321024 19079408
02:24:47 921 8192000000 10068334720 10458718208 1096456576 11.804 100 96 174 3 1191270416 76160656 18653184
02:25:49 1230 8192000000 10071712288 10459795456 1100296736 11.840 100 91 177 1 1191561584 73468208 17796640
02:26:50 7780 8192000000 10046888464 10443935744 1103296000 11.869 100 57 112 1 1156278384 41808256 11174128
02:27:59 3605 8192000000 10085435472 10481909760 1105878144 11.893 100 81 154 0 1204670944 82798320 15994480
02:29:03 1901 8192000000 10052416560 10443964416 1099040928 11.829 100 66 124 2 1168389312 56311072 13037312
02:30:06 941 8192000000 10063393120 10454552576 1095137984 11.791 100 89 172 0 1185975920 72686448 18151488
02:31:08 1191 8192000000 10053009728 10434260992 1083328368 11.679 100 94 184 0 1176686192 74766608 18591216
02:32:11 1336 8192000000 10008549984 10378387456 1051075072 11.371 100 98 190 3 1131092416 60971808 19045536
02:33:13 1185 8192000000 10008058240 10381864960 1047808720 11.340 100 96 175 1 1130963440 64584208 18570512
02:34:15 1032 8192000000 10025082784 10399252480 1046665392 11.329 100 95 184 1 1146599424 80017248 19916784
02:35:18 1258 8192000000 9981967072 10346270720 1013002592 11.004 100 98 190 1 1104612272 72498976 19110704
02:36:20 1072 8192000000 10008635088 10383388672 1037119472 11.237 100 93 179 1 1129474672 74514560 17840640
02:37:24 19222 8192000000 10010778288 10388963328 1060332400 11.460 100 68 131 1 1141940720 69108112 12500208
02:38:55 1548 8192000000 10036367968 10415947776 1072601376 11.577 100 80 152 0 1158273888 70904576 14767936
02:40:04 14761 8192000000 10065940688 10459156480 1089285984 11.736 100 66 130 2 1187410240 84596608 13527648
02:41:28 3034 8192000000 10099963520 10478292992 1100225328 11.840 100 80 149 2 1208458672 94315552 13917792
02:42:35 11990 8192000000 10130126608 10523914240 1116534240 11.994 100 59 108 2 1243412448 114743040 12135168
02:43:55 3368 8192000000 10183346384 10579562496 1133140848 12.151 100 72 135 2 1307893424 159824496 14928080
02:45:04 3309 8192000000 10250197968 10646061056 1139243008 12.208 100 78 153 1 1364496240 208414288 16838944
02:46:14 11207 8192000000 10267209424 10663383040 1143169104 12.245 100 81 160 0 1374930144 213867168 17893872
02:47:32 3845 8192000000 10260886096 10665861120 1159028304 12.394 100 77 143 2 1375514944 200524016 15962624
02:48:39 1501 8192000000 10315926176 10722910208 1172167696 12.517 100 92 181 4 1432447920 238574976 21705248
02:49:43 2730 8192000000 10354721120 10760925184 1174602096 12.540 100 82 157 0 1462469248 269469488 18397664
02:50:49 1280 8192000000 10402258224 10807918592 1182069232 12.609 100 94 174 0 1517712560 313459136 22184192
02:51:57 2889 8192000000 10410958848 10856529920 1187259072 12.658 100 79 156 0 1527552976 321554064 18739840
02:53:04 727 8192000000 10424304368 10845614080 1188021776 12.665 100 96 187 2 1532350624 322786848 21542000
02:54:08 1180 8192000000 10439393232 10856579072 1185126064 12.638 100 88 177 0 1544581936 338093424 21362448
02:55:14 833 8192000000 10445232304 10863329280 1184077168 12.628 100 99 199 0 1548885072 341319648 23488256
02:56:19 911 8192000000 10487320736 10895544320 1183536144 12.623 100 93 182 0 1592565424 387612688 21416592
02:57:22 1334 8192000000 10527431488 10933534720 1188944512 12.674 100 95 186 1 1628217248 417559040 21713696
02:58:29 1195 8192000000 10537298528 10967777280 1189949872 12.683 100 85 170 0 1644201760 433497648 20754240
02:59:34 9178 8192000000 10490992768 10975121408 1188519680 12.670 100 59 118 0 1601572896 397581408 15471808
03:00:47 10368 8192000000 10478340880 10942394368 1187957968 12.664 100 50 100 0 1613074656 412515680 12601008
03:02:07 936 8192000000 10528240752 10970718208 1183861616 12.626 100 97 189 1 1635512352 429636480 22014256
03:03:10 23667 8192000000 10455097200 10929192960 1168940064 12.487 99 32 61 3 1560523744 383376224 8207456
03:04:51 27249 8192000000 10375245456 10910584832 1162300272 12.425 100 22 44 0 1499215856 331178704 5736880
03:06:29 13477 8192000000 10262923600 10755211264 1141873040 12.233 99 38 72 1 1377907424 228193664 7840720
03:07:49 10177 8192000000 10176694624 10657067008 1149074096 12.301 100 45 88 1 1285553072 126848480 9630496
03:09:02 10396 8192000000 10147167568 10565578752 1139427200 12.210 99 51 88 3 1251505312 103052512 9025600
03:10:14 6350 8192000000 10197372368 10630033408 1156899504 12.374 100 51 102 0 1302093216 134163504 11030208
03:11:23 940 8192000000 10225672048 10638614528 1152556544 12.333 99 89 178 2 1319070912 147109168 19405200
03:12:25 941 8192000000 10218932480 10639847424 1155019264 12.357 100 97 185 0 1313560512 137743552 20797696
03:13:28 969 8192000000 10222896176 10680205312 1176095728 12.554 100 95 188 3 1321714976 125046352 20572896
03:14:30 2027 8192000000 10244792912 10694729728 1175407920 12.547 100 78 153 1 1346465984 154017440 17040624
03:15:33 54984 8192000000 10245268480 10690621440 1170413136 12.501 99 59 110 1 1327813424 144441856 12958432
03:17:49 77804 8192000000 10217667152 10697539584 1157211312 12.377 99 19 36 2 1321963712 159676224 5076176
03:20:25 40781 8192000000 10278028944 10719883264 1151401088 12.323 98 37 74 0 1370848656 210538272 8909296
03:22:06 42165 8192000000 10284514336 10760019968 1144506896 12.258 100 33 66 1 1419171968 266453280 8211792
03:23:50 42129 8192000000 10092208880 10625966080 1128307584 12.105 99 6 11 1 1193973888 64422016 1244288
03:25:35 37389 8192000000 10106011152 10556289024 1142232368 12.237 100 28 54 2 1208520016 59623888 6663760
03:27:24 68677 8192000000 10100525968 10553241600 1123106528 12.056 99 17 35 1 1178533408 51701920 3724960
03:29:42 85129 8192000000 10120224704 10616827904 1111958144 11.951 97 11 22 0 1203970448 89427968 2584336
03:32:08 44352 8192000000 10093572400 10540609536 1125532640 12.079 100 39 74 3 1190452992 56800288 8120064
03:34:29 68901 8192000000 10048038624 10518036480 1092336384 11.765 97 25 50 1 1142098544 43782240 5979920
03:36:41 93665 8192000000 10065086448 10542391296 1119185728 12.019 99 31 62 0 1171672192 45971040 6515424
03:39:17 13210 8192000000 10075642384 10506170368 1111749696 11.949 99 60 113 1 1172850448 49602608 11498144
03:40:34 53483 8192000000 10034663952 10511450112 1081018432 11.657 96 11 22 1 1138480112 55397552 2064128
03:43:29 96509 8192000000 10061582480 10566348800 1117397904 12.002 99 12 25 0 1196213264 75958880 2856480
03:46:16 20244 8192000000 10099406640 10541322240 1127854144 12.101 99 33 66 1 1180276048 45955408 6466496
03:47:41 70760 8192000000 10152761520 10618130432 1107378224 11.908 97 24 48 1 1221118464 108661856 5078384
03:49:54 41802 8192000000 10073447664 10504613888 1099749840 11.835 97 39 78 0 1151537808 42972624 8815344
03:51:36 25017 8192000000 10127808256 10585362432 1130491072 12.126 100 44 87 1 1218893840 79639440 8763328
03:53:04 45141 8192000000 10082176896 10525880320 1125976528 12.083 99 30 60 0 1184778944 52571008 6231408
03:54:49 70545 8192000000 10093750096 10581901312 1136369968 12.181 100 38 75 2 1211536288 67635312 7531008
03:57:01 61704 8192000000 10086412800 10561585152 1134699040 12.166 100 35 70 0 1208415456 66405792 7310624
04:00:22 55202 8192000000 10099303504 10538127360 1122124928 12.047 99 45 90 0 1192720832 61191104 9404800
04:02:17 58927 8192000000 10053331440 10532495360 1113260336 11.963 98 9 18 0 1170054368 54799520 1994512
04:04:18 36447 8192000000 10070130256 10509357056 1114110064 11.971 98 32 63 3 1168520624 46223872 8186688
04:05:57 56288 8192000000 10087951856 10563411968 1105376064 11.889 97 17 34 0 1189169984 80020192 3773728
04:08:04 76247 8192000000 10145930416 10613047296 1129814528 12.120 99 18 37 0 1244902512 110992272 4095712
04:10:21 51805 8192000000 10117399792 10579804160 1118583840 12.014 98 25 50 0 1212417792 88230784 5603168
04:12:20 48825 8192000000 10083184336 10521632768 1119192112 12.019 98 26 52 0 1178995072 54440576 5362384
04:14:09 75725 8192000000 10086825312 10552565760 1104912864 11.884 98 19 38 0 1163958784 55508448 3537472
04:16:26 8534 8192000000 10121698160 10549317632 1131456768 12.135 100 50 98 3 1205784576 63140368 11187440
04:17:37 65857 8192000000 10080180496 10562129920 1126300992 12.086 99 20 38 1 1208972608 78539088 4132528
04:19:50 11858 8192000000 10101760768 10522505216 1134556144 12.164 99 63 122 0 1183836544 36044896 13235504
04:21:03 203180 8192000000 10112727760 10554245120 1081117648 11.658 94 30 60 1 1177415120 90351600 5945872
04:25:27 49171 8192000000 10048681936 10531618816 1112903440 11.960 99 13 26 2 1154191232 38747440 2540352
04:27:19 68874 8192000000 10040805024 10498121728 1110129440 11.934 99 12 25 1 1132309504 18929456 3250608
04:30:28 63979 8192000000 10094120544 10540281856 1131344944 12.134 100 23 46 0 1197234144 60988496 4900704
04:32:33 46488 8192000000 10118440208 10575970304 1112028992 11.952 98 40 80 2 1207063776 85773472 9261312
04:34:22 32953 8192000000 10125358048 10562461696 1107743280 11.911 98 42 82 0 1195095376 78878912 8473184
04:35:56 64915 8192000000 10101795056 10557407232 1132204960 12.142 99 25 50 1 1185153408 47647856 5300592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-04-2022 04:38:26 to 24-04-2022 04:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 155 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-04-2022 04:38:43 to 24-04-2022 04:38:45.

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          217466
MON$OLDEST_ACTIVE               220420
MON$OLDEST_SNAPSHOT             220420
MON$NEXT_TRANSACTION            220420
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                       2111424
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             774
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:565: 
:566: mscs-test-161.budzdorov.ru    Sun Apr 24 04:37:21 2022
:567:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:568: 
:569: 
:570: mscs-test-161.budzdorov.ru    Sun Apr 24 04:37:21 2022
:571:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:572: 
--- end of diff output ---

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

24.04.22 04:38:46. 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-24 04:38:49 No severe PSQL-related problems occured
24.04.22 04:38:50. End of report.