Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2022 10: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 14627140 kB
MemAvailable 30080948 kB
Buffers 0 kB
Cached 17047444 kB
SwapCached 3344 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 2022-12-20 10:37:23
DTS_END                         2022-12-20 10:37:23.3160
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/38574
TRN_ID                          218061
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: 9093.28 21901 1091194
customer order: creation 174.75 467 20970
customer order: refuse 15.02 449 1802
order to supplier: creation 46.87 1438 5624
order to supplier: removal 3.27 182490 392
invoice (draft): creation 46.59 3137 5591
invoice (draft): removal 50.18 946 6021
invoice accept: apply 41.73 26619 5007
invoice accept: cancel 45.55 1221 5466
customer reserve: creation 3891.92 249 467030
customer reserve: removal 4479.43 104 537531
realization accept: apply 84.48 292 10138
realization accept: cancel 80.47 178 9656
payment from customer: creation 59.84 181 7181
payment from customer: removal 5.80 140 696
payment to supplier: creation 50.96 194 6115
payment to supplier: removal 3.63 117 435
service: total inventory turnovers 3.98 4281 477
service: total monetary turnovers 7.94 330 953
service: aggregate perf. data 0.88 19382 105
service: refresh index statistics 0.03 195801 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-12-2022 10:38:33 to 20-12-2022 10:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10562
TEST_TIME 2 8206
TEST_TIME 3 6742
TEST_TIME 4 6587
TEST_TIME 5 6614
TEST_TIME 6 7089
TEST_TIME 7 6926
TEST_TIME 8 6604
TEST_TIME 9 6710
TEST_TIME 10 6502
TEST_TIME 11 6585
TEST_TIME 12 6672
TEST_TIME 13 6704
TEST_TIME 14 6825
TEST_TIME 15 6824
TEST_TIME 16 6994
TEST_TIME 17 7375
TEST_TIME 18 7342
TEST_TIME 19 7526
TEST_TIME 20 7511
TEST_TIME 21 7571
TEST_TIME 22 7710
TEST_TIME 23 7763
TEST_TIME 24 7864
TEST_TIME 25 7766
TEST_TIME 26 8094
TEST_TIME 27 8309
TEST_TIME 28 8567
TEST_TIME 29 8916
TEST_TIME 30 9009
TEST_TIME 31 9097
TEST_TIME 32 9198
TEST_TIME 33 9319
TEST_TIME 34 9594
TEST_TIME 35 9827
TEST_TIME 36 10375
TEST_TIME 37 10766
TEST_TIME 38 10786
TEST_TIME 39 10595
TEST_TIME 40 10607
TEST_TIME 41 10644
TEST_TIME 42 10582
TEST_TIME 43 10534
TEST_TIME 44 10536
TEST_TIME 45 10443
TEST_TIME 46 10571
TEST_TIME 47 10424
TEST_TIME 48 10226
TEST_TIME 49 10170
TEST_TIME 50 10028
TEST_TIME 51 9985
TEST_TIME 52 9841
TEST_TIME 53 9790
TEST_TIME 54 9772
TEST_TIME 55 9691
TEST_TIME 56 9754
TEST_TIME 57 9828
TEST_TIME 58 9764
TEST_TIME 59 9755
TEST_TIME 60 9752
TEST_TIME 61 9711
TEST_TIME 62 9747
TEST_TIME 63 9695
TEST_TIME 64 9644
TEST_TIME 65 9676
TEST_TIME 66 9613
TEST_TIME 67 9662
TEST_TIME 68 9779
TEST_TIME 69 9820
TEST_TIME 70 9853
TEST_TIME 71 9788
TEST_TIME 72 9711
TEST_TIME 73 9832
TEST_TIME 74 9905
TEST_TIME 75 9883
TEST_TIME 76 9812
TEST_TIME 77 9751
TEST_TIME 78 9689
TEST_TIME 79 9607
TEST_TIME 80 9610
TEST_TIME 81 9584
TEST_TIME 82 9621
TEST_TIME 83 9595
TEST_TIME 84 9556
TEST_TIME 85 9479
TEST_TIME 86 9389
TEST_TIME 87 9342
TEST_TIME 88 9336
TEST_TIME 89 9321
TEST_TIME 90 9346
TEST_TIME 91 9311
TEST_TIME 92 9284
TEST_TIME 93 9305
TEST_TIME 94 9333
TEST_TIME 95 9326
TEST_TIME 96 9373
TEST_TIME 97 9398
TEST_TIME 98 9391
TEST_TIME 99 9386
TEST_TIME 100 9373
TEST_TIME 101 9440
TEST_TIME 102 9479
TEST_TIME 103 9409
TEST_TIME 104 9367
TEST_TIME 105 9324
TEST_TIME 106 9324
TEST_TIME 107 9320
TEST_TIME 108 9282
TEST_TIME 109 9264
TEST_TIME 110 9234
TEST_TIME 111 9189
TEST_TIME 112 9149
TEST_TIME 113 9172
TEST_TIME 114 9149
TEST_TIME 115 9135
TEST_TIME 116 9112
TEST_TIME 117 9127
TEST_TIME 118 9209
TEST_TIME 119 9170
TEST_TIME 120 9127

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-12-2022 10:38:37 to 20-12-2022 10:38:40.

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 1521411 1521411 0 0.00 0 15587 14 0 0
doc_list_biud 1521400 1521400 0 0.00 0 90662 43 0 0
sp_add_invoice_to_stock 5109 5007 102 1.99 80 172119 26620 0 102
sp_cancel_adding_invoice 5466 5466 0 0.00 6 88774 1222 0 0
sp_cancel_client_order 1802 1802 0 0.00 5 8222 449 0 0
sp_cancel_customer_reserve 537531 537531 0 0.00 1 90663 105 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 7517 140 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 1439 117 0 0
sp_cancel_supplier_invoice 6028 6021 7 0.11 2 12471 946 0 7
sp_cancel_supplier_order 392 392 0 0.00 11 1492533 182490 0 0
sp_cancel_write_off 9656 9656 0 0.00 2 8573 179 0 0
sp_client_order 20970 20970 0 0.00 6 23496 467 0 0
sp_customer_reserve 467030 467030 0 0.00 0 128370 249 0 0
sp_fill_shopping_cart 32171 32171 0 0.00 1 18261 93 0 0
sp_fill_shopping_cart_clo_res 466994 466994 0 0.00 0 16957 19 0 0
sp_get_clo_for_invoice 4957 4957 0 0.00 0 37534 304 0 0
sp_kill_cost_storno 16253 16253 0 0.00 0 8082 78 0 0
sp_kill_qstorno_ret_qs2qd 1091131 1091131 0 0.00 0 90659 44 0 0
sp_kill_qty_storno 575961 575961 0 0.00 0 90662 103 0 0
sp_lock_selected_doc 20967 20967 0 0.00 0 7035 6 0 0
sp_make_cost_storno 28391 28391 0 0.00 0 15584 83 0 0
sp_multiply_rows_for_pdistr 28390 28390 0 0.00 0 8076 40 0 0
sp_multiply_rows_for_qdistr 20969 20969 0 0.00 0 11797 176 0 0
sp_pay_from_customer 7181 7181 0 0.00 5 11454 181 0 0
sp_pay_to_supplier 6115 6115 0 0.00 6 18820 195 0 0
sp_payment_common 13296 13296 0 0.00 2 8313 49 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 1 8194 374 0 0
sp_qd_handle_on_invoice_upd_sts 10421 10421 0 0.00 0 21961 552 0 0
sp_qd_handle_on_reserve_upd_sts 10137 10137 0 0.00 0 15330 69 0 0
sp_reserve_write_off 10138 10138 0 0.00 8 15600 293 0 0
sp_supplier_invoice 5591 5591 0 0.00 9 73160 3138 0 0
sp_supplier_order 5624 5624 0 0.00 6 39947 1438 0 0
srv_aggregate_perf_data 105 105 0 0.00 2240 183415 19383 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 743 138359 32722 0 0
srv_find_qd_qs_mism 1041486 1041486 0 0.00 0 13915 10 0 0
srv_make_invnt_saldo 477 477 0 0.00 48 59534 4282 0 0
srv_make_money_saldo 953 953 0 0.00 0 13986 330 0 0
srv_recalc_idx_stat 4 4 0 0.00 29164 364080 195802 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5014 5014 0 0.00 1 15767 1116 0 0
t$perf-norm:sp_cancel_adding_invoice 393 393 0 0.00 1 7478 529 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 5285 38 0 0
t$perf-norm:sp_cancel_customer_reserve 751 751 0 0.00 0 1061 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 5146 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 3066 25 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 981 45 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 112285 7794 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1873 20 0 0
t$perf-norm:sp_client_order 20970 20970 0 0.00 0 7767 42 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 1459 19 0 0
t$perf-norm:sp_pay_from_customer 7181 7181 0 0.00 0 5739 20 0 0
t$perf-norm:sp_pay_to_supplier 6115 6115 0 0.00 0 6814 22 0 0
t$perf-norm:sp_reserve_write_off 10138 10138 0 0.00 0 12255 28 0 0
t$perf-norm:sp_supplier_invoice 5591 5591 0 0.00 0 9084 36 0 0
t$perf-norm:sp_supplier_order 5624 5624 0 0.00 0 6933 41 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 556 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 735 15 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 1271 21 0 0
t$perf-norm:srv_make_money_saldo 953 953 0 0.00 0 348 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 195 64 0 0
v_add_invoice_to_stock 10108 9904 204 2.01 0 4427 23 0 204
v_all_customers 38492 38492 0 0.00 0 75 4 0 0
v_all_suppliers 31339 31339 0 0.00 0 80 5 0 0
v_all_wares 41934 41934 0 0.00 0 61 5 0 0
v_cancel_adding_invoice 786 786 0 0.00 0 996 19 0 0
v_cancel_client_order 3604 3604 0 0.00 0 862 10 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 3545 14 0 0
v_cancel_customer_reserve 1502 1502 0 0.00 1 432 11 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 761 12 0 0
v_cancel_supplier_invoice 826 812 14 1.69 0 2876 31 0 14
v_cancel_supplier_order 784 784 0 0.00 0 2415 16 0 0
v_cancel_write_off 2006 2006 0 0.00 0 1414 25 0 0
v_max_id_clo_ord 5622 5622 0 0.00 0 5927 7 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 2863 20 0 0
v_max_id_ord_sup 5579 5579 0 0.00 0 6802 8 0 0
v_max_non_paid_invoice 1645 1645 0 0.00 0 332 9 0 0
v_max_non_paid_realizn 3763 3763 0 0.00 0 4238 10 0 0
v_min_id_clo_ord 5622 5622 0 0.00 0 2179 9 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 2733 14 0 0
v_min_id_ord_sup 5579 5579 0 0.00 0 4171 10 0 0
v_min_non_paid_invoice 6115 6115 0 0.00 0 5358 15 0 0
v_min_non_paid_realizn 7180 7180 0 0.00 0 8013 16 0 0
v_our_firm 10276 10276 0 0.00 0 97 5 0 0
v_reserve_write_off 20272 20272 0 0.00 0 3457 11 0 0
x_lock_depdocs_on_canc_invoice 11081 11081 0 0.00 0 18700 125 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 14420 437 0 0
x_make_qstorno_1000_1200 5624 5624 0 0.00 3 39922 1250 0 0
x_make_qstorno_1200_2000 5591 5591 0 0.00 4 71114 2882 0 0
x_make_qstorno_2100_3300 467030 467030 0 0.00 0 128358 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2022 10:38:41 to 20-12-2022 10:38:45.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 24467 8192000000 9707907936 10005975040 786422592 8.759 100 81 162 0 814170016 7188112 20559312
08:09:57 16644 8192000000 9752007616 10056990720 825749616 9.156 100 92 184 0 859454480 11516272 22188592
08:11:32 2421 8192000000 9784441072 10099064832 856904784 9.469 100 88 176 0 896173120 18844672 20423664
08:12:53 11259 8192000000 9842668384 10177310720 908671696 9.984 100 76 151 0 958838288 33941344 16225248
08:14:07 5142 8192000000 9908093392 10249711616 948743088 10.379 100 83 161 0 1021670112 55442752 17484272
08:15:15 5937 8192000000 9963324928 10319470592 988055904 10.763 100 81 159 1 1079967760 74727904 17183952
08:16:24 2054 8192000000 9982556336 10341937152 1015825584 11.032 100 87 167 0 1098261632 65851904 16584144
08:17:28 1338 8192000000 10011572624 10375913472 1032678128 11.194 100 91 175 2 1131267776 81282000 17307648
08:18:31 1544 8192000000 9994604640 10364243968 1040874224 11.273 100 91 179 0 1119260320 60749040 17637056
08:19:35 1504 8192000000 10019739520 10391089152 1057422144 11.432 100 91 174 1 1139267840 63878688 17967008
08:20:42 1408 8192000000 10020336656 10399723520 1066295968 11.517 100 90 168 2 1142598656 58626992 17675696
08:21:46 900 8192000000 10070473712 10448646144 1069152368 11.544 100 100 188 0 1189140688 100789184 19199136
08:22:48 1000 8192000000 10048547648 10425896960 1074607568 11.596 100 92 178 0 1169379856 76566240 18206048
08:23:51 5130 8192000000 10046583760 10440724480 1079598896 11.644 100 69 136 0 1176901040 84204960 13097184
08:24:58 1786 8192000000 10046424800 10435612672 1083411360 11.680 100 84 166 1 1173412160 72568656 17432144
08:26:01 1087 8192000000 10070713296 10460762112 1090660192 11.749 100 96 181 0 1193382416 84432192 18290032
08:27:04 1328 8192000000 10066244656 10459467776 1094436736 11.785 100 91 174 0 1191204976 78834608 17933632
08:28:06 915 8192000000 10054240928 10449190912 1100914016 11.846 100 84 159 1 1179060240 61313072 16833152
08:29:08 1018 8192000000 10058353024 10450694144 1099872192 11.836 100 85 162 0 1180754032 62249824 18632016
08:30:10 1164 8192000000 10064157872 10454372352 1098685232 11.825 100 95 174 2 1181597376 65452064 17460080
08:31:12 1075 8192000000 10055233952 10440646656 1088605456 11.729 100 91 174 1 1174293040 67554992 18132592
08:32:15 1126 8192000000 10043384608 10426142720 1069755616 11.550 99 93 179 4 1167694192 79478784 18459792
08:33:17 958 8192000000 9993900528 10370777088 1049263840 11.354 99 93 176 2 1122535120 54629392 18641888
08:34:19 980 8192000000 10007805280 10384756736 1052429328 11.384 100 99 184 2 1130557168 59864016 18263824
08:35:22 1134 8192000000 9983866544 10352443392 1028122944 11.150 99 92 168 4 1104598640 59232880 17242816
08:36:24 1145 8192000000 10020380944 10396786688 1040827920 11.273 100 97 185 5 1140043664 80138624 19077120
08:37:27 1190 8192000000 10026414640 10404032512 1047910128 11.341 100 88 170 1 1147049424 82042448 17096848
08:38:41 16964 8192000000 10025116576 10407215104 1064635232 11.501 100 79 154 1 1145134016 64836128 15662656
08:40:03 4335 8192000000 10024916688 10402459648 1071622624 11.568 100 86 156 0 1131996208 45781568 14592016
08:41:13 9856 8192000000 10078263552 10467483648 1084398208 11.689 100 87 164 0 1192310240 91615376 16296656
08:42:26 4194 8192000000 10083034816 10463629312 1099241616 11.830 100 90 170 2 1197578208 81862288 16474304
08:43:35 1706 8192000000 10132870848 10528038912 1116550016 11.994 100 87 165 2 1241929744 107673552 17706176
08:44:40 1461 8192000000 10118098496 10527543296 1124263040 12.067 100 71 138 0 1239310272 99657168 15390064
08:45:47 5355 8192000000 10153927024 10548260864 1126729792 12.091 100 77 148 0 1264197904 122334000 15134112
08:47:00 9875 8192000000 10179334112 10584764416 1141504672 12.230 100 64 123 1 1299348544 144576208 13267664
08:48:17 1433 8192000000 10255437872 10651123712 1159666000 12.400 100 92 180 0 1367588368 188199072 19723296
08:49:22 2352 8192000000 10251989856 10657832960 1167195136 12.471 100 86 163 2 1372261824 186835280 18231408
08:50:30 925 8192000000 10312766912 10718658560 1174721104 12.541 100 94 182 2 1429125968 233966192 20438672
08:51:34 1071 8192000000 10344326784 10753286144 1183237888 12.620 100 95 187 0 1457765488 253266368 21261232
08:52:41 1199 8192000000 10356918528 10773028864 1187668208 12.662 100 96 188 3 1459726688 251353344 20705136
08:53:46 996 8192000000 10401247088 10842488832 1201200448 12.787 100 96 185 0 1510149696 286911392 22037856
08:54:50 1330 8192000000 10398994992 10812588032 1203050064 12.805 100 95 186 1 1507868592 282416992 22401536
08:55:53 947 8192000000 10430159360 10843811840 1202351616 12.798 100 96 190 0 1540566544 315627760 22587168
08:56:56 1541 8192000000 10467205776 10879406080 1206404544 12.836 100 96 186 3 1572794592 345067664 21322384
08:58:01 1662 8192000000 10517493152 10928926720 1199528080 12.772 100 93 186 1 1621487552 400454080 21505392
08:59:07 1109 8192000000 10529150368 10964414464 1203992032 12.813 100 93 181 0 1641100704 413644672 23464000
09:00:12 2447 8192000000 10545329232 10968010752 1189977024 12.683 100 84 164 0 1649009920 439816368 19216528
09:01:18 2013 8192000000 10528332848 10980847616 1182497312 12.613 100 80 158 0 1645244640 443231776 19515552
09:02:34 729 8192000000 10605379680 11062423552 1200440208 12.780 100 98 189 0 1708531008 485261744 22829056
09:03:39 1260 8192000000 10575241792 11017428992 1183493232 12.623 100 95 188 3 1678548208 472601808 22453168
09:04:43 2140 8192000000 10595721184 11026690048 1187815584 12.663 100 91 180 1 1698526496 488036768 22674144
09:05:47 5138 8192000000 10566597568 11053645824 1186348752 12.649 100 64 119 3 1675735616 474140960 15245904
09:06:55 15991 8192000000 10540704976 10990866432 1184751968 12.634 100 45 87 0 1662278208 466188352 11337888
09:08:38 26356 8192000000 10526096640 10972864512 1177308144 12.565 99 35 70 0 1619590992 432894016 9388832
09:10:09 24961 8192000000 10475637472 10945859584 1129942720 12.121 98 33 65 0 1581092448 442574528 8575200
09:12:16 38545 8192000000 10371881216 10917634048 1147030048 12.282 100 23 46 0 1508108704 355111120 5967536
09:14:44 46931 8192000000 10081006752 10556821504 1139058256 12.207 100 24 46 0 1219507120 75672112 4776752
09:16:38 13076 8192000000 10100171360 10513088512 1121575632 12.042 99 59 114 1 1180552576 46892208 12084736
09:17:59 77953 8192000000 10084027200 10537779200 1125907360 12.083 99 15 30 0 1181453696 52422192 3124144
09:20:45 103137 8192000000 10074187584 10541174784 1100599952 11.843 96 11 22 0 1157193328 54837536 1755840
09:23:33 111133 8192000000 10065786784 10541682688 1100946880 11.847 96 10 20 0 1127462032 24524640 1990512
09:26:48 31347 8192000000 10086351328 10511065088 1124990928 12.074 98 68 120 1 1165922672 28128768 12802976
09:28:22 93725 8192000000 10118049888 10555555840 1126356272 12.087 99 50 93 3 1201430448 64542336 10531840
09:30:57 23979 8192000000 10166798912 10607177728 1138752688 12.204 100 38 76 1 1257046240 109132448 9161104
09:32:32 6181 8192000000 10174359408 10608537600 1153170656 12.339 100 58 111 2 1278193472 112827136 12195680
09:33:40 23623 8192000000 10216835680 10687479808 1142774960 12.242 100 61 120 2 1316659920 161387520 12497440
09:35:08 40345 8192000000 10143092064 10605633536 1113672112 11.967 98 12 24 0 1248988000 132553424 2762464
09:36:49 41924 8192000000 10109530016 10596122624 1135999136 12.178 100 20 40 1 1234190112 93916448 4274528
09:39:24 54971 8192000000 10087051536 10543890432 1140488064 12.220 100 27 43 0 1166382464 21632304 4262096
09:41:20 66729 8192000000 10134847040 10613952512 1156655584 12.372 100 19 36 0 1239121872 78754848 3711440
09:43:27 22863 8192000000 10183389584 10614087680 1137011072 12.187 99 52 101 0 1267551344 119115648 11424624
09:45:02 43330 8192000000 10143754688 10621591552 1121654144 12.043 98 15 31 0 1224524432 99339360 3530928
09:46:47 32535 8192000000 10109461472 10563043328 1096881056 11.808 97 26 50 0 1159075792 57122176 5072560
09:48:19 82929 8192000000 10186709552 10638798848 1127026976 12.093 98 18 32 0 1234841488 104024976 3789536
09:50:43 53359 8192000000 10108037568 10583887872 1100800048 11.845 97 27 54 0 1182392384 76151968 5440368
09:52:42 138358 8192000000 10081211216 10534436864 1090907968 11.751 97 26 52 1 1164276864 67762112 5606784
09:56:01 14074 8192000000 10057899264 10510929920 1105898608 11.894 99 19 35 1 1158639360 49813584 2927168
09:57:19 81026 8192000000 10104211504 10565984256 1118982832 12.017 99 24 44 0 1214324720 90960080 4381808
09:59:45 36824 8192000000 10050831648 10498269184 1104478240 11.880 98 16 33 0 1146651728 38954816 3218672
10:01:24 66101 8192000000 10022599424 10499850240 1078222640 11.631 97 5 10 0 1114662352 35651504 788208
10:03:31 24233 8192000000 10049226672 10479267840 1112147600 11.953 100 38 74 0 1153666944 33084304 8435040
10:05:00 75180 8192000000 10062271984 10567303168 1117947120 12.008 100 13 26 0 1172045888 51499104 2599664
10:07:18 63043 8192000000 10061511904 10551963648 1110230912 11.935 98 13 26 0 1147770432 35294432 2245088
10:09:21 27679 8192000000 10096364928 10534686720 1118371488 12.012 98 37 70 1 1184002384 57970912 7659984
10:10:52 46574 8192000000 10104940304 10559496192 1103923760 11.875 97 33 61 1 1170294656 59280896 7090000
10:12:43 52145 8192000000 10155064528 10605793280 1143170352 12.245 100 44 86 0 1237816256 85609728 9036176
10:14:36 24592 8192000000 10083254384 10529255424 1143263648 12.246 100 29 55 0 1189292992 39850400 6178944
10:16:05 41918 8192000000 10123632752 10558877696 1123643936 12.061 99 39 74 0 1195044432 63652480 7748016
10:17:47 68818 8192000000 10075050720 10530160640 1116816448 11.997 98 17 34 0 1193390864 72809488 3764928
10:19:56 26496 8192000000 10082972144 10509914112 1109604736 11.929 98 37 69 0 1143011872 25961616 7445520
10:21:25 105321 8192000000 10085492048 10553163776 1123033392 12.056 100 23 44 0 1218819488 91212992 4573104
10:24:13 11865 8192000000 10099729824 10541834240 1117597456 12.004 99 55 108 1 1192550928 63226992 11726480
10:25:26 57227 8192000000 10054669040 10528845824 1127200032 12.095 100 8 16 0 1164137328 35143632 1793664
10:27:25 62931 8192000000 10083695424 10551701504 1124418048 12.069 99 19 38 0 1164306048 35691440 4196560
10:29:57 48566 8192000000 10071988592 10564947968 1111259568 11.944 98 14 28 0 1186732064 71903472 3569024
10:31:51 26965 8192000000 10093430912 10532818944 1109306720 11.926 98 40 76 1 1158206768 40850144 8049904
10:33:19 24592 8192000000 10133365216 10573905920 1119003296 12.018 99 45 90 0 1215944640 87314064 9627280
10:34:52 63776 8192000000 10058023184 10517942272 1103100720 11.867 98 21 42 0 1169440464 61632080 4707664
10:36:58 40975 8192000000 9310373904 9663352832 421578768 4.894 37 10 20 0 470512880 46948528 1985584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 20-12-2022 10:38:47 to 20-12-2022 10:39:04.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2022 10:39:05 to 20-12-2022 10:39:07.

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          218060
MON$OLDEST_ACTIVE               219189
MON$OLDEST_SNAPSHOT             219189
MON$NEXT_TRANSACTION            219189
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                       2079456
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
--- end of diff output ---

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

20.12.22 10:39:08. 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-12-20 10:39:10 No severe PSQL-related problems occured
20.12.22 10:39:12. End of report.