Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2023 13: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 15303032 kB
MemAvailable 30383500 kB
Buffers 0 kB
Cached 16375252 kB
SwapCached 3376 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.3
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 2023-02-13 13:37:25
DTS_END                         2023-02-13 13:37:25.3300
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/37144
TRN_ID                          222777
ATT_ID                          663
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: 8642.04 28534 1037045
customer order: creation 164.41 437 19729
customer order: refuse 14.03 436 1683
order to supplier: creation 45.00 1267 5400
order to supplier: removal 3.24 161639 389
invoice (draft): creation 44.69 3089 5363
invoice (draft): removal 47.29 1026 5675
invoice accept: apply 40.94 28715 4913
invoice accept: cancel 42.31 1148 5077
customer reserve: creation 3821.90 276 458628
customer reserve: removal 4139.61 98 496753
realization accept: apply 81.63 252 9795
realization accept: cancel 72.83 174 8739
payment from customer: creation 56.81 158 6817
payment from customer: removal 5.36 103 643
payment to supplier: creation 46.51 174 5581
payment to supplier: removal 3.13 121 375
service: total inventory turnovers 4.13 3600 496
service: total monetary turnovers 7.26 380 871
service: aggregate perf. data 0.95 13851 114
service: refresh index statistics 0.03 353733 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-02-2023 13:38:24 to 13-02-2023 13:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5727
TEST_TIME 2 4788
TEST_TIME 3 4570
TEST_TIME 4 5143
TEST_TIME 5 5578
TEST_TIME 6 5803
TEST_TIME 7 5559
TEST_TIME 8 5874
TEST_TIME 9 6157
TEST_TIME 10 6399
TEST_TIME 11 6624
TEST_TIME 12 7039
TEST_TIME 13 7284
TEST_TIME 14 7312
TEST_TIME 15 7249
TEST_TIME 16 7265
TEST_TIME 17 7315
TEST_TIME 18 7480
TEST_TIME 19 7452
TEST_TIME 20 7495
TEST_TIME 21 7708
TEST_TIME 22 7791
TEST_TIME 23 8128
TEST_TIME 24 8420
TEST_TIME 25 8567
TEST_TIME 26 8891
TEST_TIME 27 9179
TEST_TIME 28 10013
TEST_TIME 29 10408
TEST_TIME 30 10851
TEST_TIME 31 10908
TEST_TIME 32 10969
TEST_TIME 33 10812
TEST_TIME 34 10587
TEST_TIME 35 10488
TEST_TIME 36 10448
TEST_TIME 37 10237
TEST_TIME 38 10292
TEST_TIME 39 10241
TEST_TIME 40 10102
TEST_TIME 41 10001
TEST_TIME 42 9962
TEST_TIME 43 9943
TEST_TIME 44 9958
TEST_TIME 45 9744
TEST_TIME 46 9802
TEST_TIME 47 9720
TEST_TIME 48 9603
TEST_TIME 49 9587
TEST_TIME 50 9596
TEST_TIME 51 9602
TEST_TIME 52 9575
TEST_TIME 53 9601
TEST_TIME 54 9567
TEST_TIME 55 9572
TEST_TIME 56 9576
TEST_TIME 57 9497
TEST_TIME 58 9474
TEST_TIME 59 9444
TEST_TIME 60 9450
TEST_TIME 61 9454
TEST_TIME 62 9473
TEST_TIME 63 9368
TEST_TIME 64 9290
TEST_TIME 65 9203
TEST_TIME 66 9160
TEST_TIME 67 9163
TEST_TIME 68 9149
TEST_TIME 69 9054
TEST_TIME 70 9021
TEST_TIME 71 9014
TEST_TIME 72 8998
TEST_TIME 73 8957
TEST_TIME 74 9042
TEST_TIME 75 9020
TEST_TIME 76 8997
TEST_TIME 77 9028
TEST_TIME 78 9046
TEST_TIME 79 9088
TEST_TIME 80 9039
TEST_TIME 81 9082
TEST_TIME 82 9195
TEST_TIME 83 9131
TEST_TIME 84 9095
TEST_TIME 85 9096
TEST_TIME 86 9013
TEST_TIME 87 8981
TEST_TIME 88 8934
TEST_TIME 89 8889
TEST_TIME 90 8832
TEST_TIME 91 8810
TEST_TIME 92 8821
TEST_TIME 93 8830
TEST_TIME 94 8807
TEST_TIME 95 8784
TEST_TIME 96 8768
TEST_TIME 97 8821
TEST_TIME 98 8825
TEST_TIME 99 8830
TEST_TIME 100 8886
TEST_TIME 101 8847
TEST_TIME 102 8866
TEST_TIME 103 8897
TEST_TIME 104 8931
TEST_TIME 105 8972
TEST_TIME 106 8901
TEST_TIME 107 8877
TEST_TIME 108 8826
TEST_TIME 109 8817
TEST_TIME 110 8761
TEST_TIME 111 8712
TEST_TIME 112 8698
TEST_TIME 113 8674
TEST_TIME 114 8649
TEST_TIME 115 8649
TEST_TIME 116 8701
TEST_TIME 117 8686
TEST_TIME 118 8670
TEST_TIME 119 8708
TEST_TIME 120 8659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-02-2023 13:38:27 to 13-02-2023 13:38:31.

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 1456483 1456483 0 0.00 0 37055 15 0 0
doc_list_biud 1456470 1456470 0 0.00 0 37348 39 0 0
sp_add_invoice_to_stock 5044 4913 131 2.59 54 248400 28715 0 131
sp_cancel_adding_invoice 5077 5077 0 0.00 6 102378 1148 0 0
sp_cancel_client_order 1683 1683 0 0.00 5 13138 436 0 0
sp_cancel_customer_reserve 496753 496753 0 0.00 1 37390 98 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 3 2932 103 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 4468 122 0 0
sp_cancel_supplier_invoice 5685 5675 10 0.17 3 27953 1027 0 10
sp_cancel_supplier_order 389 389 0 0.00 16 1356780 161640 0 0
sp_cancel_write_off 8739 8739 0 0.00 2 20999 174 0 0
sp_client_order 19729 19729 0 0.00 5 13747 437 0 0
sp_customer_reserve 458628 458628 0 0.00 1 55203 276 0 0
sp_fill_shopping_cart 30482 30482 0 0.00 1 22710 82 0 0
sp_fill_shopping_cart_clo_res 458593 458593 0 0.00 0 12995 19 0 0
sp_get_clo_for_invoice 4875 4875 0 0.00 0 36957 343 0 0
sp_kill_cost_storno 14834 14834 0 0.00 0 6019 73 0 0
sp_kill_qstorno_ret_qs2qd 1008309 1008309 0 0.00 0 37289 43 0 0
sp_kill_qty_storno 532985 532985 0 0.00 0 37334 99 0 0
sp_lock_selected_doc 20225 20225 0 0.00 0 9873 6 0 0
sp_make_cost_storno 27068 27068 0 0.00 0 17772 71 0 0
sp_multiply_rows_for_pdistr 27068 27068 0 0.00 0 9396 35 0 0
sp_multiply_rows_for_qdistr 19729 19729 0 0.00 0 12498 169 0 0
sp_pay_from_customer 6817 6817 0 0.00 5 11087 159 0 0
sp_pay_to_supplier 5581 5581 0 0.00 6 7858 174 0 0
sp_payment_common 12398 12398 0 0.00 2 7821 43 0 0
sp_qd_handle_on_cancel_clo 1683 1683 0 0.00 0 13117 368 0 0
sp_qd_handle_on_invoice_upd_sts 9952 9952 0 0.00 0 28065 499 0 0
sp_qd_handle_on_reserve_upd_sts 9794 9794 0 0.00 0 9069 58 0 0
sp_reserve_write_off 9795 9795 0 0.00 9 16101 253 0 0
sp_supplier_invoice 5363 5363 0 0.00 7 69628 3089 0 0
sp_supplier_order 5400 5400 0 0.00 7 37792 1267 0 0
srv_aggregate_perf_data 114 114 0 0.00 2410 112498 13852 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 730 125260 35886 0 0
srv_find_qd_qs_mism 988034 988034 0 0.00 0 37051 13 0 0
srv_make_invnt_saldo 496 496 0 0.00 68 89114 3601 0 0
srv_make_money_saldo 871 871 0 0.00 1 33619 380 0 0
srv_recalc_idx_stat 4 4 0 0.00 175799 467666 353733 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4914 4914 0 0.00 1 27803 852 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 6654 399 0 0
t$perf-norm:sp_cancel_client_order 1683 1683 0 0.00 0 2177 21 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 1158 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 312 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 1196 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 424 424 0 0.00 0 1060 27 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 88035 6258 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 2399 18 0 0
t$perf-norm:sp_client_order 19730 19730 0 0.00 0 26191 33 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 538 12 0 0
t$perf-norm:sp_pay_from_customer 6817 6817 0 0.00 0 26897 22 0 0
t$perf-norm:sp_pay_to_supplier 5581 5581 0 0.00 0 13745 19 0 0
t$perf-norm:sp_reserve_write_off 9795 9795 0 0.00 0 8876 22 0 0
t$perf-norm:sp_supplier_invoice 5363 5363 0 0.00 0 3299 27 0 0
t$perf-norm:sp_supplier_order 5400 5400 0 0.00 0 6762 28 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 688 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 1311 36 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 3530 24 0 0
t$perf-norm:srv_make_money_saldo 871 871 0 0.00 0 460 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 192 52 0 0
v_add_invoice_to_stock 10006 9744 262 2.61 0 5208 27 0 262
v_all_customers 35718 35718 0 0.00 0 198 3 0 0
v_all_suppliers 29782 29782 0 0.00 0 199 4 0 0
v_all_wares 39454 39454 0 0.00 0 200 3 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 3054 17 0 0
v_cancel_client_order 3366 3366 0 0.00 0 1887 10 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 2911 12 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 1 3212 14 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 4386 21 0 0
v_cancel_supplier_invoice 868 848 20 2.30 0 582 23 0 20
v_cancel_supplier_order 778 778 0 0.00 0 4339 19 0 0
v_cancel_write_off 1936 1936 0 0.00 0 4404 30 0 0
v_max_id_clo_ord 5396 5396 0 0.00 0 2747 5 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 1459 10 0 0
v_max_id_ord_sup 5357 5357 0 0.00 0 4968 7 0 0
v_max_non_paid_invoice 1442 1442 0 0.00 0 218 7 0 0
v_max_non_paid_realizn 3716 3716 0 0.00 0 3142 8 0 0
v_min_id_clo_ord 5396 5396 0 0.00 0 1286 8 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 5311 20 0 0
v_min_id_ord_sup 5357 5357 0 0.00 0 4980 9 0 0
v_min_non_paid_invoice 5581 5581 0 0.00 0 7804 16 0 0
v_min_non_paid_realizn 6817 6817 0 0.00 0 6575 19 0 0
v_our_firm 9938 9938 0 0.00 0 57 4 0 0
v_reserve_write_off 19584 19584 0 0.00 0 4394 13 0 0
x_lock_depdocs_on_canc_invoice 10328 10328 0 0.00 0 23269 145 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 13252 436 0 0
x_make_qstorno_1000_1200 5400 5400 0 0.00 3 37759 1108 0 0
x_make_qstorno_1200_2000 5363 5363 0 0.00 3 69611 2849 0 0
x_make_qstorno_2100_3300 458626 458626 0 0.00 0 55189 245 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2023 13:38:32 to 13-02-2023 13:38:36.

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:29 29 8192000000 8810971872 8826974208 12378816 0.150 4 4 5 0 12628320 136960 112544
11:08:29 25752 8192000000 9717285040 10013507584 795205648 8.848 100 91 183 0 825117536 7735440 22176448
11:10:02 17759 8192000000 9761734960 10067419136 831391584 9.213 100 89 178 0 868623680 16087664 21144432
11:11:31 17005 8192000000 9801007392 10120282112 866581616 9.566 100 83 164 0 904703856 19255456 18866784
11:12:54 5957 8192000000 9884370752 10215915520 916134896 10.058 100 89 176 0 988114720 52644048 19335776
11:14:04 3550 8192000000 9934105184 10276458496 963240704 10.521 100 88 172 1 1054214336 72408048 18565584
11:15:10 1440 8192000000 9968369552 10320502784 995315408 10.833 100 92 180 0 1086448480 72922128 18210944
11:16:13 1262 8192000000 9981160112 10346713088 1023783248 11.109 100 97 181 0 1104432432 62171568 18477616
11:17:17 1072 8192000000 10022706288 10393837568 1045915744 11.321 100 95 184 0 1145076592 79862080 19298768
11:18:19 909 8192000000 10024536256 10404634624 1065845648 11.512 100 93 178 1 1141740688 56593392 19301648
11:19:21 1257 8192000000 10027012048 10412855296 1072748624 11.578 100 92 175 0 1154779168 62900176 19130368
11:20:23 1867 8192000000 10039955168 10423312384 1075591024 11.605 100 81 153 1 1161931360 71176560 15163776
11:21:25 1997 8192000000 10036407536 10425393152 1085544160 11.700 100 79 153 1 1161778704 61725440 14509104
11:22:28 1102 8192000000 10036576896 10424320000 1089299952 11.736 100 83 155 3 1156132016 52075552 14756512
11:23:30 1495 8192000000 10055303584 10447052800 1091186880 11.754 100 76 147 0 1179208064 72659952 15361232
11:24:33 1151 8192000000 10077423744 10463133696 1096121504 11.801 100 97 190 1 1198847840 82348368 20377968
11:25:35 1115 8192000000 10094636480 10483204096 1101288000 11.850 100 97 186 1 1214666864 94078656 19300208
11:26:37 1125 8192000000 10090770736 10481438720 1107333840 11.907 100 98 191 0 1211040256 83788848 19917568
11:27:39 2311 8192000000 10058982528 10448351232 1104424848 11.880 100 72 142 0 1176349744 57139936 14784960
11:28:42 1719 8192000000 10044218416 10438975488 1102931136 11.865 100 87 148 0 1164533280 48104464 13497680
11:29:46 996 8192000000 10022552464 10407452672 1069563280 11.548 100 80 144 4 1146511856 61207728 15740848
11:30:48 841 8192000000 10015974784 10397794304 1071687648 11.568 100 91 170 1 1138418352 49520128 17210576
11:31:51 918 8192000000 10009546480 10382196736 1042117200 11.285 100 92 170 2 1129360448 69951152 17292096
11:32:53 1042 8192000000 10024609456 10400362496 1054418592 11.403 100 93 175 0 1145718768 73316416 17983760
11:33:55 1152 8192000000 9997496384 10377605120 1042235136 11.286 100 84 167 3 1121979376 61832464 17911776
11:34:57 966 8192000000 9976952992 10351030272 1028992320 11.159 100 85 150 2 1096274464 53409744 13872400
11:35:59 878 8192000000 9995412288 10374725632 1052351760 11.383 100 86 161 2 1118225920 49389456 16484704
11:37:08 1153 8192000000 9961600896 10347315200 1048255104 11.344 100 61 114 3 1085845008 25315120 12274784
11:38:14 12025 8192000000 10008316784 10393780224 1067225856 11.526 100 59 108 1 1125627664 47657456 10744352
11:39:36 13886 8192000000 10036839760 10427539456 1088764112 11.731 100 75 134 3 1165523936 64037200 12722624
11:40:55 8779 8192000000 10087788368 10479689728 1096202000 11.802 100 70 129 2 1207378512 97710144 13466368
11:42:12 5413 8192000000 10128846576 10523885568 1107691712 11.911 100 73 142 0 1249076720 125193568 16191440
11:43:27 2752 8192000000 10150025168 10545115136 1121755488 12.044 100 79 156 3 1275440416 136967488 16717440
11:44:34 2731 8192000000 10201778336 10598887424 1129859824 12.120 100 82 161 0 1316639440 168574848 18204768
11:45:40 4042 8192000000 10241147984 10654851072 1144806880 12.261 100 74 148 0 1357024256 196389296 15828080
11:46:47 1797 8192000000 10266714944 10661715968 1161163280 12.414 100 94 181 1 1379841056 198068960 20608816
11:47:51 1592 8192000000 10297583600 10704281600 1172580528 12.521 100 93 181 1 1411463472 218740624 20142320
11:48:56 719 8192000000 10304962688 10714447872 1163740400 12.438 99 96 185 0 1414333568 230392736 20200432
11:49:59 959 8192000000 10333573232 10747654144 1181148944 12.601 100 93 182 1 1442771440 241787200 19835296
11:51:03 1081 8192000000 10348120208 10761048064 1186108016 12.647 100 93 185 0 1457888240 250608832 21171392
11:52:07 1413 8192000000 10383280064 10789986304 1195948400 12.739 100 89 164 0 1489786416 275260992 18577024
11:53:12 2148 8192000000 10452204048 10858762240 1206041568 12.832 100 94 181 1 1562213216 335872896 20298752
11:54:17 3197 8192000000 10489315632 10898096128 1198646432 12.764 100 91 176 1 1590614320 371647504 20320384
11:55:27 1128 8192000000 10538454000 10953150464 1204965728 12.822 100 98 186 4 1645630000 417618720 23045552
11:56:31 3141 8192000000 10552416112 10965446656 1196298400 12.742 100 95 188 0 1660809008 441767280 22743328
11:57:37 1002 8192000000 10575799072 11000270848 1193414256 12.715 99 96 188 2 1679253760 462707776 23131728
11:58:41 1069 8192000000 10621741632 11047112704 1203348960 12.807 100 97 194 0 1723145616 496569392 23227264
11:59:43 854 8192000000 10594041680 11064492032 1202499680 12.800 100 89 177 0 1696493152 471882624 22110848
12:00:46 1669 8192000000 10626183584 11087785984 1198440176 12.762 100 69 136 0 1739937824 524108224 17389424
12:01:58 16146 8192000000 10581041072 11058229248 1193204672 12.713 100 49 95 1 1689782800 484128464 12449664
12:03:21 10175 8192000000 10508374192 10989711360 1166448128 12.464 99 44 87 0 1618023616 440037696 11537792
12:04:33 21984 8192000000 10370518192 10933780480 1159947072 12.403 99 31 62 0 1530865824 362677328 8241424
12:06:11 16989 8192000000 10256534944 10747297792 1120704896 12.034 99 21 40 0 1378138896 252719808 4714192
12:07:46 26034 8192000000 10078325216 10515034112 1085640176 11.701 97 15 30 0 1166356864 77517216 3199472
12:09:14 13228 8192000000 10119759856 10545057792 1140803888 12.223 100 51 97 1 1214203040 62082816 11316336
12:10:33 75545 8192000000 10169830208 10619318272 1148713040 12.297 100 54 106 0 1251364736 90546144 12105552
12:13:32 43348 8192000000 10136515376 10597404672 1143875776 12.252 100 13 26 0 1252165072 104952720 3336576
12:15:23 57498 8192000000 10124181104 10613211136 1154944624 12.356 100 23 45 0 1260411152 100586736 4879792
12:18:22 63110 8192000000 10135294016 10580295680 1124738496 12.072 98 20 38 0 1219465520 90898768 3828256
12:20:30 47744 8192000000 10110287232 10617012224 1152209840 12.330 100 16 32 0 1238649184 82858144 3581200
12:22:19 89228 8192000000 10091246048 10572959744 1130626096 12.127 98 14 29 0 1193318080 59482368 3209616
12:27:06 59979 8192000000 10109614816 10539614208 1129365456 12.115 99 25 50 0 1194021360 59504128 5151776
12:29:07 60019 8192000000 10091873664 10561794048 1142293488 12.237 100 36 70 0 1213439088 64216960 6928640
12:31:32 45508 8192000000 10083033424 10503241728 1089050352 11.734 96 39 78 0 1157690944 60808448 7832144
12:33:20 56116 8192000000 10043114320 10473447424 1091302144 11.755 97 24 47 0 1148175712 51862608 5010960
12:35:20 44505 8192000000 10109069616 10539589632 1132076272 12.141 100 51 101 1 1173190432 31360400 9753760
12:37:06 30991 8192000000 10081672320 10517073920 1126968816 12.093 99 35 70 0 1180944992 47324432 6651744
12:38:39 75802 8192000000 10051056064 10538352640 1081035120 11.657 96 23 46 0 1125010384 38429216 5546048
12:41:21 87887 8192000000 10066674000 10504843264 1063585216 11.491 95 27 54 0 1121635424 52722640 5327568
12:43:51 76044 8192000000 10065752784 10533167104 1102608896 11.862 99 22 42 0 1179202864 72146048 4447920
12:46:27 41301 8192000000 10076349776 10519695360 1123664368 12.062 100 19 38 0 1182041472 54314368 4062736
12:48:50 48009 8192000000 10106841904 10553683968 1135666688 12.175 100 19 38 1 1206377632 66815760 3895184
12:50:38 50873 8192000000 10060151600 10504908800 1095034576 11.791 96 24 45 0 1146731344 47259920 4436848
12:52:29 21526 8192000000 10097735680 10531414016 1127747968 12.100 99 43 83 0 1186752384 50250864 8753552
12:53:58 49598 8192000000 10156771232 10628460544 1139233168 12.208 100 40 80 0 1272885280 125221456 8430656
12:55:53 34708 8192000000 10064536528 10523910144 1106172800 11.896 98 30 57 1 1172117792 60878864 5066128
12:57:31 29599 8192000000 10171938400 10596720640 1134358576 12.162 99 58 111 2 1242610800 96920528 11331696
12:59:10 80876 8192000000 10066065744 10503077888 1117468864 12.003 98 20 37 0 1184271504 62736016 4066624
13:01:31 7177 8192000000 10112354208 10527506432 1118416240 12.012 98 63 123 1 1180137872 49453008 12268624
13:02:39 84847 8192000000 10100985520 10544103424 1142403888 12.238 100 39 78 0 1213174352 62698112 8072352
13:05:16 46744 8192000000 10089833696 10539929600 1145272736 12.265 100 15 30 0 1191888240 43190432 3425072
13:08:29 69605 8192000000 10092940992 10609029120 1136279872 12.181 98 18 34 1 1219601104 79677200 3644032
13:10:52 34889 8192000000 10100565312 10571448320 1133890784 12.158 99 15 28 1 1187110752 50479200 2740768
13:12:28 85158 8192000000 10124007744 10572128256 1081118736 11.658 94 28 56 0 1146865600 59822640 5924224
13:14:54 52363 8192000000 10107729824 10592514048 1102052048 11.857 96 21 42 1 1191830672 84813584 4965040
13:16:48 63625 8192000000 10069188096 10531483648 1097271520 11.812 96 16 32 0 1156357360 55927552 3158288
13:18:52 17135 8192000000 10153726224 10582315008 1144286576 12.256 100 52 104 1 1232569920 78044864 10238480
13:20:09 66561 8192000000 10167680720 10657206272 1109479856 11.927 97 32 64 1 1204296976 88596960 6220160
13:22:16 125260 8192000000 10064805792 10512666624 1120844016 12.035 98 18 36 1 1160355968 36097520 3414432
13:25:24 13524 8192000000 10089634896 10537840640 1130554384 12.127 99 45 88 0 1181445424 43088416 7802624
13:26:41 65080 8192000000 10075246192 10539970560 1133054528 12.150 99 9 18 0 1183826464 48929472 1842464
13:29:19 63813 8192000000 10099845760 10598096896 1121435952 12.041 98 22 44 1 1208644992 82395440 4813600
13:31:24 62364 8192000000 10106075872 10548805632 1083015840 11.676 95 20 35 0 1160635584 73959008 3660736
13:33:26 53124 8192000000 10064172096 10511478784 1051259552 11.373 93 17 34 0 1075261408 20559856 3442000
13:35:20 46948 8192000000 10071108096 10534309888 1064151472 11.496 95 27 53 0 1144719936 75301664 5266800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 13-02-2023 13:38:38 to 13-02-2023 13:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 13-02-2023 13:38:55 to 13-02-2023 13:38:57.

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          219298
MON$OLDEST_ACTIVE               223041
MON$OLDEST_SNAPSHOT             223041
MON$NEXT_TRANSACTION            223041
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                       2033312
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             788
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2899 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2899 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2899 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2899 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 ---
:284: 
:285: mscs-test-161.budzdorov.ru    Mon Feb 13 13:37:28 2023
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:287: 
:288: 
:289: mscs-test-161.budzdorov.ru    Mon Feb 13 13:37:33 2023
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:291: 
:292: 
:293: mscs-test-161.budzdorov.ru    Mon Feb 13 13:37:36 2023
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:295: 
--- end of diff output ---

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

13.02.23 13:38:58. 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
2023-02-13 13:39:01 No severe PSQL-related problems occured
13.02.23 13:39:02. End of report.