Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.2023 10:39
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 15105820 kB
MemAvailable 30420284 kB
Buffers 0 kB
Cached 16621088 kB
SwapCached 3348 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-16 10:37:26
DTS_END                         2023-02-16 10:37:26.3440
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/53688
TRN_ID                          230833
ATT_ID                          677
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: 9006.43 28335 1080772
customer order: creation 175.88 459 21106
customer order: refuse 15.74 419 1889
order to supplier: creation 47.63 1340 5715
order to supplier: removal 3.54 134003 425
invoice (draft): creation 47.54 3322 5705
invoice (draft): removal 48.32 930 5798
invoice accept: apply 43.24 25847 5189
invoice accept: cancel 43.43 1273 5212
customer reserve: creation 3946.47 250 473576
customer reserve: removal 4338.04 86 520565
realization accept: apply 85.63 280 10276
realization accept: cancel 73.63 175 8836
payment from customer: creation 62.29 183 7475
payment from customer: removal 6.18 128 741
payment to supplier: creation 51.76 195 6211
payment to supplier: removal 3.64 139 437
service: total inventory turnovers 4.34 3527 521
service: total monetary turnovers 8.03 356 963
service: aggregate perf. data 1.07 15563 128
service: refresh index statistics 0.03 378221 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-02-2023 10:40:04 to 16-02-2023 10:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6824
TEST_TIME 2 6596
TEST_TIME 3 5686
TEST_TIME 4 6159
TEST_TIME 5 5792
TEST_TIME 6 5714
TEST_TIME 7 5646
TEST_TIME 8 5495
TEST_TIME 9 5545
TEST_TIME 10 5675
TEST_TIME 11 6983
TEST_TIME 12 7082
TEST_TIME 13 7113
TEST_TIME 14 7198
TEST_TIME 15 7168
TEST_TIME 16 7255
TEST_TIME 17 7598
TEST_TIME 18 7679
TEST_TIME 19 7768
TEST_TIME 20 8215
TEST_TIME 21 8362
TEST_TIME 22 8445
TEST_TIME 23 8490
TEST_TIME 24 8595
TEST_TIME 25 8532
TEST_TIME 26 8994
TEST_TIME 27 9267
TEST_TIME 28 9695
TEST_TIME 29 10195
TEST_TIME 30 10104
TEST_TIME 31 10258
TEST_TIME 32 10911
TEST_TIME 33 11010
TEST_TIME 34 10961
TEST_TIME 35 10805
TEST_TIME 36 10812
TEST_TIME 37 10721
TEST_TIME 38 10791
TEST_TIME 39 10665
TEST_TIME 40 10711
TEST_TIME 41 10636
TEST_TIME 42 10419
TEST_TIME 43 10242
TEST_TIME 44 10264
TEST_TIME 45 10134
TEST_TIME 46 10132
TEST_TIME 47 10068
TEST_TIME 48 10033
TEST_TIME 49 10008
TEST_TIME 50 9991
TEST_TIME 51 9936
TEST_TIME 52 9931
TEST_TIME 53 9874
TEST_TIME 54 9796
TEST_TIME 55 9768
TEST_TIME 56 9611
TEST_TIME 57 9561
TEST_TIME 58 9550
TEST_TIME 59 9530
TEST_TIME 60 9447
TEST_TIME 61 9463
TEST_TIME 62 9450
TEST_TIME 63 9393
TEST_TIME 64 9458
TEST_TIME 65 9592
TEST_TIME 66 9516
TEST_TIME 67 9585
TEST_TIME 68 9573
TEST_TIME 69 9508
TEST_TIME 70 9580
TEST_TIME 71 9708
TEST_TIME 72 9642
TEST_TIME 73 9539
TEST_TIME 74 9464
TEST_TIME 75 9424
TEST_TIME 76 9384
TEST_TIME 77 9326
TEST_TIME 78 9356
TEST_TIME 79 9298
TEST_TIME 80 9280
TEST_TIME 81 9287
TEST_TIME 82 9236
TEST_TIME 83 9272
TEST_TIME 84 9319
TEST_TIME 85 9327
TEST_TIME 86 9388
TEST_TIME 87 9385
TEST_TIME 88 9362
TEST_TIME 89 9418
TEST_TIME 90 9347
TEST_TIME 91 9274
TEST_TIME 92 9298
TEST_TIME 93 9259
TEST_TIME 94 9215
TEST_TIME 95 9166
TEST_TIME 96 9125
TEST_TIME 97 9100
TEST_TIME 98 9060
TEST_TIME 99 9081
TEST_TIME 100 9089
TEST_TIME 101 9086
TEST_TIME 102 9121
TEST_TIME 103 9103
TEST_TIME 104 9075
TEST_TIME 105 9115
TEST_TIME 106 9124
TEST_TIME 107 9162
TEST_TIME 108 9199
TEST_TIME 109 9177
TEST_TIME 110 9193
TEST_TIME 111 9182
TEST_TIME 112 9204
TEST_TIME 113 9266
TEST_TIME 114 9274
TEST_TIME 115 9212
TEST_TIME 116 9148
TEST_TIME 117 9149
TEST_TIME 118 9109
TEST_TIME 119 9087
TEST_TIME 120 9036

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-02-2023 10:40:07 to 16-02-2023 10:40:11.

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 1516268 1516268 0 0.00 0 20857 13 0 0
doc_list_biud 1516258 1516258 0 0.00 0 47604 36 0 0
sp_add_invoice_to_stock 5352 5189 163 3.04 58 248551 25848 0 163
sp_cancel_adding_invoice 5212 5212 0 0.00 5 78541 1273 0 0
sp_cancel_client_order 1889 1889 0 0.00 5 14202 419 0 0
sp_cancel_customer_reserve 520565 520565 0 0.00 1 47607 87 0 0
sp_cancel_pay_from_customer 741 741 0 0.00 4 10394 128 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 4 5694 140 0 0
sp_cancel_supplier_invoice 5812 5798 14 0.24 2 25249 931 0 14
sp_cancel_supplier_order 425 425 0 0.00 10 1543109 134004 0 0
sp_cancel_write_off 8836 8836 0 0.00 2 12832 175 0 0
sp_client_order 21106 21106 0 0.00 6 45191 459 0 0
sp_customer_reserve 473576 473576 0 0.00 0 39290 250 0 0
sp_fill_shopping_cart 32511 32511 0 0.00 1 22028 83 0 0
sp_fill_shopping_cart_clo_res 473543 473543 0 0.00 0 14776 17 0 0
sp_get_clo_for_invoice 5145 5145 0 0.00 0 21408 283 0 0
sp_kill_cost_storno 15226 15226 0 0.00 0 8362 73 0 0
sp_kill_qstorno_ret_qs2qd 1056189 1056189 0 0.00 0 47602 38 0 0
sp_kill_qty_storno 558143 558143 0 0.00 0 47604 89 0 0
sp_lock_selected_doc 21498 21498 0 0.00 0 8825 9 0 0
sp_make_cost_storno 29106 29106 0 0.00 0 15502 78 0 0
sp_multiply_rows_for_pdistr 29104 29104 0 0.00 0 8407 40 0 0
sp_multiply_rows_for_qdistr 21104 21104 0 0.00 0 45168 168 0 0
sp_pay_from_customer 7475 7475 0 0.00 5 10387 183 0 0
sp_pay_to_supplier 6211 6211 0 0.00 6 16433 195 0 0
sp_payment_common 13685 13685 0 0.00 2 7639 51 0 0
sp_qd_handle_on_cancel_clo 1889 1889 0 0.00 0 14194 335 0 0
sp_qd_handle_on_invoice_upd_sts 10355 10355 0 0.00 0 25899 487 0 0
sp_qd_handle_on_reserve_upd_sts 10275 10275 0 0.00 0 10611 58 0 0
sp_reserve_write_off 10276 10276 0 0.00 8 15959 281 0 0
sp_supplier_invoice 5705 5705 0 0.00 8 104719 3323 0 0
sp_supplier_order 5715 5715 0 0.00 8 68995 1341 0 0
srv_aggregate_perf_data 128 128 0 0.00 2278 236458 15564 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 840 137889 36428 0 0
srv_find_qd_qs_mism 1029784 1029784 0 0.00 0 20856 10 0 0
srv_make_invnt_saldo 521 521 0 0.00 85 75003 3527 0 0
srv_make_money_saldo 963 963 0 0.00 1 21618 357 0 0
srv_recalc_idx_stat 4 4 0 0.00 238142 509314 378221 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5191 5191 0 0.00 1 25297 898 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 7815 495 0 0
t$perf-norm:sp_cancel_client_order 1889 1889 0 0.00 0 5002 27 0 0
t$perf-norm:sp_cancel_customer_reserve 743 743 0 0.00 0 18956 47 0 0
t$perf-norm:sp_cancel_pay_from_customer 741 741 0 0.00 0 2756 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 2406 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 459 459 0 0.00 0 1435 40 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 89904 5995 0 0
t$perf-norm:sp_cancel_write_off 942 942 0 0.00 0 9081 33 0 0
t$perf-norm:sp_client_order 21106 21106 0 0.00 0 10469 36 0 0
t$perf-norm:sp_customer_reserve 1076 1076 0 0.00 0 4279 17 0 0
t$perf-norm:sp_pay_from_customer 7475 7475 0 0.00 0 8889 18 0 0
t$perf-norm:sp_pay_to_supplier 6211 6211 0 0.00 0 3713 20 0 0
t$perf-norm:sp_reserve_write_off 10276 10276 0 0.00 0 20092 29 0 0
t$perf-norm:sp_supplier_invoice 5705 5705 0 0.00 0 6955 33 0 0
t$perf-norm:sp_supplier_order 5715 5715 0 0.00 0 8899 34 0 0
t$perf-norm:srv_aggregate_perf_data 128 128 0 0.00 0 1081 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 21 3 0 0
t$perf-norm:srv_make_invnt_saldo 521 521 0 0.00 0 638 15 0 0
t$perf-norm:srv_make_money_saldo 963 963 0 0.00 0 6910 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 117 52 0 0
v_add_invoice_to_stock 10606 10280 326 3.07 0 3251 25 0 326
v_all_customers 38520 38520 0 0.00 0 107 4 0 0
v_all_suppliers 31999 31999 0 0.00 0 176 4 0 0
v_all_wares 42186 42186 0 0.00 0 147 4 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 414 13 0 0
v_cancel_client_order 3778 3778 0 0.00 0 3689 13 0 0
v_cancel_customer_prepayment 1482 1482 0 0.00 0 3788 15 0 0
v_cancel_customer_reserve 1486 1486 0 0.00 0 1433 10 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 2800 17 0 0
v_cancel_supplier_invoice 946 918 28 2.95 0 3357 28 0 28
v_cancel_supplier_order 850 850 0 0.00 0 6268 25 0 0
v_cancel_write_off 1884 1884 0 0.00 0 3582 24 0 0
v_max_id_clo_ord 5711 5711 0 0.00 0 1361 5 0 0
v_max_id_clo_res 1076 1076 0 0.00 0 3001 13 0 0
v_max_id_ord_sup 5701 5701 0 0.00 0 4839 7 0 0
v_max_non_paid_invoice 1622 1622 0 0.00 0 410 8 0 0
v_max_non_paid_realizn 3896 3896 0 0.00 0 712 7 0 0
v_min_id_clo_ord 5711 5711 0 0.00 0 869 7 0 0
v_min_id_clo_res 1076 1076 0 0.00 0 4680 17 0 0
v_min_id_ord_sup 5701 5701 0 0.00 0 3481 9 0 0
v_min_non_paid_invoice 6210 6210 0 0.00 0 7629 17 0 0
v_min_non_paid_realizn 7474 7474 0 0.00 0 7612 23 0 0
v_our_firm 10824 10824 0 0.00 0 56 4 0 0
v_reserve_write_off 20550 20550 0 0.00 0 5768 11 0 0
x_lock_depdocs_on_canc_invoice 10551 10551 0 0.00 0 24716 127 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 14873 478 0 0
x_make_qstorno_1000_1200 5715 5715 0 0.00 2 68978 1173 0 0
x_make_qstorno_1200_2000 5705 5705 0 0.00 3 102181 3102 0 0
x_make_qstorno_2100_3300 473574 473574 0 0.00 0 39289 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-02-2023 10:40:12 to 16-02-2023 10:40:16.

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:30 3 8192000000 8802546080 8815042560 7767120 0.094 3 1 2 0 7873104 70208 35776
08:08:31 41445 8192000000 9732773008 10033467392 802702480 8.924 100 86 172 0 831325376 7896864 20726032
08:10:18 26081 8192000000 9768175552 10079457280 841681328 9.317 100 84 168 0 876124528 14732224 19710976
08:11:53 10450 8192000000 9816087616 10136350720 875984864 9.660 100 84 168 0 925834384 30683184 19166336
08:13:05 8043 8192000000 9868528192 10198433792 908648000 9.984 100 86 172 0 978477360 50863184 18966176
08:14:18 4128 8192000000 9915894016 10254376960 942950672 10.322 100 91 180 0 1027907152 66423168 18533312
08:15:24 2627 8192000000 9957479744 10307014656 980936928 10.693 100 86 167 2 1074086320 76247488 16901904
08:16:29 1029 8192000000 9996241504 10361049088 1013578896 11.010 100 87 171 0 1117121952 86307584 17235472
08:17:32 850 8192000000 10012749216 10381987840 1033125472 11.199 100 95 175 2 1135301440 83252272 18923696
08:18:34 891 8192000000 10023300896 10391007232 1052560992 11.385 100 99 196 0 1140001056 67666784 19773280
08:19:35 2392 8192000000 10016202288 10390593536 1058118304 11.438 100 85 165 1 1132905744 58631088 16156352
08:20:39 875 8192000000 10010859776 10393247744 1063546416 11.490 100 88 161 2 1134904624 53610368 17747840
08:21:40 1072 8192000000 10029420592 10413854720 1069338016 11.546 100 90 168 1 1154055152 68289536 16427600
08:22:43 815 8192000000 10019006448 10402672640 1078519920 11.633 100 86 170 0 1143641216 45870256 19251040
08:23:44 1218 8192000000 10051702944 10433081344 1078140192 11.630 100 100 201 1 1172773168 74021968 20611008
08:24:47 1163 8192000000 10078646192 10465255424 1079623104 11.644 100 97 188 0 1198184992 99917920 18643968
08:25:49 1402 8192000000 10074783072 10460762112 1085440528 11.699 100 96 186 2 1193860576 89492256 18927792
08:26:51 1472 8192000000 10054436224 10443325440 1089840400 11.741 100 83 160 2 1169434064 63986880 15606784
08:27:54 896 8192000000 10045248128 10438467584 1086455568 11.709 100 82 162 1 1169464096 66243648 16764880
08:28:57 3185 8192000000 10011804512 10410885120 1076855088 11.617 99 53 98 1 1150585280 64496784 9233408
08:30:01 3281 8192000000 9987002608 10376163328 1053611296 11.395 98 68 120 0 1104345648 38406208 12328144
08:31:05 11007 8192000000 10015981360 10403012608 1058055872 11.438 100 61 117 1 1138904352 69518960 11329520
08:32:17 884 8192000000 10002704464 10384658432 1051631424 11.376 99 87 174 1 1122079888 52114128 18334336
08:33:19 1205 8192000000 9993035232 10363142144 1025746416 11.127 100 95 178 3 1111979152 68235584 17997152
08:34:21 4913 8192000000 9984075856 10365562880 1029086768 11.160 99 63 117 6 1108391872 66460944 12844160
08:35:27 2834 8192000000 9978001456 10351820800 1020555280 11.077 99 76 151 0 1099711152 64474128 14681744
08:36:32 938 8192000000 9970713840 10342248448 1026487712 11.135 100 85 156 1 1094301616 51355136 16458768
08:37:35 11419 8192000000 10012249600 10404499456 1048678576 11.348 100 58 109 2 1132461920 73201488 10581856
08:39:02 4838 8192000000 10014025824 10393026560 1061898400 11.475 100 76 145 0 1129668864 52923520 14846944
08:40:10 7719 8192000000 10043653856 10433077248 1070421296 11.556 100 57 114 0 1164048112 82024880 11601936
08:41:43 13404 8192000000 10087597952 10495979520 1093453664 11.775 100 61 120 0 1201808736 95203152 13151920
08:43:04 10072 8192000000 10114469760 10517118976 1111609744 11.948 100 70 131 0 1235801008 110373088 13818176
08:44:17 6822 8192000000 10142383888 10546860032 1122473392 12.050 100 69 118 0 1261974384 126274256 13226736
08:45:29 2277 8192000000 10205442032 10604265472 1130851008 12.129 100 77 144 2 1314222928 167953168 15418752
08:46:36 1612 8192000000 10225789776 10624974848 1140698976 12.222 100 91 171 0 1338864112 179321360 18843776
08:47:40 888 8192000000 10248664256 10671616000 1159333504 12.397 100 91 174 2 1370294720 191182160 19779056
08:48:44 1346 8192000000 10241188912 10685542400 1174518992 12.539 100 93 180 1 1353064128 158183024 20362112
08:49:49 3694 8192000000 10255460336 10668204032 1182042096 12.609 100 74 144 2 1365803040 167465408 16295536
08:50:55 2608 8192000000 10256673216 10661715968 1173704864 12.531 99 84 158 0 1362055072 171019232 17330976
08:52:02 2357 8192000000 10303306304 10720890880 1191903264 12.701 100 70 123 3 1418327488 212086240 14337984
08:53:07 1033 8192000000 10338939648 10743263232 1192513040 12.707 100 96 186 1 1451155424 235803232 22839152
08:54:10 1190 8192000000 10337879744 10740113408 1174615776 12.540 99 96 182 0 1450248144 255997488 19634880
08:55:13 1099 8192000000 10395777360 10800914432 1187906560 12.664 100 93 181 1 1504093952 295811552 20375840
08:56:16 3727 8192000000 10403156336 10821066752 1156796384 12.373 99 91 182 1 1500679728 322341232 21542112
08:57:24 1387 8192000000 10392893936 10811637760 1173825296 12.533 100 97 180 1 1492708032 298790176 20092560
08:58:27 830 8192000000 10385558736 10810376192 1162877200 12.430 100 87 169 0 1490443936 307189280 20377456
08:59:32 2101 8192000000 10393700736 10815938560 1168927248 12.487 100 66 128 2 1509649968 325430528 15292192
09:00:38 1449 8192000000 10450365280 10870771712 1176737776 12.560 100 85 167 1 1555432640 358080000 20614864
09:01:42 4385 8192000000 10475024928 10891075584 1178637536 12.577 100 70 140 0 1582481552 386094752 17749264
09:02:53 1791 8192000000 10492484240 10938527744 1174920112 12.543 100 89 174 0 1600297168 403510352 21866704
09:03:57 10774 8192000000 10434019808 10897637376 1158153040 12.386 100 46 92 0 1532854416 362845152 11856224
09:05:13 14996 8192000000 10368225056 10865737728 1163574000 12.437 100 40 77 0 1481556320 307746304 10236016
09:06:31 19955 8192000000 10294918976 10766782464 1147820512 12.289 99 26 50 1 1413418096 259059280 6538304
09:08:08 46282 8192000000 10260746032 10729717760 1142804768 12.242 99 17 34 0 1367928256 220926784 4196704
09:09:59 98289 8192000000 10048348864 10475216896 1071150144 11.563 94 14 24 1 1117405504 44317936 1937424
09:12:38 41606 8192000000 10097700544 10556239872 1099522912 11.833 98 26 47 0 1181237536 77063760 4650864
09:14:23 89835 8192000000 10067072816 10508558336 1095997280 11.800 97 30 58 1 1143863952 42173088 5693584
09:17:05 81831 8192000000 10055037984 10527490048 1075054080 11.600 95 12 24 0 1126958336 49917776 1986480
09:19:37 77199 8192000000 10114626976 10554941440 1143962560 12.253 100 33 62 1 1190520864 39734224 6824080
09:22:05 23788 8192000000 10072118304 10539954176 1133072672 12.150 99 18 34 0 1164613264 29154176 2386416
09:23:32 14980 8192000000 10114255296 10548936704 1160281424 12.406 100 31 55 0 1198394592 32257632 5855536
09:24:51 12423 8192000000 10165130160 10595127296 1161932288 12.421 100 63 109 2 1253276912 78474400 12870224
09:26:06 60323 8192000000 10089827360 10575429632 1130204144 12.123 98 18 36 0 1200348144 65830464 4313536
09:28:14 44777 8192000000 10115857632 10583060480 1160087648 12.404 100 10 20 0 1212971472 50868608 2015216
09:30:40 22542 8192000000 10110893744 10561290240 1154934448 12.356 99 30 55 0 1202422416 42359904 5128064
09:32:10 108606 8192000000 10080203664 10564071424 1154911872 12.356 100 24 44 0 1206006480 46602416 4492192
09:34:59 58846 8192000000 10070655584 10548764672 1106417744 11.898 96 13 26 0 1178641440 69495328 2728368
09:37:01 57088 8192000000 10069932448 10505981952 1082042256 11.667 95 34 65 2 1125473056 36879680 6551120
09:39:01 24812 8192000000 10078209232 10530779136 1065494768 11.509 94 33 66 0 1124741440 52055488 7191184
09:40:31 35104 8192000000 10103597360 10568142848 1084410912 11.689 97 35 70 0 1193094768 101051872 7631984
09:42:15 38754 8192000000 10043559312 10511290368 1084302960 11.688 98 27 54 0 1144752064 54585728 5863376
09:43:57 41799 8192000000 10079507312 10552045568 1108056560 11.914 100 28 54 0 1198482192 84973488 5452144
09:45:41 18988 8192000000 10073997328 10502258688 1106880416 11.903 99 38 71 0 1148515632 35157872 6477344
09:47:01 24454 8192000000 10152431312 10601758720 1124842832 12.073 100 40 80 0 1251213200 118228256 8142112
09:48:26 83748 8192000000 10056742304 10493546496 1098957888 11.828 97 24 48 0 1148803120 45265472 4579760
09:51:19 15647 8192000000 10062625664 10497908736 1122381168 12.049 99 41 74 0 1158506832 28898912 7226752
09:52:36 80319 8192000000 10048368848 10518163456 1095879776 11.799 97 16 32 0 1170807936 71039472 3888688
09:55:13 61547 8192000000 10061027072 10543312896 1100021584 11.838 98 16 32 2 1167054944 63321472 3711888
09:57:20 45095 8192000000 10106005552 10564784128 1087358912 11.718 97 30 60 0 1179004544 85350016 6295616
09:59:13 46853 8192000000 10131448336 10569592832 1124304928 12.068 100 22 44 0 1220999520 91678960 5015632
10:01:01 55721 8192000000 10117981744 10583396352 1110972912 11.942 99 18 36 0 1238524752 123677568 3874272
10:02:58 66006 8192000000 10091139056 10536419328 1114480928 11.975 99 22 44 0 1204831888 85848768 4502192
10:05:09 6681 8192000000 10098881344 10533752832 1126539216 12.089 100 52 103 0 1192261184 55499120 10222848
10:06:18 69642 8192000000 10137030080 10595618816 1139296448 12.209 100 44 86 1 1222427440 74292016 8838976
10:08:31 98822 8192000000 10065327328 10562506752 1127936992 12.102 99 29 56 1 1160161552 26674208 5550352
10:11:10 74510 8192000000 10094753984 10551644160 1139785984 12.214 100 17 34 0 1200549168 57341456 3421728
10:13:25 48731 8192000000 10076380928 10552360960 1134556176 12.164 100 14 24 1 1219979936 82632864 2790896
10:15:16 67014 8192000000 10109475536 10608304128 1140243472 12.218 100 16 32 1 1236352016 92675328 3433216
10:17:23 71814 8192000000 10079398080 10553364480 1078007824 11.628 94 17 34 0 1135358528 53805040 3545664
10:20:15 53688 8192000000 10112153984 10554224640 1113057088 11.961 97 25 50 0 1165145184 46744240 5343856
10:22:40 34228 8192000000 10126046256 10600898560 1131809040 12.138 99 29 58 0 1234538320 96118320 6610960
10:24:16 92073 8192000000 10106031872 10619265024 1034012016 11.207 91 29 58 1 1145683424 105574016 6097392
10:27:00 14128 8192000000 10049645392 10479157248 1078173232 11.630 97 38 73 0 1121620256 36165536 7281488
10:28:31 34296 8192000000 10136065920 10574024704 1094116640 11.782 98 43 86 0 1185438272 82765040 8556592
10:30:07 51997 8192000000 10116963200 10600583168 1125237824 12.076 100 38 76 0 1229228080 96508736 7481520
10:32:04 137889 8192000000 10117967248 10603245568 1145595648 12.268 100 29 58 0 1231725712 79871392 6258672
10:35:50 83149 8192000000 10105167152 10605342720 1152039712 12.329 100 11 22 0 1237443328 83128848 2274768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 16-02-2023 10:40:18 to 16-02-2023 10:40:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-02-2023 10:40:35 to 16-02-2023 10:40:37.

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          229875
MON$OLDEST_ACTIVE               231609
MON$OLDEST_SNAPSHOT             231609
MON$NEXT_TRANSACTION            231609
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                       2054368
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2902 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2902 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2902 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2902 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 ---
:91: 
:92: mscs-test-161.budzdorov.ru    Thu Feb 16 10:37:49 2023
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
--- end of diff output ---

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

16.02.23 10:40:39. 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-16 10:40:41 No severe PSQL-related problems occured
16.02.23 10:40:43. End of report.