Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2022 07: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 15376552 kB
MemAvailable 30589584 kB
Buffers 0 kB
Cached 16365212 kB
SwapCached 2128 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-10-04 07:37:17
DTS_END                         2022-10-04 07:37:17.5620
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/37320
TRN_ID                          219578
ATT_ID                          662
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: 9145.30 21385 1097436
customer order: creation 169.46 486 20335
customer order: refuse 14.65 413 1758
order to supplier: creation 46.18 1325 5541
order to supplier: removal 3.58 154779 430
invoice (draft): creation 43.77 3012 5252
invoice (draft): removal 52.32 911 6278
invoice accept: apply 40.68 26094 4882
invoice accept: cancel 47.59 1007 5711
customer reserve: creation 3820.01 246 458401
customer reserve: removal 4615.78 94 553894
realization accept: apply 81.17 270 9740
realization accept: cancel 82.16 159 9859
payment from customer: creation 58.05 187 6966
payment from customer: removal 5.41 136 649
payment to supplier: creation 48.96 204 5875
payment to supplier: removal 3.61 134 433
service: total inventory turnovers 3.78 3930 453
service: total monetary turnovers 7.16 334 859
service: aggregate perf. data 0.97 14246 116
service: refresh index statistics 0.03 219723 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2022 07:38:58 to 04-10-2022 07:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8494
TEST_TIME 2 6303
TEST_TIME 3 5144
TEST_TIME 4 5253
TEST_TIME 5 5317
TEST_TIME 6 5066
TEST_TIME 7 5192
TEST_TIME 8 5283
TEST_TIME 9 5854
TEST_TIME 10 6141
TEST_TIME 11 6283
TEST_TIME 12 6224
TEST_TIME 13 6484
TEST_TIME 14 6908
TEST_TIME 15 7022
TEST_TIME 16 7066
TEST_TIME 17 7155
TEST_TIME 18 7401
TEST_TIME 19 7532
TEST_TIME 20 7792
TEST_TIME 21 7955
TEST_TIME 22 8019
TEST_TIME 23 8374
TEST_TIME 24 8511
TEST_TIME 25 8739
TEST_TIME 26 9130
TEST_TIME 27 9563
TEST_TIME 28 9960
TEST_TIME 29 10392
TEST_TIME 30 10818
TEST_TIME 31 10914
TEST_TIME 32 10778
TEST_TIME 33 10876
TEST_TIME 34 10925
TEST_TIME 35 10965
TEST_TIME 36 10717
TEST_TIME 37 10550
TEST_TIME 38 10555
TEST_TIME 39 10483
TEST_TIME 40 10297
TEST_TIME 41 10243
TEST_TIME 42 10166
TEST_TIME 43 10088
TEST_TIME 44 10184
TEST_TIME 45 10144
TEST_TIME 46 10127
TEST_TIME 47 10031
TEST_TIME 48 9945
TEST_TIME 49 9755
TEST_TIME 50 9679
TEST_TIME 51 9714
TEST_TIME 52 9753
TEST_TIME 53 9734
TEST_TIME 54 9791
TEST_TIME 55 9735
TEST_TIME 56 9727
TEST_TIME 57 9704
TEST_TIME 58 9760
TEST_TIME 59 9727
TEST_TIME 60 9784
TEST_TIME 61 9785
TEST_TIME 62 9858
TEST_TIME 63 9875
TEST_TIME 64 9924
TEST_TIME 65 10018
TEST_TIME 66 9972
TEST_TIME 67 9878
TEST_TIME 68 9786
TEST_TIME 69 9757
TEST_TIME 70 9735
TEST_TIME 71 9669
TEST_TIME 72 9601
TEST_TIME 73 9618
TEST_TIME 74 9526
TEST_TIME 75 9471
TEST_TIME 76 9500
TEST_TIME 77 9468
TEST_TIME 78 9416
TEST_TIME 79 9424
TEST_TIME 80 9450
TEST_TIME 81 9398
TEST_TIME 82 9405
TEST_TIME 83 9544
TEST_TIME 84 9515
TEST_TIME 85 9576
TEST_TIME 86 9580
TEST_TIME 87 9560
TEST_TIME 88 9462
TEST_TIME 89 9497
TEST_TIME 90 9422
TEST_TIME 91 9426
TEST_TIME 92 9388
TEST_TIME 93 9361
TEST_TIME 94 9412
TEST_TIME 95 9409
TEST_TIME 96 9407
TEST_TIME 97 9396
TEST_TIME 98 9361
TEST_TIME 99 9319
TEST_TIME 100 9339
TEST_TIME 101 9365
TEST_TIME 102 9389
TEST_TIME 103 9536
TEST_TIME 104 9480
TEST_TIME 105 9559
TEST_TIME 106 9538
TEST_TIME 107 9499
TEST_TIME 108 9447
TEST_TIME 109 9391
TEST_TIME 110 9386
TEST_TIME 111 9388
TEST_TIME 112 9350
TEST_TIME 113 9313
TEST_TIME 114 9325
TEST_TIME 115 9311
TEST_TIME 116 9278
TEST_TIME 117 9233
TEST_TIME 118 9256
TEST_TIME 119 9230
TEST_TIME 120 9172

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-10-2022 07:39:02 to 04-10-2022 07:39:05.

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 1519652 1519652 0 0.00 0 26440 13 0 0
doc_list_biud 1519651 1519651 0 0.00 0 62255 40 0 0
sp_add_invoice_to_stock 4999 4882 117 2.34 69 214234 26095 0 117
sp_cancel_adding_invoice 5711 5711 0 0.00 7 57525 1008 0 0
sp_cancel_client_order 1758 1758 0 0.00 6 13984 414 0 0
sp_cancel_customer_reserve 553894 553894 0 0.00 1 62264 94 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 5057 136 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 4 4046 134 0 0
sp_cancel_supplier_invoice 6287 6278 9 0.14 3 41864 911 0 9
sp_cancel_supplier_order 430 430 0 0.00 11 1272602 154780 0 0
sp_cancel_write_off 9859 9859 0 0.00 2 12682 160 0 0
sp_client_order 20335 20335 0 0.00 5 44089 486 0 0
sp_customer_reserve 458401 458401 0 0.00 0 111148 247 0 0
sp_fill_shopping_cart 31125 31125 0 0.00 1 26664 75 0 0
sp_fill_shopping_cart_clo_res 458359 458359 0 0.00 0 14435 20 0 0
sp_get_clo_for_invoice 4828 4828 0 0.00 0 27134 299 0 0
sp_kill_cost_storno 16652 16652 0 0.00 0 18348 68 0 0
sp_kill_qstorno_ret_qs2qd 1124355 1124355 0 0.00 0 60549 41 0 0
sp_kill_qty_storno 592498 592498 0 0.00 0 62254 94 0 0
sp_lock_selected_doc 20248 20248 0 0.00 0 8493 8 0 0
sp_make_cost_storno 27409 27409 0 0.00 0 21964 80 0 0
sp_multiply_rows_for_pdistr 27409 27409 0 0.00 0 7955 38 0 0
sp_multiply_rows_for_qdistr 20335 20335 0 0.00 0 12682 190 0 0
sp_pay_from_customer 6966 6966 0 0.00 5 35436 187 0 0
sp_pay_to_supplier 5875 5875 0 0.00 6 29882 204 0 0
sp_payment_common 12841 12841 0 0.00 2 23329 47 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 13850 347 0 0
sp_qd_handle_on_invoice_upd_sts 10539 10539 0 0.00 0 20613 463 0 0
sp_qd_handle_on_reserve_upd_sts 9740 9740 0 0.00 0 14372 62 0 0
sp_reserve_write_off 9740 9740 0 0.00 8 14778 270 0 0
sp_supplier_invoice 5252 5252 0 0.00 10 92003 3012 0 0
sp_supplier_order 5541 5541 0 0.00 7 36216 1326 0 0
srv_aggregate_perf_data 116 116 0 0.00 2640 149341 14246 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 742 151663 37946 0 0
srv_find_qd_qs_mism 1049121 1049121 0 0.00 0 26380 10 0 0
srv_make_invnt_saldo 453 453 0 0.00 39 91588 3930 0 0
srv_make_money_saldo 859 859 0 0.00 2 15159 335 0 0
srv_recalc_idx_stat 4 4 0 0.00 58033 320833 219723 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 1 22333 862 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 7317 360 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 2040 26 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 1663 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 2849 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 233 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 924 22 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 121678 6177 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 3256 22 0 0
t$perf-norm:sp_client_order 20335 20335 0 0.00 0 5716 36 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 3791 22 0 0
t$perf-norm:sp_pay_from_customer 6966 6966 0 0.00 0 4967 17 0 0
t$perf-norm:sp_pay_to_supplier 5875 5875 0 0.00 0 4016 16 0 0
t$perf-norm:sp_reserve_write_off 9740 9740 0 0.00 0 7430 20 0 0
t$perf-norm:sp_supplier_invoice 5252 5252 0 0.00 0 3480 27 0 0
t$perf-norm:sp_supplier_order 5541 5541 0 0.00 0 5435 28 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1900 27 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 799 16 0 0
t$perf-norm:srv_make_invnt_saldo 453 453 0 0.00 0 5403 40 0 0
t$perf-norm:srv_make_money_saldo 859 859 0 0.00 0 3347 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 311 81 0 0
v_add_invoice_to_stock 9888 9654 234 2.36 0 5302 26 0 234
v_all_customers 37344 37344 0 0.00 0 147 4 0 0
v_all_suppliers 30690 30690 0 0.00 0 77 4 0 0
v_all_wares 40670 40670 0 0.00 0 183 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 358 13 0 0
v_cancel_client_order 3516 3516 0 0.00 0 3486 10 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 4718 18 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 2149 14 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 3441 18 0 0
v_cancel_supplier_invoice 784 766 18 2.29 0 1008 20 0 18
v_cancel_supplier_order 860 860 0 0.00 0 1870 16 0 0
v_cancel_write_off 1862 1862 0 0.00 0 4545 24 0 0
v_max_id_clo_ord 5541 5541 0 0.00 0 900 5 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 2251 16 0 0
v_max_id_ord_sup 5249 5249 0 0.00 0 3902 7 0 0
v_max_non_paid_invoice 1320 1320 0 0.00 0 312 8 0 0
v_max_non_paid_realizn 3541 3541 0 0.00 0 177 7 0 0
v_min_id_clo_ord 5541 5541 0 0.00 0 1957 7 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 4470 14 0 0
v_min_id_ord_sup 5249 5249 0 0.00 0 2785 7 0 0
v_min_non_paid_invoice 5875 5875 0 0.00 0 7034 18 0 0
v_min_non_paid_realizn 6966 6966 0 0.00 0 23324 21 0 0
v_our_firm 10176 10176 0 0.00 0 73 4 0 0
v_reserve_write_off 19480 19480 0 0.00 0 4531 11 0 0
x_lock_depdocs_on_canc_invoice 11606 11606 0 0.00 0 10303 115 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 5419 312 0 0
x_make_qstorno_1000_1200 5541 5541 0 0.00 3 36206 1180 0 0
x_make_qstorno_1200_2000 5252 5252 0 0.00 4 84974 2800 0 0
x_make_qstorno_2100_3300 458401 458401 0 0.00 0 111146 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2022 07:39:06 to 04-10-2022 07:39:10.

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
05:07:21 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:23 35624 8192000000 9743417728 10049859584 817174112 9.070 100 89 179 1 846481664 7999408 21308144
05:10:02 5559 8192000000 9772772048 10088501248 853177456 9.432 100 88 176 1 886373120 12535360 20660304
05:11:15 16299 8192000000 9808927984 10128384000 872718208 9.627 100 91 182 0 914347088 20816944 20811936
05:12:38 8657 8192000000 9850016256 10183630848 910691328 10.004 100 80 155 0 959276512 32322000 16263184
05:13:50 3136 8192000000 9919560240 10263957504 949415008 10.385 100 81 162 0 1032209520 65134224 17660288
05:14:55 2577 8192000000 9970256400 10326691840 995014960 10.830 100 87 172 0 1086950624 74020704 17914960
05:16:00 908 8192000000 10007944496 10371407872 1030533632 11.174 100 91 177 2 1128274096 80005392 17735072
05:17:04 968 8192000000 10038495872 10415632384 1053840272 11.397 100 97 186 1 1159631536 86929136 18862128
05:18:06 1711 8192000000 10048649248 10425364480 1066114448 11.515 100 86 163 1 1167130032 84488832 16526752
05:19:09 1063 8192000000 10031401440 10406674432 1074083248 11.591 100 96 187 1 1148195440 55800864 18311328
05:20:13 958 8192000000 10046543504 10431664128 1079950896 11.647 100 95 178 2 1168937680 70138576 18848208
05:21:16 1182 8192000000 10082127568 10465443840 1087589888 11.720 100 99 194 0 1201506224 94093072 19823264
05:22:18 1263 8192000000 10082166160 10468515840 1095812224 11.798 100 93 181 1 1194584832 80521536 18251072
05:23:20 991 8192000000 10054244224 10444951552 1101320336 11.850 100 87 168 1 1180217168 60384416 18512416
05:24:22 771 8192000000 10083715600 10476580864 1105175616 11.887 100 90 171 0 1203908688 82628688 16104384
05:25:25 874 8192000000 10071989680 10468470784 1107164512 11.906 100 81 158 0 1191479584 67896416 16418656
05:26:28 1104 8192000000 10063343776 10455478272 1097211136 11.811 100 93 172 1 1184149040 69286080 17651824
05:27:29 1072 8192000000 10106367504 10498727936 1108830096 11.921 100 91 178 0 1226461648 99747936 17883616
05:28:32 1152 8192000000 10054061904 10444197888 1093599584 11.777 99 93 178 2 1178207344 66174096 18433664
05:29:34 1502 8192000000 10030370416 10421092352 1076868256 11.618 100 87 163 2 1151845456 58494816 16482384
05:30:36 1101 8192000000 10051289888 10437488640 1087151680 11.716 100 97 190 0 1173388624 65778288 20458656
05:31:39 1055 8192000000 10025736864 10415738880 1084947888 11.695 100 89 166 1 1154244112 49150880 20145344
05:32:40 1349 8192000000 9997679712 10375950336 1053762512 11.397 100 92 170 4 1127787904 54995472 19029920
05:33:43 1195 8192000000 9981072960 10350473216 1021174688 11.083 100 96 184 2 1105616624 65566976 18874960
05:34:44 1114 8192000000 10011868144 10390302720 1058897712 11.446 100 95 178 2 1136267120 58295552 19073856
05:35:47 5814 8192000000 9997298064 10377166848 1034798688 11.215 99 74 142 0 1114833232 65422880 14611664
05:36:53 2283 8192000000 9975118240 10361692160 1033142544 11.199 99 61 115 6 1095262608 51039200 11080864
05:38:05 730 8192000000 10015416144 10392457216 1058742416 11.444 99 90 164 0 1138314384 62657728 16914240
05:39:11 11170 8192000000 10016509136 10401951744 1057068624 11.428 99 65 124 1 1132437712 63847424 11521664
05:40:32 11121 8192000000 10063885904 10452320256 1088905056 11.732 100 74 137 1 1190731024 87853600 13972368
05:41:45 1235 8192000000 10140194704 10529996800 1108317472 11.916 100 89 174 0 1263341408 137243664 17780272
05:42:52 3892 8192000000 10127976304 10523541504 1116934240 11.998 100 64 121 2 1249228624 118716688 13577696
05:44:01 5633 8192000000 10172766576 10575409152 1128380512 12.106 100 51 99 2 1294104096 154059216 11664368
05:45:10 1550 8192000000 10211127248 10617573376 1143510880 12.249 100 87 170 0 1325645344 162350144 19784320
05:46:15 1280 8192000000 10266516736 10662662144 1157007056 12.375 100 93 177 0 1381633152 203671184 20954912
05:47:20 2706 8192000000 10269151888 10683416576 1166250528 12.462 100 81 158 1 1381279056 196235344 18793184
05:48:27 895 8192000000 10294310816 10697375744 1170186752 12.499 100 94 184 1 1406794128 216621456 19985920
05:49:33 1177 8192000000 10339346880 10753839104 1182548848 12.614 100 97 189 1 1452132912 246166608 23417456
05:50:38 1285 8192000000 10353376368 10771472384 1179974512 12.590 100 90 171 4 1457657776 259101408 18581856
05:51:43 1051 8192000000 10380240224 10799714304 1188998144 12.674 100 95 180 0 1492119360 282761648 20359568
05:52:47 1516 8192000000 10403507120 10809860096 1193280128 12.714 100 95 177 0 1512069120 297696944 21092048
05:53:57 1092 8192000000 10432765328 10845073408 1183807328 12.626 100 86 158 0 1546602896 343950352 18845216
05:55:01 1447 8192000000 10495761920 10923855872 1195650832 12.736 100 95 190 1 1600277568 382509536 22117200
05:56:05 733 8192000000 10476742016 10935590912 1197432560 12.752 100 93 178 0 1583490688 365880304 20177824
05:57:10 825 8192000000 10534520560 10986250240 1206651536 12.838 100 98 190 1 1637044608 407483280 22909792
05:58:19 2251 8192000000 10532355312 10971754496 1206240208 12.834 100 82 159 1 1634176624 407101744 20834672
05:59:25 4628 8192000000 10527022896 10960404480 1195378768 12.733 100 61 117 0 1633273616 421141360 16753488
06:00:42 6589 8192000000 10529793888 10991226880 1183274496 12.621 99 51 101 0 1625121184 428779904 13066784
06:01:57 13829 8192000000 10473977792 10968989696 1174001216 12.534 100 42 84 0 1576599664 391287312 11311136
06:03:20 13724 8192000000 10475324928 10974855168 1148160336 12.292 98 40 77 1 1577776208 419406672 10209200
06:04:36 24331 8192000000 10326939584 10822029312 1134203536 12.161 99 25 49 0 1447477328 306961344 6312448
06:06:07 43204 8192000000 10196033632 10680160256 1113263984 11.963 98 15 30 0 1352696896 235179776 4253136
06:07:54 62744 8192000000 10079701664 10533335040 1087418864 11.718 95 21 36 0 1156897744 65442656 4036224
06:09:58 61503 8192000000 10106511968 10591965184 1125274816 12.077 99 17 31 0 1226229488 98250128 2704544
06:12:09 124085 8192000000 10058668144 10494074880 1119248320 12.020 98 32 55 1 1186433392 60921264 6263808
06:15:22 151663 8192000000 10080582416 10517024768 1081687072 11.664 94 35 67 0 1109211888 20888944 6635872
06:19:02 39373 8192000000 10110624464 10552639488 1146223120 12.274 100 29 55 0 1217902000 65859744 5819136
06:20:43 31954 8192000000 10167557952 10620768256 1150613520 12.315 100 45 86 1 1262222400 102897040 8711840
06:22:16 94860 8192000000 10064215776 10510974976 1104482544 11.880 96 14 28 0 1130679184 23208512 2988128
06:25:05 62818 8192000000 10103225008 10562969600 1151126416 12.320 100 23 43 0 1191296544 35581248 4588880
06:27:12 65496 8192000000 10140226080 10632830976 1130940896 12.130 98 28 54 0 1220961440 84702432 5318112
06:29:49 78268 8192000000 10130340688 10656092160 1140937760 12.224 99 17 34 0 1233707904 89290192 3479952
06:32:19 32557 8192000000 10086760048 10525880320 1124560688 12.070 98 27 53 0 1166980720 36841104 5578928
06:33:52 26410 8192000000 10090806192 10532569088 1128220288 12.105 99 49 96 1 1194423536 56177536 10025712
06:35:20 25101 8192000000 10126227184 10577731584 1114838336 11.978 97 35 71 1 1211128848 88407920 7882592
06:36:52 72108 8192000000 10113032544 10590707712 1120633200 12.033 98 24 46 0 1237753168 111581344 5538624
06:39:08 23283 8192000000 10085721600 10526433280 1139738576 12.213 99 31 61 0 1197590416 51992560 5859280
06:40:32 43527 8192000000 10146279232 10588979200 1132036432 12.141 98 30 60 1 1220448464 82289552 6122480
06:42:54 66702 8192000000 10090043056 10525446144 1108631760 11.919 97 31 62 0 1144090112 29347136 6111216
06:45:15 37069 8192000000 10124412544 10570416128 1124374464 12.068 99 70 136 0 1191403760 52477616 14551680
06:47:04 35023 8192000000 10052214544 10537390080 1120018256 12.027 99 17 32 0 1175967808 52697936 3251616
06:48:48 86699 8192000000 10089999584 10539982848 1113054448 11.961 98 10 20 0 1169582448 54220864 2307136
06:51:15 56140 8192000000 10079017936 10544967680 1104275600 11.878 98 11 22 0 1154917408 48129136 2512672
06:53:15 30687 8192000000 10050450032 10482524160 1097858944 11.817 97 27 50 1 1127003120 24208464 4935712
06:54:49 20400 8192000000 10082635280 10517594112 1122899776 12.054 100 35 68 0 1186857168 56898736 7058656
06:56:18 32278 8192000000 10104516768 10537254912 1109579024 11.928 99 37 74 0 1203537936 86054544 7904368
06:57:51 22164 8192000000 10115729040 10538778624 1112962272 11.960 99 54 106 0 1232005824 107225728 11817824
06:59:19 47740 8192000000 10150729392 10601635840 1110350496 11.936 99 31 62 0 1247732944 130524784 6857664
07:01:07 104985 8192000000 10117801344 10614722560 1117696032 12.005 99 33 64 2 1226856768 102579456 6581280
07:03:53 102837 8192000000 10125068848 10597179392 1119581568 12.023 99 50 100 0 1196086272 64885664 11619040
07:06:46 59667 8192000000 10063038256 10557169664 1115663776 11.986 99 5 10 0 1158171728 41529856 978096
07:08:47 73143 8192000000 10061776416 10502557696 1044209232 11.305 92 13 25 0 1105250688 58558720 2482736
07:11:00 54266 8192000000 10070276240 10541912064 1085076336 11.696 97 19 38 0 1177852128 88728576 4047216
07:12:56 26674 8192000000 10091724400 10527666176 1127861744 12.101 100 41 82 0 1182235424 46029696 8343984
07:14:31 36728 8192000000 10161451248 10602323968 1147865104 12.289 100 40 72 0 1242340944 86735616 7740224
07:16:13 5722 8192000000 10191971040 10626535424 1158366224 12.388 100 53 93 1 1289570608 120600912 10603472
07:17:20 40321 8192000000 10216815280 10657521664 1150397840 12.313 100 37 74 0 1309445184 150811632 8235712
07:19:16 42830 8192000000 10193900336 10666049536 1131214672 12.133 99 17 34 1 1280030976 144916224 3900080
07:20:59 38334 8192000000 10082692064 10510712832 1118179360 12.010 98 39 76 0 1157617456 31550368 7887728
07:22:37 53809 8192000000 10093068672 10542280704 1132166720 12.142 99 19 37 0 1166110672 29923136 4020816
07:24:46 81973 8192000000 10101726880 10543439872 1136158928 12.179 99 45 88 0 1192740112 47055104 9526080
07:27:11 32759 8192000000 10075688592 10538278912 1120978688 12.036 97 17 32 0 1168955120 44317968 3658464
07:28:47 67199 8192000000 10091930224 10572603392 1145420160 12.266 100 8 17 0 1209738832 62797216 1521456
07:30:54 54400 8192000000 10053656928 10507022336 1104780992 11.883 96 19 38 0 1170616288 61893808 3941488
07:32:50 67148 8192000000 10096147696 10577797120 1140518768 12.220 100 15 30 0 1202355904 58511296 3325840
07:35:04 86676 8192000000 10095445072 10617044992 1144964928 12.262 100 17 32 1 1197779488 48930176 3884384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 117 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2022 07:39:30 to 04-10-2022 07:39:32.

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          218602
MON$OLDEST_ACTIVE               220211
MON$OLDEST_SNAPSHOT             220211
MON$NEXT_TRANSACTION            220211
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                       2052512
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Tue Oct  4 07:37:26 2022
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

04.10.22 07:39:33. 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-10-04 07:39:35 No severe PSQL-related problems occured
04.10.22 07:39:37. End of report.