Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.04.2022 13:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16144740 kB
MemAvailable 31473924 kB
Buffers 0 kB
Cached 15531500 kB
SwapCached 2688 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-04-17 13:37:14
DTS_END                         2022-04-17 13:37:14.0960
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55412
TRN_ID                          226502
ATT_ID                          631
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: 9354.44 22873 1122533
customer order: creation 174.02 452 20882
customer order: refuse 14.60 391 1752
order to supplier: creation 46.81 1369 5617
order to supplier: removal 3.80 160369 456
invoice (draft): creation 46.73 3148 5608
invoice (draft): removal 53.60 1037 6432
invoice accept: apply 41.59 23950 4991
invoice accept: cancel 49.02 1113 5882
customer reserve: creation 3883.81 226 466057
customer reserve: removal 4734.83 99 568180
realization accept: apply 87.24 278 10469
realization accept: cancel 86.22 170 10346
payment from customer: creation 60.67 167 7280
payment from customer: removal 5.83 125 700
payment to supplier: creation 49.38 189 5926
payment to supplier: removal 3.22 135 386
service: total inventory turnovers 4.44 3806 533
service: total monetary turnovers 7.65 379 918
service: aggregate perf. data 0.95 15778 114
service: refresh index statistics 0.03 244274 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-04-2022 13:38:06 to 17-04-2022 13:38:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6842
TEST_TIME 2 6103
TEST_TIME 3 5808
TEST_TIME 4 6043
TEST_TIME 5 5672
TEST_TIME 6 5440
TEST_TIME 7 6082
TEST_TIME 8 6890
TEST_TIME 9 6780
TEST_TIME 10 6767
TEST_TIME 11 6989
TEST_TIME 12 6967
TEST_TIME 13 7144
TEST_TIME 14 7312
TEST_TIME 15 7372
TEST_TIME 16 7348
TEST_TIME 17 7683
TEST_TIME 18 7943
TEST_TIME 19 8526
TEST_TIME 20 8627
TEST_TIME 21 8508
TEST_TIME 22 8433
TEST_TIME 23 8519
TEST_TIME 24 8594
TEST_TIME 25 8782
TEST_TIME 26 8689
TEST_TIME 27 8911
TEST_TIME 28 9189
TEST_TIME 29 9324
TEST_TIME 30 9522
TEST_TIME 31 9760
TEST_TIME 32 10109
TEST_TIME 33 10893
TEST_TIME 34 11483
TEST_TIME 35 11429
TEST_TIME 36 11286
TEST_TIME 37 11187
TEST_TIME 38 11122
TEST_TIME 39 11101
TEST_TIME 40 11151
TEST_TIME 41 11063
TEST_TIME 42 10914
TEST_TIME 43 10719
TEST_TIME 44 10530
TEST_TIME 45 10356
TEST_TIME 46 10368
TEST_TIME 47 10377
TEST_TIME 48 10306
TEST_TIME 49 10380
TEST_TIME 50 10403
TEST_TIME 51 10414
TEST_TIME 52 10360
TEST_TIME 53 10343
TEST_TIME 54 10261
TEST_TIME 55 10167
TEST_TIME 56 10025
TEST_TIME 57 10089
TEST_TIME 58 9998
TEST_TIME 59 9935
TEST_TIME 60 9808
TEST_TIME 61 9801
TEST_TIME 62 9766
TEST_TIME 63 9741
TEST_TIME 64 9733
TEST_TIME 65 9758
TEST_TIME 66 9779
TEST_TIME 67 9753
TEST_TIME 68 9819
TEST_TIME 69 9878
TEST_TIME 70 9910
TEST_TIME 71 9963
TEST_TIME 72 9912
TEST_TIME 73 9915
TEST_TIME 74 10009
TEST_TIME 75 10002
TEST_TIME 76 9991
TEST_TIME 77 9932
TEST_TIME 78 9952
TEST_TIME 79 9914
TEST_TIME 80 9869
TEST_TIME 81 9827
TEST_TIME 82 9729
TEST_TIME 83 9666
TEST_TIME 84 9616
TEST_TIME 85 9554
TEST_TIME 86 9513
TEST_TIME 87 9567
TEST_TIME 88 9562
TEST_TIME 89 9527
TEST_TIME 90 9552
TEST_TIME 91 9529
TEST_TIME 92 9501
TEST_TIME 93 9491
TEST_TIME 94 9496
TEST_TIME 95 9496
TEST_TIME 96 9596
TEST_TIME 97 9635
TEST_TIME 98 9704
TEST_TIME 99 9763
TEST_TIME 100 9733
TEST_TIME 101 9656
TEST_TIME 102 9634
TEST_TIME 103 9575
TEST_TIME 104 9564
TEST_TIME 105 9522
TEST_TIME 106 9485
TEST_TIME 107 9424
TEST_TIME 108 9391
TEST_TIME 109 9355
TEST_TIME 110 9343
TEST_TIME 111 9337
TEST_TIME 112 9323
TEST_TIME 113 9338
TEST_TIME 114 9340
TEST_TIME 115 9352
TEST_TIME 116 9395
TEST_TIME 117 9365
TEST_TIME 118 9390
TEST_TIME 119 9437
TEST_TIME 120 9378

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-04-2022 13:38:10 to 17-04-2022 13:38:13.

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 1553898 1553898 0 0.00 0 28859 13 0 0
doc_list_biud 1553893 1553893 0 0.00 0 39514 42 0 0
sp_add_invoice_to_stock 5100 4991 109 2.13 75 169645 23950 0 109
sp_cancel_adding_invoice 5882 5882 0 0.00 7 64033 1114 0 0
sp_cancel_client_order 1752 1752 0 0.00 6 14926 391 0 0
sp_cancel_customer_reserve 568180 568180 0 0.00 1 39524 100 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 3 8639 125 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 10361 136 0 0
sp_cancel_supplier_invoice 6439 6432 7 0.10 3 28558 1037 0 7
sp_cancel_supplier_order 456 456 0 0.00 11 1512954 160370 0 0
sp_cancel_write_off 10346 10346 0 0.00 2 14873 171 0 0
sp_client_order 20882 20882 0 0.00 6 28637 452 0 0
sp_customer_reserve 466057 466057 0 0.00 1 97381 226 0 0
sp_fill_shopping_cart 32101 32101 0 0.00 1 15469 79 0 0
sp_fill_shopping_cart_clo_res 466047 466047 0 0.00 0 14394 18 0 0
sp_get_clo_for_invoice 4956 4956 0 0.00 0 31841 258 0 0
sp_kill_cost_storno 17314 17314 0 0.00 0 12219 77 0 0
sp_kill_qstorno_ret_qs2qd 1153594 1153594 0 0.00 0 35353 43 0 0
sp_kill_qty_storno 608472 608472 0 0.00 0 39513 99 0 0
sp_lock_selected_doc 21281 21281 0 0.00 0 10388 9 0 0
sp_make_cost_storno 28631 28631 0 0.00 0 28854 84 0 0
sp_multiply_rows_for_pdistr 28630 28630 0 0.00 0 7315 37 0 0
sp_multiply_rows_for_qdistr 20882 20882 0 0.00 0 28617 180 0 0
sp_pay_from_customer 7280 7280 0 0.00 6 9063 168 0 0
sp_pay_to_supplier 5926 5926 0 0.00 6 11136 189 0 0
sp_payment_common 13206 13206 0 0.00 2 5576 39 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 1 14918 320 0 0
sp_qd_handle_on_invoice_upd_sts 10838 10838 0 0.00 0 20811 512 0 0
sp_qd_handle_on_reserve_upd_sts 10468 10468 0 0.00 0 17883 58 0 0
sp_reserve_write_off 10469 10469 0 0.00 9 19432 279 0 0
sp_supplier_invoice 5608 5608 0 0.00 12 90826 3149 0 0
sp_supplier_order 5617 5617 0 0.00 8 39395 1370 0 0
srv_aggregate_perf_data 114 114 0 0.00 2579 140042 15778 0 0
srv_fill_mon_cache_memory 73 73 0 0.00 688 120102 33793 0 0
srv_find_qd_qs_mism 1074044 1074044 0 0.00 0 10129 9 0 0
srv_make_invnt_saldo 533 533 0 0.00 39 90302 3806 0 0
srv_make_money_saldo 918 918 0 0.00 1 14449 380 0 0
srv_recalc_idx_stat 4 4 0 0.00 133617 431400 244275 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 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 4995 4995 0 0.00 1 17646 900 0 0
t$perf-norm:sp_cancel_adding_invoice 418 418 0 0.00 2 6332 407 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 2571 23 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 922 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 4732 27 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 684 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 812 32 0 0
t$perf-norm:sp_cancel_supplier_order 456 456 0 0.00 0 101741 7006 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 1760 21 0 0
t$perf-norm:sp_client_order 20882 20882 0 0.00 0 4903 33 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 3349 16 0 0
t$perf-norm:sp_pay_from_customer 7280 7280 0 0.00 0 10235 20 0 0
t$perf-norm:sp_pay_to_supplier 5926 5926 0 0.00 0 6114 18 0 0
t$perf-norm:sp_reserve_write_off 10469 10469 0 0.00 0 27194 26 0 0
t$perf-norm:sp_supplier_invoice 5608 5608 0 0.00 0 3542 26 0 0
t$perf-norm:sp_supplier_order 5617 5617 0 0.00 0 4489 29 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 578 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 305 8 0 0
t$perf-norm:srv_make_invnt_saldo 533 533 0 0.00 0 905 14 0 0
t$perf-norm:srv_make_money_saldo 918 918 0 0.00 0 896 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 173 51 0 0
v_add_invoice_to_stock 10130 9912 218 2.15 0 5956 26 0 218
v_all_customers 38454 38454 0 0.00 0 126 3 0 0
v_all_suppliers 31502 31502 0 0.00 0 72 4 0 0
v_all_wares 41764 41764 0 0.00 0 58 4 0 0
v_cancel_adding_invoice 836 836 0 0.00 0 1182 15 0 0
v_cancel_client_order 3504 3504 0 0.00 0 3383 12 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 1867 12 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 870 9 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 3259 17 0 0
v_cancel_supplier_invoice 786 772 14 1.78 0 630 21 0 14
v_cancel_supplier_order 912 912 0 0.00 0 674 12 0 0
v_cancel_write_off 2084 2084 0 0.00 0 4136 27 0 0
v_max_id_clo_ord 5617 5617 0 0.00 0 137 4 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 3386 19 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 8330 9 0 0
v_max_non_paid_invoice 1393 1393 0 0.00 0 278 7 0 0
v_max_non_paid_realizn 3839 3839 0 0.00 0 4300 9 0 0
v_min_id_clo_ord 5617 5617 0 0.00 0 2668 10 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 1828 10 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 6708 9 0 0
v_min_non_paid_invoice 5926 5926 0 0.00 0 5563 13 0 0
v_min_non_paid_realizn 7280 7280 0 0.00 0 3239 12 0 0
v_our_firm 10192 10192 0 0.00 0 45 4 0 0
v_reserve_write_off 20936 20936 0 0.00 0 5801 12 0 0
x_lock_depdocs_on_canc_invoice 11928 11928 0 0.00 0 14014 110 0 0
x_lock_depdocs_on_canc_sup_ord 456 456 0 0.00 0 2523 284 0 0
x_make_qstorno_1000_1200 5617 5617 0 0.00 2 39382 1215 0 0
x_make_qstorno_1200_2000 5608 5608 0 0.00 6 90799 2933 0 0
x_make_qstorno_2100_3300 466056 466056 0 0.00 0 97371 197 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-04-2022 13:38:14 to 17-04-2022 13:38:18.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 31902 8192000000 9717792464 10014707712 799148112 8.888 100 92 184 0 830398848 7829536 23421200
11:10:03 24248 8192000000 9769180448 10078461952 837950496 9.279 100 90 180 0 872538256 12590608 21997152
11:11:32 17167 8192000000 9816610368 10140667904 877563952 9.675 100 86 172 1 930096368 32395472 20136944
11:12:54 8647 8192000000 9879918144 10215813120 917478576 10.071 100 73 146 1 988930240 55201040 16250624
11:14:09 2295 8192000000 9932161088 10277888000 975641456 10.642 100 96 176 5 1042689392 49019024 18028912
11:15:13 2539 8192000000 9969359472 10326245376 1006015648 10.937 100 90 176 1 1082865952 58747008 18103296
11:16:17 945 8192000000 10003990288 10369343488 1029233520 11.161 100 96 187 1 1124083280 76455312 18394448
11:17:19 973 8192000000 10021129440 10396422144 1051150864 11.372 100 95 185 1 1141438608 71121968 19165776
11:18:22 1032 8192000000 10048085776 10426478592 1062817696 11.483 100 96 180 3 1171185280 89248752 19118832
11:19:25 1159 8192000000 10055923152 10431995904 1072181184 11.573 100 96 190 0 1173464640 82008432 19275024
11:20:28 1035 8192000000 10065325456 10443067392 1076818672 11.617 100 97 191 0 1181286800 85303600 19164528
11:21:30 3802 8192000000 10046024784 10435039232 1081858528 11.665 100 78 147 2 1171503040 74114064 15530448
11:22:36 883 8192000000 10036893072 10421592064 1087427664 11.718 100 93 180 0 1156805008 50737664 18639680
11:23:38 1031 8192000000 10060041040 10444206080 1088696080 11.730 100 97 194 1 1180372800 70540688 21136032
11:24:40 1232 8192000000 10067427712 10454728704 1091721136 11.759 100 97 192 1 1190257440 79310784 19225520
11:25:42 1359 8192000000 10057779504 10446487552 1095053680 11.791 100 94 180 2 1184261584 69440016 19767888
11:26:46 1084 8192000000 10079448848 10469335040 1091980432 11.761 100 91 181 0 1201097856 89816464 19300960
11:27:48 1155 8192000000 10066449200 10457735168 1090240928 11.745 100 97 189 1 1186735136 77562336 18931872
11:28:50 1113 8192000000 10033522272 10418491392 1070647728 11.558 100 91 179 0 1154659648 66140880 17871040
11:29:51 1837 8192000000 10036030960 10421460992 1078733536 11.635 100 87 174 2 1158818864 62348496 17736832
11:30:54 5680 8192000000 9998830656 10381713408 1052946240 11.389 99 59 102 1 1114259552 50920544 10392768
11:32:00 745 8192000000 10019337696 10404405248 1055769696 11.416 100 83 161 0 1139220640 66095024 17355920
11:33:02 1174 8192000000 10003712736 10383671296 1050532416 11.366 100 84 156 3 1129361616 62802864 16026336
11:34:04 982 8192000000 9948968496 10318524416 1016822384 11.041 99 86 157 2 1070066608 36892480 16351744
11:35:06 4279 8192000000 9985675024 10377895936 1054255856 11.401 100 59 104 2 1110832304 45644048 10932400
11:36:13 1143 8192000000 10004481392 10381504512 1055570688 11.414 100 91 176 0 1124039104 50190720 18277696
11:37:15 935 8192000000 9990782768 10364538880 1041917008 11.283 99 86 152 1 1112563200 54041696 16604496
11:38:23 9825 8192000000 10028645664 10408443904 1075072592 11.600 100 80 150 3 1150538800 60897392 14568816
11:39:35 894 8192000000 10070127248 10455162880 1091137632 11.753 100 95 181 0 1191461152 80340704 19982816
11:40:40 2622 8192000000 10112047520 10499973120 1107740848 11.911 100 77 148 0 1232517360 109364272 15412240
11:41:57 946 8192000000 10136556320 10523131904 1110875184 11.941 100 88 165 3 1248564192 120633216 17055792
11:43:02 6924 8192000000 10171149360 10574364672 1115878624 11.988 100 65 126 2 1281069616 151070544 14120448
11:44:11 1613 8192000000 10189987792 10588946432 1123896544 12.064 100 76 147 1 1299950400 160585216 15468640
11:45:18 4014 8192000000 10194212976 10621706240 1141179952 12.227 100 77 151 0 1311722256 153790608 16751696
11:46:29 4958 8192000000 10232222880 10641620992 1165314192 12.453 100 79 154 2 1341757168 160640816 15802160
11:47:40 1373 8192000000 10260611008 10670239744 1172088464 12.516 100 93 175 2 1370439344 178531616 19819264
11:48:43 1786 8192000000 10292454128 10691383296 1173441856 12.529 100 88 174 1 1401820832 208464752 19914224
11:49:49 804 8192000000 10342442320 10751893504 1182342016 12.612 100 98 191 0 1452139920 248274416 21523488
11:50:53 1214 8192000000 10376551584 10779869184 1179867504 12.589 100 93 172 5 1479893952 279095872 20930576
11:51:56 1107 8192000000 10374573648 10786529280 1177760128 12.569 99 92 180 0 1486264016 287348336 21155552
11:53:01 3924 8192000000 10430987280 10840621056 1185749872 12.644 100 86 170 1 1538474368 332217568 20506928
11:54:09 1080 8192000000 10435040816 10868985856 1186989296 12.655 100 100 197 2 1541937824 330292688 24655840
11:55:13 1618 8192000000 10448499904 10873479168 1196037120 12.740 100 86 169 0 1560133136 343591168 20504848
11:56:16 1380 8192000000 10412350688 10864222208 1192670128 12.708 100 91 179 1 1520307232 305551072 22086032
11:57:18 3831 8192000000 10400379424 10852483072 1191406336 12.696 100 57 107 1 1510572192 306111872 13053984
11:58:36 5300 8192000000 10422194624 10872082432 1193202400 12.713 100 69 138 0 1547121920 338191792 15727728
11:59:45 854 8192000000 10518312608 10948296704 1190522496 12.688 100 97 191 2 1623323872 408769488 24031888
12:00:48 681 8192000000 10531558720 10964676608 1203474592 12.809 100 86 170 1 1636223104 412331536 20416976
12:01:52 3603 8192000000 10527244544 10983260160 1196835712 12.747 100 70 141 1 1634835024 420723376 17275936
12:02:59 1728 8192000000 10560943776 10990952448 1193438240 12.715 100 68 134 0 1663776368 454086736 16251392
12:04:11 4160 8192000000 10541953952 11028623360 1181210112 12.601 100 59 116 0 1644923568 448957680 14755776
12:05:18 7105 8192000000 10521679712 10988171264 1174954368 12.543 100 52 104 0 1624105712 435978464 13172880
12:06:26 5288 8192000000 10558109152 11017736192 1179169776 12.582 100 48 96 1 1661509440 470021200 12318464
12:07:34 23005 8192000000 10510120352 10976460800 1175168368 12.545 100 35 70 1 1612972112 429031232 8772512
12:08:58 24354 8192000000 10445383680 10937102336 1162540400 12.427 100 30 58 1 1546605504 376251280 7813824
12:10:42 41447 8192000000 10116399856 10624282624 1151096464 12.320 100 12 24 2 1278291920 124430768 2764688
12:12:24 43777 8192000000 10068753200 10508099584 1085258816 11.698 94 20 40 1 1116904464 28070976 3574672
12:14:14 28660 8192000000 10084854672 10509484032 1098540032 11.824 96 41 82 2 1146828864 39594064 8694768
12:15:51 48151 8192000000 10114567488 10540433408 1118501216 12.013 99 36 68 1 1200155744 74525184 7129344
12:17:39 109699 8192000000 10069205696 10528587776 1091902672 11.761 96 14 28 1 1139758720 44575488 3280560
12:20:31 105585 8192000000 10150674800 10587332608 1116931824 11.998 98 38 69 3 1190951728 66057824 7962080
12:23:17 120102 8192000000 10147565120 10638110720 1110631776 11.938 97 23 46 1 1225528752 109674288 5222688
12:26:33 37871 8192000000 10097615632 10579120128 1139688656 12.213 100 39 78 1 1214440656 65123360 9628640
12:28:23 39709 8192000000 10077083600 10544058368 1120171200 12.029 98 16 32 0 1167122480 43477984 3473296
12:30:03 24999 8192000000 10084293936 10558435328 1139977648 12.215 100 14 28 0 1192496544 49651856 2867040
12:32:22 36835 8192000000 10076915072 10556944384 1135749792 12.176 100 7 14 1 1170503328 33433536 1320000
12:35:05 40612 8192000000 10049827856 10524110848 1121467248 12.041 98 17 34 0 1160942256 35846080 3628928
12:36:46 76697 8192000000 10085722272 10551898112 1141050896 12.225 100 22 44 0 1191896464 46158592 4686976
12:39:05 65175 8192000000 10066233632 10533486592 1120251184 12.029 98 9 18 1 1171858080 49967200 1639696
12:41:15 44025 8192000000 10122610176 10577858560 1123971472 12.064 98 22 44 1 1186755024 58172528 4611024
12:42:59 74953 8192000000 10119862800 10569142272 1105323840 11.888 97 14 28 0 1170994608 62724048 2946720
12:45:16 29161 8192000000 10163036720 10632601600 1144936160 12.262 100 27 54 2 1262096448 111299552 5860736
12:47:00 54957 8192000000 10103191456 10590253056 1110816000 11.940 97 14 28 0 1196478960 82532672 3130288
12:48:57 45618 8192000000 10154590976 10590433280 1154039712 12.347 100 42 84 2 1253142304 90224880 8877712
12:50:53 39703 8192000000 10146248064 10603941888 1098664112 11.825 97 18 36 0 1230863584 128147632 4051840
12:52:40 115735 8192000000 10119496912 10578100224 1109639376 11.929 98 35 70 1 1206007200 88785776 7582048
12:55:37 5152 8192000000 10105907920 10570862592 1131154752 12.132 100 37 72 1 1195301808 55762992 8384064
12:56:45 57839 8192000000 10049709488 10512269312 1121632016 12.042 99 17 33 0 1156617424 31563936 3421472
12:58:44 41856 8192000000 10033887264 10506711040 1094011920 11.781 97 2 4 0 1121162896 27051152 99824
13:00:26 72692 8192000000 10044542976 10502774784 1122815920 12.054 100 18 36 3 1173275648 46421040 4038688
13:02:50 41757 8192000000 10104801200 10557272064 1107515472 11.909 97 23 46 1 1180182816 68069008 4598336
13:04:38 41246 8192000000 10089527904 10529378304 1087395776 11.718 96 26 51 1 1142694992 50159168 5140048
13:06:24 38060 8192000000 10120165744 10561679360 1139578688 12.212 100 26 49 1 1200649824 55533680 5537456
13:08:02 12005 8192000000 10100702976 10533146624 1131173024 12.132 99 46 91 0 1184429616 43551152 9705440
13:09:15 20099 8192000000 10159031280 10588053504 1146885248 12.280 100 49 98 1 1242040752 85337600 9817904
13:10:37 16956 8192000000 10173135760 10604953600 1140172176 12.217 99 53 106 1 1270565328 119612592 10780560
13:11:57 15904 8192000000 10200828352 10636021760 1150365040 12.313 100 44 85 2 1292767568 133045392 9357136
13:13:18 31329 8192000000 10167392432 10656813056 1134467296 12.163 99 39 77 1 1277662224 134093760 9101168
13:15:02 78888 8192000000 10164622368 10638696448 1120308272 12.030 97 12 24 1 1245913856 122883600 2721984
13:17:21 95589 8192000000 10085642896 10534604800 1146209968 12.274 100 34 67 0 1199984400 46812064 6962368
13:19:59 83100 8192000000 10041922928 10534486016 1106257904 11.897 97 8 16 1 1154221680 46599920 1363856
13:22:23 62758 8192000000 10048218944 10496540672 1069802592 11.550 95 22 41 0 1104369488 29785984 4780912
13:24:27 103731 8192000000 10091307696 10552606720 1119163312 12.019 99 21 40 2 1181517296 57961600 4392384
13:27:12 43235 8192000000 10114182544 10560438272 1127687440 12.100 99 25 50 1 1209827408 77064720 5075248
13:28:57 67120 8192000000 10146337312 10621128704 1128663920 12.109 99 16 30 1 1250429008 118347296 3417792
13:31:04 50409 8192000000 10114523456 10552905728 1101022432 11.847 97 16 33 1 1185102032 80445776 3633824
13:32:57 33428 8192000000 10158064848 10604924928 1132658496 12.146 100 39 78 1 1244971568 103893776 8419296
13:34:40 66862 8192000000 10136658032 10616586240 1122056384 12.046 99 36 72 1 1236384672 106841504 7486784
13:36:49 36224 8192000000 9383934496 9717121024 499321456 5.745 45 18 36 0 520803104 17880688 3600960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 17-04-2022 13:38:20 to 17-04-2022 13:38:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 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 17-04-2022 13:38:37 to 17-04-2022 13:38:39.

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          226994
MON$OLDEST_ACTIVE               226995
MON$OLDEST_SNAPSHOT             226995
MON$NEXT_TRANSACTION            226995
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                       2079776
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:248: 
:249: mscs-test-161.budzdorov.ru    Sun Apr 17 13:37:32 2022
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:251: 
--- end of diff output ---

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

17.04.22 13:38:40. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-04-17 13:38:43 No severe PSQL-related problems occured
17.04.22 13:38:44. End of report.