Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.07.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 15566204 kB
MemAvailable 30992656 kB
Buffers 0 kB
Cached 16126492 kB
SwapCached 2512 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-07-31 07:37:17
DTS_END                         2022-07-31 07:37:17.2790
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/44974
TRN_ID                          231373
ATT_ID                          682
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: 9304.69 29808 1116563
customer order: creation 180.42 402 21650
customer order: refuse 15.44 348 1853
order to supplier: creation 48.12 1255 5774
order to supplier: removal 3.30 142030 396
invoice (draft): creation 48.18 3001 5781
invoice (draft): removal 49.52 994 5942
invoice accept: apply 44.61 23317 5353
invoice accept: cancel 44.86 1056 5383
customer reserve: creation 4187.14 220 502457
customer reserve: removal 4379.65 82 525558
realization accept: apply 87.29 238 10475
realization accept: cancel 77.28 143 9274
payment from customer: creation 62.99 156 7559
payment from customer: removal 5.67 98 680
payment to supplier: creation 53.22 167 6386
payment to supplier: removal 3.63 109 435
service: total inventory turnovers 4.31 3897 517
service: total monetary turnovers 8.07 365 968
service: aggregate perf. data 0.98 16381 118
service: refresh index statistics 0.03 401894 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-07-2022 07:38:44 to 31-07-2022 07:38:46.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6757
TEST_TIME 2 6798
TEST_TIME 3 6068
TEST_TIME 4 5800
TEST_TIME 5 5526
TEST_TIME 6 5944
TEST_TIME 7 5797
TEST_TIME 8 6228
TEST_TIME 9 6277
TEST_TIME 10 6333
TEST_TIME 11 6431
TEST_TIME 12 6612
TEST_TIME 13 7034
TEST_TIME 14 7015
TEST_TIME 15 7103
TEST_TIME 16 7084
TEST_TIME 17 7090
TEST_TIME 18 7084
TEST_TIME 19 7367
TEST_TIME 20 7694
TEST_TIME 21 8077
TEST_TIME 22 8103
TEST_TIME 23 8145
TEST_TIME 24 8616
TEST_TIME 25 8687
TEST_TIME 26 8706
TEST_TIME 27 9187
TEST_TIME 28 10079
TEST_TIME 29 10385
TEST_TIME 30 10430
TEST_TIME 31 10388
TEST_TIME 32 10539
TEST_TIME 33 10622
TEST_TIME 34 10600
TEST_TIME 35 10400
TEST_TIME 36 10592
TEST_TIME 37 10491
TEST_TIME 38 10562
TEST_TIME 39 10464
TEST_TIME 40 10395
TEST_TIME 41 10327
TEST_TIME 42 10269
TEST_TIME 43 10147
TEST_TIME 44 10405
TEST_TIME 45 10295
TEST_TIME 46 10178
TEST_TIME 47 10200
TEST_TIME 48 10314
TEST_TIME 49 10207
TEST_TIME 50 10134
TEST_TIME 51 10025
TEST_TIME 52 9961
TEST_TIME 53 9919
TEST_TIME 54 9835
TEST_TIME 55 9819
TEST_TIME 56 9826
TEST_TIME 57 9766
TEST_TIME 58 9740
TEST_TIME 59 9799
TEST_TIME 60 9729
TEST_TIME 61 9799
TEST_TIME 62 9707
TEST_TIME 63 9618
TEST_TIME 64 9629
TEST_TIME 65 9575
TEST_TIME 66 9525
TEST_TIME 67 9501
TEST_TIME 68 9490
TEST_TIME 69 9552
TEST_TIME 70 9541
TEST_TIME 71 9571
TEST_TIME 72 9562
TEST_TIME 73 9670
TEST_TIME 74 9629
TEST_TIME 75 9772
TEST_TIME 76 9870
TEST_TIME 77 9862
TEST_TIME 78 9799
TEST_TIME 79 9818
TEST_TIME 80 9725
TEST_TIME 81 9665
TEST_TIME 82 9623
TEST_TIME 83 9581
TEST_TIME 84 9548
TEST_TIME 85 9602
TEST_TIME 86 9543
TEST_TIME 87 9536
TEST_TIME 88 9464
TEST_TIME 89 9405
TEST_TIME 90 9433
TEST_TIME 91 9417
TEST_TIME 92 9418
TEST_TIME 93 9405
TEST_TIME 94 9414
TEST_TIME 95 9382
TEST_TIME 96 9400
TEST_TIME 97 9460
TEST_TIME 98 9445
TEST_TIME 99 9406
TEST_TIME 100 9486
TEST_TIME 101 9472
TEST_TIME 102 9449
TEST_TIME 103 9525
TEST_TIME 104 9458
TEST_TIME 105 9393
TEST_TIME 106 9362
TEST_TIME 107 9369
TEST_TIME 108 9379
TEST_TIME 109 9341
TEST_TIME 110 9322
TEST_TIME 111 9370
TEST_TIME 112 9333
TEST_TIME 113 9354
TEST_TIME 114 9366
TEST_TIME 115 9356
TEST_TIME 116 9334
TEST_TIME 117 9369
TEST_TIME 118 9402
TEST_TIME 119 9384
TEST_TIME 120 9322

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-07-2022 07:38:48 to 31-07-2022 07:38:51.

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 1576624 1576624 0 0.00 0 22127 12 0 0
doc_list_biud 1576624 1576624 0 0.00 0 72843 33 0 0
sp_add_invoice_to_stock 5493 5353 140 2.54 26 170053 23318 0 140
sp_cancel_adding_invoice 5383 5383 0 0.00 5 75175 1056 0 0
sp_cancel_client_order 1853 1853 0 0.00 6 9344 349 0 0
sp_cancel_customer_reserve 525558 525558 0 0.00 1 22467 83 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 4 2287 98 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 4 4216 110 0 0
sp_cancel_supplier_invoice 5953 5942 11 0.18 4 74262 995 0 11
sp_cancel_supplier_order 396 396 0 0.00 19 1439424 142030 0 0
sp_cancel_write_off 9274 9274 0 0.00 2 19402 143 0 0
sp_client_order 21650 21650 0 0.00 5 20421 403 0 0
sp_customer_reserve 502457 502457 0 0.00 0 144981 220 0 0
sp_fill_shopping_cart 33194 33194 0 0.00 1 25010 76 0 0
sp_fill_shopping_cart_clo_res 502448 502448 0 0.00 0 16073 16 0 0
sp_get_clo_for_invoice 5319 5319 0 0.00 0 20589 246 0 0
sp_kill_cost_storno 15772 15772 0 0.00 0 16172 63 0 0
sp_kill_qstorno_ret_qs2qd 1066728 1066728 0 0.00 0 72842 37 0 0
sp_kill_qty_storno 564199 564199 0 0.00 0 72843 86 0 0
sp_lock_selected_doc 21812 21812 0 0.00 0 8139 6 0 0
sp_make_cost_storno 29738 29738 0 0.00 0 22114 68 0 0
sp_multiply_rows_for_pdistr 29738 29738 0 0.00 0 9654 32 0 0
sp_multiply_rows_for_qdistr 21650 21650 0 0.00 0 14016 145 0 0
sp_pay_from_customer 7559 7559 0 0.00 6 14084 156 0 0
sp_pay_to_supplier 6386 6386 0 0.00 6 40377 167 0 0
sp_payment_common 13945 13945 0 0.00 2 36305 46 0 0
sp_qd_handle_on_cancel_clo 1853 1853 0 0.00 1 9331 283 0 0
sp_qd_handle_on_invoice_upd_sts 10701 10701 0 0.00 0 17148 476 0 0
sp_qd_handle_on_reserve_upd_sts 10475 10475 0 0.00 0 16091 52 0 0
sp_reserve_write_off 10475 10475 0 0.00 7 21323 239 0 0
sp_supplier_invoice 5781 5781 0 0.00 8 88320 3001 0 0
sp_supplier_order 5774 5774 0 0.00 7 71949 1255 0 0
srv_aggregate_perf_data 118 118 0 0.00 2088 142984 16381 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 778 174851 37959 0 0
srv_find_qd_qs_mism 1062891 1062891 0 0.00 0 17246 9 0 0
srv_make_invnt_saldo 517 517 0 0.00 51 112673 3898 0 0
srv_make_money_saldo 968 968 0 0.00 1 14267 365 0 0
srv_recalc_idx_stat 4 4 0 0.00 356082 456185 401894 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5353 5353 0 0.00 1 14066 791 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 5330 370 0 0
t$perf-norm:sp_cancel_client_order 1853 1853 0 0.00 0 819 22 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 2749 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 3583 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 489 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 757 35 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 97328 6013 0 0
t$perf-norm:sp_cancel_write_off 1091 1091 0 0.00 0 1962 15 0 0
t$perf-norm:sp_client_order 21650 21650 0 0.00 0 4755 31 0 0
t$perf-norm:sp_customer_reserve 1097 1097 0 0.00 0 3845 14 0 0
t$perf-norm:sp_pay_from_customer 7559 7559 0 0.00 0 3647 16 0 0
t$perf-norm:sp_pay_to_supplier 6386 6386 0 0.00 0 4225 17 0 0
t$perf-norm:sp_reserve_write_off 10475 10475 0 0.00 0 3237 20 0 0
t$perf-norm:sp_supplier_invoice 5781 5781 0 0.00 0 4039 25 0 0
t$perf-norm:sp_supplier_order 5774 5774 0 0.00 0 2944 26 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 249 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 118 5 0 0
t$perf-norm:srv_make_invnt_saldo 517 517 0 0.00 0 657 12 0 0
t$perf-norm:srv_make_money_saldo 968 968 0 0.00 0 353 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 184 51 0 0
v_add_invoice_to_stock 10916 10636 280 2.56 0 6110 25 0 280
v_all_customers 40186 40186 0 0.00 0 97 3 0 0
v_all_suppliers 32668 32668 0 0.00 0 158 4 0 0
v_all_wares 43298 43298 0 0.00 0 121 3 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2323 15 0 0
v_cancel_client_order 3706 3706 0 0.00 0 4740 10 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 2193 12 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 3812 17 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 4125 18 0 0
v_cancel_supplier_invoice 876 854 22 2.51 0 1094 23 0 22
v_cancel_supplier_order 792 792 0 0.00 0 1495 13 0 0
v_cancel_write_off 2182 2182 0 0.00 0 2990 24 0 0
v_max_id_clo_ord 5774 5774 0 0.00 0 559 4 0 0
v_max_id_clo_res 1097 1097 0 0.00 0 3201 17 0 0
v_max_id_ord_sup 5771 5771 0 0.00 0 2573 5 0 0
v_max_non_paid_invoice 1597 1597 0 0.00 0 651 7 0 0
v_max_non_paid_realizn 3775 3775 0 0.00 0 898 7 0 0
v_min_id_clo_ord 5774 5774 0 0.00 0 2473 8 0 0
v_min_id_clo_res 1097 1097 0 0.00 0 2962 13 0 0
v_min_id_ord_sup 5771 5771 0 0.00 0 5116 13 0 0
v_min_non_paid_invoice 6386 6386 0 0.00 0 36290 22 0 0
v_min_non_paid_realizn 7559 7559 0 0.00 0 8129 19 0 0
v_our_firm 10680 10680 0 0.00 0 158 4 0 0
v_reserve_write_off 20950 20950 0 0.00 0 4637 12 0 0
x_lock_depdocs_on_canc_invoice 10898 10898 0 0.00 0 26620 116 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 3861 290 0 0
x_make_qstorno_1000_1200 5774 5774 0 0.00 2 71907 1112 0 0
x_make_qstorno_1200_2000 5781 5781 0 0.00 3 88273 2780 0 0
x_make_qstorno_2100_3300 502457 502457 0 0.00 0 144977 194 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-07-2022 07:38:52 to 31-07-2022 07:38:56.

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 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:22 20083 8192000000 9725567552 10025635840 805191664 8.949 100 88 176 0 833738672 7432128 21114880
05:09:49 14788 8192000000 9762014304 10066325504 837166064 9.271 100 91 182 0 871177696 12475056 21536576
05:11:09 15667 8192000000 9804728016 10121785344 867761264 9.578 100 87 175 0 908267616 20827248 19679104
05:12:28 7686 8192000000 9869003504 10199851008 910637568 10.004 100 83 164 0 980882240 52341376 17903296
05:13:39 3162 8192000000 9912723248 10255867904 960123296 10.490 100 83 165 4 1021042800 42806144 18113360
05:14:45 2316 8192000000 9967535104 10320146432 993217648 10.813 100 92 181 0 1074333776 62486848 18629280
05:15:49 1899 8192000000 10025202720 10386755584 1022057792 11.092 100 96 189 0 1138375920 97315488 19002640
05:16:54 6413 8192000000 9999528480 10368479232 1041703824 11.281 100 76 142 0 1123767488 68790512 13273152
05:18:04 904 8192000000 10023504400 10398785536 1061235744 11.468 100 97 180 3 1146173024 66026432 18910848
05:19:06 1662 8192000000 10058176816 10442530816 1071647264 11.568 100 89 175 1 1173078064 83622304 17808496
05:20:10 2327 8192000000 10045616016 10421649408 1079394912 11.642 100 87 169 1 1169167504 73361520 16411072
05:21:13 785 8192000000 10047917680 10425724928 1083106176 11.677 100 94 183 2 1168276048 66502448 18667424
05:22:15 1179 8192000000 10068341600 10452717568 1087703280 11.721 100 98 190 0 1191433808 84218720 19511808
05:23:18 979 8192000000 10047022512 10434555904 1092627392 11.768 100 93 180 0 1161204064 50947264 17629408
05:24:19 1091 8192000000 10053847664 10442010624 1093717120 11.778 100 92 169 1 1172629280 61623456 17288704
05:25:22 1082 8192000000 10074438016 10463555584 1102450432 11.861 100 97 188 0 1195738704 73561888 19726384
05:26:24 1051 8192000000 10061951264 10448736256 1099037152 11.829 100 92 180 0 1183821200 66003952 18780096
05:27:26 1084 8192000000 10062012352 10449567744 1105145344 11.886 100 95 185 0 1184430656 59799648 19485664
05:28:28 4877 8192000000 10077073312 10470232064 1108143840 11.915 100 67 133 2 1190748368 69360432 13244096
05:29:34 4171 8192000000 10049948192 10439032832 1089631392 11.739 100 75 143 0 1178871424 74007472 15232560
05:30:39 1165 8192000000 10065643616 10449408000 1084958528 11.695 100 93 178 0 1185348896 82386800 18003568
05:31:41 6834 8192000000 10012950672 10407665664 1057284896 11.430 99 69 134 4 1129361120 57925952 14150272
05:32:51 1136 8192000000 10010506896 10393198592 1067058848 11.524 100 66 123 0 1128271744 48477024 12735872
05:33:53 1262 8192000000 10009495248 10385305600 1043317616 11.297 100 93 171 2 1130092944 69294592 17480736
05:34:55 1137 8192000000 9977819616 10352975872 1033387552 11.201 100 86 159 1 1100168912 50356112 16425248
05:35:57 1191 8192000000 9993663760 10369171456 1037978384 11.245 100 94 175 1 1111765696 55451760 18335552
05:36:59 5958 8192000000 9970146368 10343903232 1023100368 11.102 99 57 92 2 1080763200 48646496 9016336
05:38:12 15818 8192000000 9989614352 10378063872 1054499952 11.404 100 62 121 2 1110464640 44026112 11938576
05:39:31 940 8192000000 10032940816 10413105152 1065074304 11.505 100 93 165 3 1151833232 68550704 18208224
05:40:36 17239 8192000000 10040791888 10434990080 1083161088 11.678 100 65 123 2 1156416416 60708144 12547184
05:41:58 1680 8192000000 10102318496 10488070144 1100756864 11.845 100 76 141 1 1221048672 105881456 14410352
05:43:04 956 8192000000 10166851056 10552889344 1114481872 11.975 100 98 186 1 1286969168 152136864 20350432
05:44:09 3554 8192000000 10184673248 10573578240 1125037312 12.075 100 90 169 1 1300251200 157765680 17448208
05:45:19 1942 8192000000 10199362880 10610839552 1132822352 12.148 100 86 159 3 1315693408 165404976 17466080
05:46:25 1526 8192000000 10218609712 10615107584 1138852176 12.205 100 76 140 2 1334480480 181015728 14612576
05:47:33 1038 8192000000 10262274336 10660708352 1156580208 12.371 100 94 183 0 1377702064 200418848 20703008
05:48:37 1799 8192000000 10303891680 10706796544 1153330352 12.341 100 90 169 0 1416057376 243441264 19285760
05:49:42 1191 8192000000 10309301824 10716184576 1163051440 12.432 100 94 183 1 1425442064 242275856 20114768
05:50:46 1543 8192000000 10330075296 10730872832 1170848912 12.505 100 87 171 2 1439617056 250209760 18558384
05:51:51 1088 8192000000 10363907040 10770907136 1171436304 12.510 100 86 165 0 1473677328 283798304 18442720
05:52:54 4123 8192000000 10400601712 10807140352 1183813152 12.626 100 84 166 0 1505792112 303308496 18670464
05:54:03 1111 8192000000 10431283616 10839281664 1166210544 12.461 100 98 186 2 1536480224 348164400 22105280
05:55:08 1619 8192000000 10459927856 10860068864 1160145632 12.405 99 92 176 2 1565144208 384460096 20538480
05:56:18 939 8192000000 10489442896 10930233344 1179775104 12.588 100 98 187 2 1600609200 397924880 22909216
05:57:23 920 8192000000 10441790672 10907164672 1171651344 12.512 99 97 191 0 1550924352 355678160 23594848
05:58:27 768 8192000000 10435675056 10853949440 1172513136 12.520 100 93 180 1 1541669280 348293760 20862384
05:59:30 3976 8192000000 10450066400 10870435840 1154287600 12.350 99 61 119 1 1552714256 384019104 14407552
06:00:45 7068 8192000000 10440704464 10909069312 1152825040 12.336 100 61 117 0 1548866048 380684288 15356720
06:01:55 9355 8192000000 10452670752 10905817088 1158259248 12.387 100 46 89 1 1553701808 384790592 10651968
06:03:35 31837 8192000000 10413715472 10856673280 1141716768 12.232 100 32 60 1 1539224432 388472624 9035040
06:05:09 6521 8192000000 10284303040 10788446208 1136204640 12.180 100 58 108 2 1382849904 232944928 13700336
06:06:17 29934 8192000000 10132297584 10589454336 1135207008 12.170 100 35 68 1 1232608368 89816944 7584416
06:07:48 58991 8192000000 10146029584 10586451968 1111571296 11.947 97 21 40 0 1220375200 103391376 5412528
06:09:58 36868 8192000000 10104972640 10570534912 1106179744 11.896 98 24 48 0 1187436352 76385344 4871264
06:11:45 27504 8192000000 10093908192 10524905472 1118608368 12.014 100 52 93 2 1195647664 66941088 10098208
06:13:30 74962 8192000000 10081440336 10545168384 1128881184 12.111 100 14 27 1 1178464848 46654096 2929568
06:15:50 95206 8192000000 10057136384 10512429056 1104821472 11.883 97 13 26 0 1141004096 34019712 2162912
06:18:26 65651 8192000000 10090506624 10542661632 1126065024 12.084 98 17 34 0 1167145712 37805520 3275168
06:20:33 50177 8192000000 10168217936 10609414144 1129701936 12.119 98 19 36 0 1239488352 106163968 3622448
06:22:27 174847 8192000000 10133387088 10697494528 1128067888 12.103 97 9 18 0 1234701264 104792784 1840592
06:26:22 62656 8192000000 10077784992 10548600832 1141602160 12.231 99 31 58 1 1180906144 32415600 6888384
06:28:32 26678 8192000000 10140899488 10576154624 1151848384 12.327 99 53 94 3 1224582992 63129904 9604704
06:30:00 77836 8192000000 10134429680 10585821184 1154906032 12.356 100 36 72 0 1234489440 71908464 7674944
06:32:25 42974 8192000000 10099790944 10563928064 1127669904 12.099 98 26 51 1 1180935168 48499840 4765424
06:34:21 7350 8192000000 10110658736 10526273536 1135393056 12.172 99 70 133 5 1196887760 46850112 14644592
06:35:30 46241 8192000000 10129165200 10602770432 1111033328 11.942 98 17 34 0 1237422848 122021792 4367728
06:37:16 58285 8192000000 10117081568 10594512896 1109589712 11.929 98 9 18 0 1189266048 77744912 1931424
06:39:17 162583 8192000000 10041154064 10556407808 1054171104 11.401 93 9 18 0 1095964400 40275504 1517792
06:43:00 23423 8192000000 10058190704 10494001152 1118242608 12.010 100 39 74 3 1141516208 15922496 7351104
06:44:33 28617 8192000000 10089911376 10544394240 1116231008 11.991 99 30 60 0 1177782592 54985008 6566576
06:46:06 57337 8192000000 10080778064 10540986368 1123501424 12.060 99 23 44 1 1182244464 54376512 4366528
06:48:05 22778 8192000000 10152840704 10602541056 1139212400 12.208 100 33 66 0 1249773680 103357552 7203728
06:49:28 38792 8192000000 10165653888 10624151552 1143206128 12.246 100 21 42 0 1256087296 108430560 4450608
06:51:09 54850 8192000000 10168714176 10624012288 1115771952 11.987 97 17 34 0 1218031104 98064112 4195040
06:53:08 75925 8192000000 10089493936 10536140800 1098922432 11.827 96 22 41 1 1176095504 72679584 4493488
06:55:32 45215 8192000000 10114523856 10555908096 1110196384 11.934 98 41 82 0 1198082736 79989488 7896864
06:57:17 81813 8192000000 10063708144 10502090752 1123291984 12.058 99 33 66 0 1159084160 29474848 6317328
06:59:40 35031 8192000000 10074821136 10524360704 1109155680 11.924 98 26 52 0 1181980784 67290384 5534720
07:01:16 108491 8192000000 10055853792 10572967936 1103523488 11.871 97 13 26 0 1163531392 57207408 2800496
07:04:06 47478 8192000000 10072544672 10538086400 1138212592 12.199 100 17 35 0 1195775024 53851456 3710976
07:05:55 74349 8192000000 10097016960 10546778112 1130675552 12.128 99 16 32 1 1185864000 51371328 3817120
07:08:25 67795 8192000000 10094084848 10557480960 1136868880 12.186 99 11 22 0 1184385296 45012080 2504336
07:10:33 38205 8192000000 10083325840 10548789248 1139804848 12.214 100 38 76 0 1189786784 42353392 7628544
07:12:12 21138 8192000000 10115248560 10563948544 1150616256 12.315 100 29 58 0 1206126976 49090976 6419744
07:13:39 88771 8192000000 10073366480 10598354944 1107336960 11.907 96 33 64 1 1205641472 91426736 6877776
07:16:09 13577 8192000000 10121678720 10556170240 1149830944 12.308 100 42 81 0 1210455360 52478128 8146288
07:17:25 49945 8192000000 10101404576 10588377088 1117964448 12.008 97 27 52 0 1219583616 96270096 5349072
07:19:16 80523 8192000000 10083946976 10605543424 1143432672 12.248 99 27 52 0 1214486048 66137632 4915744
07:22:19 18357 8192000000 10092851616 10544439296 1157543568 12.380 100 38 67 1 1195739888 31052912 7143408
07:23:40 77726 8192000000 10085247088 10597953536 1101932016 11.856 96 6 12 0 1155430096 52448208 1049872
07:26:04 31604 8192000000 10107048224 10567086080 1141070816 12.226 99 33 66 0 1194984976 46950512 6963648
07:27:43 48624 8192000000 10069912832 10556104704 1091622656 11.758 96 22 44 2 1179473104 82860160 4990288
07:29:32 23243 8192000000 10111309600 10549694464 1128591264 12.108 100 59 109 1 1191069360 50820224 11657872
07:31:13 37151 8192000000 10064258400 10503966720 1140120656 12.217 100 32 62 0 1171393440 24567888 6704896
07:32:55 18599 8192000000 10086600432 10519146496 1115695424 11.986 99 39 78 0 1177268528 54029600 7543504
07:34:14 27211 8192000000 10115570384 10587561984 1131500432 12.136 100 38 76 0 1239030816 99765104 7765280
07:35:49 102983 8192000000 9143047936 9466916864 269322576 3.182 24 7 12 1 335173776 64271024 1580176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-07-2022 07:38:58 to 31-07-2022 07:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-07-2022 07:39:15 to 31-07-2022 07:39:17.

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          233746
MON$OLDEST_ACTIVE               233747
MON$OLDEST_SNAPSHOT             233747
MON$NEXT_TRANSACTION            233747
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                       2082592
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             798
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:346: 
:347: mscs-test-161.budzdorov.ru    Sun Jul 31 07:37:49 2022
:348:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:349: 
--- end of diff output ---

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

31.07.22 07:39:18. 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-07-31 07:39:20 No severe PSQL-related problems occured
31.07.22 07:39:22. End of report.