Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.07.2022 04: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 15688092 kB
MemAvailable 31113224 kB
Buffers 20 kB
Cached 15989840 kB
SwapCached 2524 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-11 04:37:19
DTS_END                         2022-07-11 04:37:19.0330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/45498
TRN_ID                          220310
ATT_ID                          649
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: 9059.03 27665 1087083
customer order: creation 167.14 488 20057
customer order: refuse 14.65 404 1758
order to supplier: creation 45.04 1344 5405
order to supplier: removal 3.30 186903 396
invoice (draft): creation 45.11 3404 5413
invoice (draft): removal 49.87 1059 5984
invoice accept: apply 41.45 26518 4974
invoice accept: cancel 45.32 1324 5438
customer reserve: creation 3861.63 251 463395
customer reserve: removal 4497.45 106 539694
realization accept: apply 81.94 280 9833
realization accept: cancel 79.03 174 9484
payment from customer: creation 57.34 189 6881
payment from customer: removal 5.66 150 679
payment to supplier: creation 48.09 204 5771
payment to supplier: removal 3.08 112 370
service: total inventory turnovers 4.08 3958 489
service: total monetary turnovers 7.81 380 937
service: aggregate perf. data 1.00 14545 120
service: refresh index statistics 0.04 311507 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-07-2022 04:39:19 to 11-07-2022 04:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12412
TEST_TIME 2 8847
TEST_TIME 3 7987
TEST_TIME 4 7549
TEST_TIME 5 6707
TEST_TIME 6 6286
TEST_TIME 7 6446
TEST_TIME 8 6454
TEST_TIME 9 6485
TEST_TIME 10 6584
TEST_TIME 11 6576
TEST_TIME 12 6735
TEST_TIME 13 6888
TEST_TIME 14 7030
TEST_TIME 15 7171
TEST_TIME 16 7349
TEST_TIME 17 7443
TEST_TIME 18 7630
TEST_TIME 19 7562
TEST_TIME 20 7631
TEST_TIME 21 7665
TEST_TIME 22 7729
TEST_TIME 23 7840
TEST_TIME 24 8037
TEST_TIME 25 7964
TEST_TIME 26 8090
TEST_TIME 27 8778
TEST_TIME 28 9193
TEST_TIME 29 9367
TEST_TIME 30 9552
TEST_TIME 31 9564
TEST_TIME 32 9835
TEST_TIME 33 10032
TEST_TIME 34 10647
TEST_TIME 35 11354
TEST_TIME 36 11387
TEST_TIME 37 11278
TEST_TIME 38 11087
TEST_TIME 39 10892
TEST_TIME 40 10728
TEST_TIME 41 10558
TEST_TIME 42 10473
TEST_TIME 43 10414
TEST_TIME 44 10366
TEST_TIME 45 10247
TEST_TIME 46 10259
TEST_TIME 47 10223
TEST_TIME 48 10111
TEST_TIME 49 9954
TEST_TIME 50 9888
TEST_TIME 51 9844
TEST_TIME 52 9959
TEST_TIME 53 9794
TEST_TIME 54 9841
TEST_TIME 55 9738
TEST_TIME 56 9712
TEST_TIME 57 9626
TEST_TIME 58 9552
TEST_TIME 59 9495
TEST_TIME 60 9436
TEST_TIME 61 9403
TEST_TIME 62 9394
TEST_TIME 63 9435
TEST_TIME 64 9477
TEST_TIME 65 9516
TEST_TIME 66 9521
TEST_TIME 67 9502
TEST_TIME 68 9572
TEST_TIME 69 9643
TEST_TIME 70 9604
TEST_TIME 71 9706
TEST_TIME 72 9733
TEST_TIME 73 9657
TEST_TIME 74 9547
TEST_TIME 75 9471
TEST_TIME 76 9460
TEST_TIME 77 9401
TEST_TIME 78 9364
TEST_TIME 79 9321
TEST_TIME 80 9269
TEST_TIME 81 9296
TEST_TIME 82 9277
TEST_TIME 83 9260
TEST_TIME 84 9278
TEST_TIME 85 9322
TEST_TIME 86 9335
TEST_TIME 87 9452
TEST_TIME 88 9491
TEST_TIME 89 9521
TEST_TIME 90 9504
TEST_TIME 91 9506
TEST_TIME 92 9427
TEST_TIME 93 9368
TEST_TIME 94 9327
TEST_TIME 95 9312
TEST_TIME 96 9281
TEST_TIME 97 9254
TEST_TIME 98 9242
TEST_TIME 99 9246
TEST_TIME 100 9216
TEST_TIME 101 9235
TEST_TIME 102 9231
TEST_TIME 103 9203
TEST_TIME 104 9191
TEST_TIME 105 9226
TEST_TIME 106 9194
TEST_TIME 107 9282
TEST_TIME 108 9307
TEST_TIME 109 9321
TEST_TIME 110 9342
TEST_TIME 111 9311
TEST_TIME 112 9336
TEST_TIME 113 9306
TEST_TIME 114 9242
TEST_TIME 115 9194
TEST_TIME 116 9187
TEST_TIME 117 9138
TEST_TIME 118 9131
TEST_TIME 119 9115
TEST_TIME 120 9081

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-07-2022 04:39:23 to 11-07-2022 04:39:26.

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 1514364 1514364 0 0.00 0 33875 14 0 0
doc_list_biud 1514358 1514358 0 0.00 0 72358 44 0 0
sp_add_invoice_to_stock 5103 4974 129 2.52 46 220062 26519 0 129
sp_cancel_adding_invoice 5438 5438 0 0.00 6 132731 1325 0 0
sp_cancel_client_order 1758 1758 0 0.00 5 14059 404 0 0
sp_cancel_customer_reserve 539694 539694 0 0.00 1 31379 107 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 4 15916 150 0 0
sp_cancel_pay_to_supplier 370 370 0 0.00 3 1815 112 0 0
sp_cancel_supplier_invoice 5995 5984 11 0.18 3 19254 1059 0 11
sp_cancel_supplier_order 396 396 0 0.00 20 1474532 186903 0 0
sp_cancel_write_off 9484 9484 0 0.00 2 9259 175 0 0
sp_client_order 20057 20057 0 0.00 5 33171 488 0 0
sp_customer_reserve 463395 463395 0 0.00 0 59887 252 0 0
sp_fill_shopping_cart 30863 30863 0 0.00 1 19537 86 0 0
sp_fill_shopping_cart_clo_res 463374 463374 0 0.00 0 14422 18 0 0
sp_get_clo_for_invoice 4940 4940 0 0.00 0 30696 280 0 0
sp_kill_cost_storno 15971 15971 0 0.00 0 9208 76 0 0
sp_kill_qstorno_ret_qs2qd 1095252 1095252 0 0.00 0 31364 46 0 0
sp_kill_qty_storno 577526 577526 0 0.00 0 72358 106 0 0
sp_lock_selected_doc 20404 20404 0 0.00 0 11390 9 0 0
sp_make_cost_storno 27424 27424 0 0.00 0 15486 84 0 0
sp_multiply_rows_for_pdistr 27424 27424 0 0.00 0 6716 38 0 0
sp_multiply_rows_for_qdistr 20057 20057 0 0.00 0 10456 192 0 0
sp_pay_from_customer 6881 6881 0 0.00 5 31142 189 0 0
sp_pay_to_supplier 5771 5771 0 0.00 6 19059 205 0 0
sp_payment_common 12651 12651 0 0.00 2 11802 46 0 0
sp_qd_handle_on_cancel_clo 1758 1758 0 0.00 0 14037 325 0 0
sp_qd_handle_on_invoice_upd_sts 10377 10377 0 0.00 0 72357 576 0 0
sp_qd_handle_on_reserve_upd_sts 9833 9833 0 0.00 0 17948 62 0 0
sp_reserve_write_off 9833 9833 0 0.00 8 23280 280 0 0
sp_supplier_invoice 5413 5413 0 0.00 9 147938 3405 0 0
sp_supplier_order 5405 5405 0 0.00 8 27412 1344 0 0
srv_aggregate_perf_data 120 120 0 0.00 2612 164246 14545 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 825 144264 33864 0 0
srv_find_qd_qs_mism 1039104 1039104 0 0.00 0 33872 10 0 0
srv_make_invnt_saldo 489 489 0 0.00 51 136216 3959 0 0
srv_make_money_saldo 937 937 0 0.00 1 10830 381 0 0
srv_recalc_idx_stat 5 5 0 0.00 29125 557797 311507 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 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 4977 4977 0 0.00 0 33074 962 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 6040 459 0 0
t$perf-norm:sp_cancel_client_order 1758 1758 0 0.00 0 1902 29 0 0
t$perf-norm:sp_cancel_customer_reserve 703 703 0 0.00 0 3383 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 709 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 370 370 0 0.00 0 2685 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 6109 53 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 96563 7472 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 1131 19 0 0
t$perf-norm:sp_client_order 20058 20058 0 0.00 0 5391 37 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 960 16 0 0
t$perf-norm:sp_pay_from_customer 6881 6881 0 0.00 0 5286 20 0 0
t$perf-norm:sp_pay_to_supplier 5771 5771 0 0.00 0 6998 21 0 0
t$perf-norm:sp_reserve_write_off 9833 9833 0 0.00 0 6239 21 0 0
t$perf-norm:sp_supplier_invoice 5413 5413 0 0.00 0 2006 29 0 0
t$perf-norm:sp_supplier_order 5405 5405 0 0.00 0 2844 33 0 0
t$perf-norm:srv_aggregate_perf_data 120 120 0 0.00 0 824 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 487 14 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 1279 14 0 0
t$perf-norm:srv_make_money_saldo 937 937 0 0.00 0 538 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 234 78 0 0
v_add_invoice_to_stock 10131 9873 258 2.54 0 15905 28 0 258
v_all_customers 36383 36383 0 0.00 0 72 4 0 0
v_all_suppliers 30134 30134 0 0.00 0 175 4 0 0
v_all_wares 40106 40106 0 0.00 0 73 4 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 2080 17 0 0
v_cancel_client_order 3516 3516 0 0.00 0 2795 13 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 3848 16 0 0
v_cancel_customer_reserve 1406 1406 0 0.00 0 1328 11 0 0
v_cancel_payment_to_supplier 740 740 0 0.00 0 1138 14 0 0
v_cancel_supplier_invoice 812 790 22 2.70 0 4031 32 0 22
v_cancel_supplier_order 792 792 0 0.00 0 689 13 0 0
v_cancel_write_off 1904 1904 0 0.00 0 4083 26 0 0
v_max_id_clo_ord 5402 5402 0 0.00 0 1848 5 0 0
v_max_id_clo_res 983 983 0 0.00 0 1972 19 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 4174 8 0 0
v_max_non_paid_invoice 1511 1511 0 0.00 0 1654 9 0 0
v_max_non_paid_realizn 3696 3696 0 0.00 0 922 7 0 0
v_min_id_clo_ord 5402 5402 0 0.00 0 1706 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 3848 17 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 5739 12 0 0
v_min_non_paid_invoice 5771 5771 0 0.00 0 4940 19 0 0
v_min_non_paid_realizn 6880 6880 0 0.00 0 5009 16 0 0
v_our_firm 10084 10084 0 0.00 0 66 5 0 0
v_reserve_write_off 19666 19666 0 0.00 0 4451 10 0 0
x_lock_depdocs_on_canc_invoice 11027 11027 0 0.00 0 22579 126 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 8533 396 0 0
x_make_qstorno_1000_1200 5405 5405 0 0.00 3 27395 1175 0 0
x_make_qstorno_1200_2000 5413 5413 0 0.00 4 147913 3167 0 0
x_make_qstorno_2100_3300 463394 463394 0 0.00 0 59885 221 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-07-2022 04:39:27 to 11-07-2022 04:39:31.

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
02:07:22 2 8192000000 8798335184 8809402368 4385616 0.053 2 1 2 0 4485008 63616 35776
02:08:23 18729 8192000000 9727972672 10027786240 803499408 8.932 100 87 174 0 832249696 7515424 21234864
02:09:46 19555 8192000000 9761675696 10070278144 840734816 9.307 100 90 180 0 874191936 12220544 21236576
02:11:09 21584 8192000000 9805149264 10123083776 866618768 9.566 100 86 172 0 904596864 18072496 19905600
02:12:34 7886 8192000000 9862287040 10195042304 917298912 10.069 100 80 161 0 971849776 36985168 17565696
02:13:49 7318 8192000000 9910098336 10255237120 961812496 10.507 100 84 168 0 1025532912 45862400 17858016
02:14:58 2337 8192000000 9987555760 10343120896 996707616 10.847 100 91 181 0 1101998112 87305088 17985408
02:16:03 961 8192000000 9994232208 10359349248 1030229056 11.171 100 95 180 0 1113285552 65411024 17645472
02:17:06 893 8192000000 10016245520 10386608128 1044306976 11.306 100 95 176 1 1132489120 70320960 17861184
02:18:08 919 8192000000 10028007568 10403102720 1064928800 11.504 100 97 187 2 1149171984 65262160 18981024
02:19:10 990 8192000000 10048522912 10433028096 1079163296 11.639 100 91 177 2 1167671776 70165040 18343440
02:20:12 6790 8192000000 10051410704 10429640704 1080556064 11.653 100 82 161 2 1170855776 74275616 16024096
02:21:19 1012 8192000000 10056296992 10440753152 1085804224 11.703 100 95 179 0 1179541680 75298768 18438688
02:22:22 1580 8192000000 10050035840 10442407936 1090924128 11.751 100 82 153 1 1174414752 67111728 16378896
02:23:24 919 8192000000 10044388544 10433454080 1091973152 11.761 100 94 169 2 1165521360 56100480 17447728
02:24:26 2415 8192000000 10045980160 10438946816 1096634112 11.806 100 75 141 4 1166526832 55476384 14416336
02:25:29 951 8192000000 10071454640 10469953536 1100412352 11.842 100 93 177 1 1191325536 72085712 18827472
02:26:32 1462 8192000000 10055727696 10450804736 1100990160 11.847 100 84 159 2 1175725456 58453376 16281920
02:27:34 1072 8192000000 10054320896 10446737408 1099884576 11.837 100 97 185 2 1178865808 59438032 19543200
02:28:36 1070 8192000000 10051027824 10438492160 1092369152 11.765 100 97 182 0 1174333744 64618944 17345648
02:29:39 1391 8192000000 10029827872 10414804992 1079604656 11.644 100 94 177 1 1150168848 52606624 17957568
02:30:42 1167 8192000000 10045973392 10433736704 1083633584 11.682 100 93 177 3 1169831904 67825376 18372944
02:31:44 873 8192000000 9998010896 10377433088 1059477520 11.451 100 90 162 0 1121601536 44722016 17402000
02:32:46 851 8192000000 9978478912 10353188864 1027891664 11.148 99 85 169 1 1099671408 54435216 17344528
02:33:51 1592 8192000000 10004501648 10380218368 1026416800 11.134 100 84 164 1 1129610544 87238096 15955648
02:34:53 1044 8192000000 9982235936 10351390720 1016374032 11.037 99 95 180 1 1101140576 65783264 18983280
02:35:55 3757 8192000000 9993555904 10380161024 1045109840 11.314 100 71 138 1 1115021856 55324304 14587712
02:36:59 1735 8192000000 9982397328 10363981824 1046170640 11.324 100 78 151 1 1105159216 43641600 15346976
02:38:05 10128 8192000000 10010831440 10390585344 1065224160 11.506 100 73 136 3 1126965984 47668800 14073024
02:39:24 1458 8192000000 10043852672 10427662336 1079805120 11.646 100 96 179 2 1162062784 64103152 18154512
02:40:31 1981 8192000000 10040266976 10429542400 1088627328 11.730 100 75 142 2 1162577648 59530400 14419920
02:41:35 1497 8192000000 10076307024 10464747520 1100182048 11.839 100 91 176 2 1198402352 77451072 20769232
02:42:42 24280 8192000000 10108966560 10505953280 1111661824 11.948 100 61 118 0 1222340000 98075568 12602608
02:44:12 2881 8192000000 10158177504 10552111104 1125334816 12.077 100 83 159 0 1269701856 126932048 17434992
02:45:19 3534 8192000000 10177455776 10573271040 1124031984 12.065 99 82 157 1 1290467648 150177136 16258528
02:46:28 1325 8192000000 10239280752 10639728640 1142796000 12.242 100 85 165 0 1343074336 181835296 18443040
02:47:33 1239 8192000000 10278065376 10673541120 1156684464 12.372 100 94 186 1 1390508208 213065040 20758704
02:48:38 1134 8192000000 10275325376 10673233920 1162310432 12.425 100 98 185 0 1384308192 202443968 19553792
02:49:45 1818 8192000000 10322829136 10724638720 1172560016 12.521 100 97 185 0 1434950448 241648032 20742400
02:50:50 2917 8192000000 10314290256 10729521152 1168191552 12.480 99 85 168 1 1435150736 247363104 19596080
02:51:59 2575 8192000000 10356555056 10783539200 1186832288 12.654 100 81 158 0 1471027984 265690144 18505552
02:53:07 863 8192000000 10374509008 10804092928 1191158304 12.694 100 86 169 2 1487364240 275893152 20312784
02:54:12 1328 8192000000 10402217840 10819293184 1198554640 12.763 100 91 169 3 1507363280 288404064 20404576
02:55:16 866 8192000000 10454761296 10876788736 1203920640 12.813 100 92 180 1 1563719232 337587440 22211152
02:56:22 913 8192000000 10484811072 10890674176 1212516240 12.892 100 94 180 0 1588481056 354806112 21158704
02:57:28 891 8192000000 10526840240 10943635456 1205401536 12.826 100 96 186 2 1635282912 407910320 21971056
02:58:31 924 8192000000 10547740288 10961776640 1193982608 12.720 100 99 194 0 1651495728 434357760 23155360
02:59:34 1159 8192000000 10564309856 11008999424 1192232032 12.704 100 95 190 0 1673060336 458008160 22820144
03:00:41 1310 8192000000 10567715520 10983522304 1179171904 12.582 100 96 191 0 1671113888 468600960 23341024
03:01:43 824 8192000000 10582964128 10990837760 1157834144 12.383 100 97 185 1 1684521264 503542704 23144416
03:02:48 964 8192000000 10634146096 11053506560 1166613888 12.465 100 95 185 1 1735323632 545220752 23488992
03:03:50 6951 8192000000 10605864576 11112448000 1169498944 12.492 100 72 141 1 1730979456 541497104 19983408
03:05:13 11271 8192000000 10495052368 10954604544 1151980464 12.328 99 49 95 1 1589794816 424808592 13005760
03:06:32 17244 8192000000 10485567872 10942304256 1152894016 12.337 100 45 90 0 1581080400 416668720 11517664
03:08:08 22222 8192000000 10479361456 10957234176 1163343680 12.435 100 33 66 0 1608335184 436219872 8771632
03:10:02 30013 8192000000 10429775904 10898210816 1155736720 12.363 100 31 60 0 1576895408 413604336 7554352
03:11:36 43469 8192000000 10138558800 10603139072 1130734464 12.128 99 15 29 0 1257015952 123170928 3110560
03:14:36 50347 8192000000 10132138208 10575159296 1114266816 11.973 96 37 74 1 1155794128 33853424 7673888
03:16:38 82668 8192000000 10178622384 10623250432 1146040592 12.272 99 31 60 1 1242170352 89993056 6136704
03:19:02 81817 8192000000 10127998480 10630582272 1135268656 12.171 99 35 66 0 1236682464 94099520 7314288
03:21:28 21480 8192000000 10111300592 10584961024 1155695584 12.363 100 43 82 2 1202553296 38899792 7957920
03:22:57 44208 8192000000 10077724336 10552999936 1122235600 12.048 98 27 54 0 1183505248 55536960 5732688
03:24:51 59037 8192000000 10116060256 10577256448 1142469024 12.239 99 18 34 1 1200589040 55010176 3109840
03:27:37 37628 8192000000 10191601552 10668470272 1151765152 12.326 100 15 30 0 1267815856 112326560 3724144
03:29:16 73221 8192000000 10105679024 10577825792 1095428192 11.794 95 17 34 1 1163899680 64457952 4013536
03:31:41 26480 8192000000 10066798832 10540392448 1127760720 12.100 99 28 46 1 1158439232 25695200 4983312
03:33:14 41082 8192000000 10065267408 10530152448 1102205792 11.859 97 29 55 1 1152289648 44985600 5098256
03:35:07 52008 8192000000 10069949488 10547400704 1126395600 12.087 100 11 22 0 1148954032 20397312 2161120
03:38:15 24563 8192000000 10106441872 10552627200 1124193728 12.067 99 34 68 0 1183462736 52389728 6879280
03:39:42 26333 8192000000 10126815680 10572644352 1115763312 11.987 98 34 68 0 1203281840 80617408 6901120
03:41:21 52188 8192000000 10100199072 10546524160 1095223824 11.792 97 23 45 0 1149192384 49502784 4465776
03:43:14 35771 8192000000 10131162112 10585579520 1134531456 12.164 99 37 73 0 1227755968 85812480 7412032
03:44:55 91244 8192000000 10147204112 10665091072 1106328624 11.898 96 25 50 0 1251450208 139608912 5512672
03:47:52 30224 8192000000 10112111264 10609848320 1112490272 11.956 98 26 52 0 1165413152 47603760 5319120
03:49:25 36546 8192000000 10095907808 10556538880 1106909808 11.903 98 44 88 1 1185845584 70435600 8500176
03:51:04 95243 8192000000 10085035792 10538209280 1113820640 11.969 98 43 87 0 1178491296 55669344 9001312
03:53:40 83821 8192000000 10096851072 10584842240 1126799136 12.091 100 8 16 0 1200645536 72051344 1795056
03:56:04 35794 8192000000 10106250784 10560258048 1108044288 11.914 99 24 44 0 1182731520 70425664 4261568
03:57:41 56717 8192000000 10115763424 10579935232 1089313712 11.736 96 27 55 0 1169807040 73829264 6664064
03:59:40 45267 8192000000 10160348528 10604986368 1134715888 12.166 99 29 58 0 1245654880 105121520 5817472
04:01:30 34246 8192000000 10169917152 10621190144 1150849536 12.317 100 31 62 0 1270070592 111809344 7411712
04:03:06 46253 8192000000 10151996544 10616864768 1134918544 12.168 99 21 43 0 1266219712 125653104 5648064
04:04:55 59147 8192000000 10107766512 10546196480 1128624800 12.108 99 10 20 0 1170752816 40228176 1899840
04:06:54 39497 8192000000 10133062848 10582388736 1140183888 12.217 99 23 47 0 1197862448 53083696 4594864
04:08:34 96283 8192000000 10095497808 10535976960 1145387088 12.266 100 38 76 0 1186993264 33646576 7959600
04:11:11 2772 8192000000 10113636352 10549256192 1155164496 12.358 100 61 115 0 1200552544 34837904 10550144
04:12:16 69580 8192000000 10121761632 10601746432 1146633664 12.278 99 34 67 0 1232678672 78956848 7088160
04:14:26 69258 8192000000 10135145024 10615468032 1157947968 12.384 100 12 24 0 1240962416 80600352 2414096
04:17:39 52201 8192000000 10088229568 10587832320 1142796448 12.242 98 21 42 0 1205471392 58533952 4140992
04:19:31 32481 8192000000 10150781600 10618691584 1166369936 12.463 100 27 54 0 1228439328 56265520 5803872
04:21:11 70402 8192000000 10197414464 10671419392 1144803152 12.261 98 27 54 0 1256103200 105190736 6109312
04:23:38 41128 8192000000 10179951104 10640760832 1125994432 12.084 98 35 69 0 1230595216 97820912 6779872
04:25:28 22691 8192000000 10085113392 10523230208 1128912496 12.111 99 44 78 2 1173328448 36107520 8308432
04:26:53 52333 8192000000 10120706656 10578382848 1109900656 11.931 97 40 78 1 1171479072 53927712 7650704
04:28:48 26506 8192000000 10118116384 10565783552 1107356288 11.907 97 34 66 1 1182909408 68880928 6672192
04:30:15 144264 8192000000 10123410960 10567016448 1114155808 11.972 98 28 56 0 1153299040 33194272 5948960
04:33:40 79462 8192000000 10125190048 10605854720 1115539280 11.985 98 12 24 0 1199255840 80745136 2971424
04:36:00 50048 8192000000 10055889024 10532982784 1081754112 11.664 96 10 21 0 1141535808 57544320 2237376

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-07-2022 04:39:33 to 11-07-2022 04:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 11-07-2022 04:39:50 to 11-07-2022 04:39:52.

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          219366
MON$OLDEST_ACTIVE               220423
MON$OLDEST_SNAPSHOT             220423
MON$NEXT_TRANSACTION            220423
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                       2081312
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

Database statistics

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

Database validation

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

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

--- start of diff output ---
--- end of diff output ---

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

11.07.22 04:39:53. 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-11 04:39:56 No severe PSQL-related problems occured
11.07.22 04:39:57. End of report.