Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.05.2022 10: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 16332532 kB
MemAvailable 31567100 kB
Buffers 0 kB
Cached 15406656 kB
SwapCached 3240 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-05-02 10:37:17
DTS_END                         2022-05-02 10:37:17.9640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56024
TRN_ID                          218245
ATT_ID                          653
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: 9094.76 22585 1091371
customer order: creation 164.12 447 19694
customer order: refuse 13.92 367 1670
order to supplier: creation 42.45 1350 5094
order to supplier: removal 3.60 157294 432
invoice (draft): creation 42.73 3187 5127
invoice (draft): removal 53.22 912 6386
invoice accept: apply 40.09 24096 4811
invoice accept: cancel 48.37 1090 5804
customer reserve: creation 3738.22 229 448586
customer reserve: removal 4659.02 97 559083
realization accept: apply 79.04 269 9485
realization accept: cancel 86.25 164 10350
payment from customer: creation 56.21 151 6745
payment from customer: removal 5.58 104 670
payment to supplier: creation 47.13 171 5655
payment to supplier: removal 3.54 106 425
service: total inventory turnovers 3.75 3841 450
service: total monetary turnovers 6.53 350 784
service: aggregate perf. data 0.95 16031 114
service: refresh index statistics 0.05 241442 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 02-05-2022 10:38:59 to 02-05-2022 10:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7442
TEST_TIME 2 5567
TEST_TIME 3 5637
TEST_TIME 4 5546
TEST_TIME 5 5407
TEST_TIME 6 5085
TEST_TIME 7 5296
TEST_TIME 8 5443
TEST_TIME 9 5860
TEST_TIME 10 5896
TEST_TIME 11 5985
TEST_TIME 12 6177
TEST_TIME 13 6497
TEST_TIME 14 6665
TEST_TIME 15 6607
TEST_TIME 16 6688
TEST_TIME 17 7369
TEST_TIME 18 7471
TEST_TIME 19 7793
TEST_TIME 20 7945
TEST_TIME 21 8169
TEST_TIME 22 8060
TEST_TIME 23 8625
TEST_TIME 24 8789
TEST_TIME 25 9130
TEST_TIME 26 9484
TEST_TIME 27 9974
TEST_TIME 28 10025
TEST_TIME 29 10052
TEST_TIME 30 10331
TEST_TIME 31 10816
TEST_TIME 32 10789
TEST_TIME 33 10655
TEST_TIME 34 10649
TEST_TIME 35 10618
TEST_TIME 36 10796
TEST_TIME 37 10689
TEST_TIME 38 10557
TEST_TIME 39 10625
TEST_TIME 40 10745
TEST_TIME 41 10639
TEST_TIME 42 10601
TEST_TIME 43 10386
TEST_TIME 44 10306
TEST_TIME 45 10221
TEST_TIME 46 10068
TEST_TIME 47 10097
TEST_TIME 48 10035
TEST_TIME 49 9970
TEST_TIME 50 9923
TEST_TIME 51 9889
TEST_TIME 52 9875
TEST_TIME 53 9803
TEST_TIME 54 9804
TEST_TIME 55 9805
TEST_TIME 56 9993
TEST_TIME 57 9913
TEST_TIME 58 9869
TEST_TIME 59 9857
TEST_TIME 60 9846
TEST_TIME 61 9846
TEST_TIME 62 9903
TEST_TIME 63 9969
TEST_TIME 64 10088
TEST_TIME 65 10074
TEST_TIME 66 10115
TEST_TIME 67 10025
TEST_TIME 68 9937
TEST_TIME 69 9840
TEST_TIME 70 9785
TEST_TIME 71 9740
TEST_TIME 72 9656
TEST_TIME 73 9609
TEST_TIME 74 9593
TEST_TIME 75 9558
TEST_TIME 76 9503
TEST_TIME 77 9521
TEST_TIME 78 9421
TEST_TIME 79 9430
TEST_TIME 80 9420
TEST_TIME 81 9387
TEST_TIME 82 9345
TEST_TIME 83 9307
TEST_TIME 84 9339
TEST_TIME 85 9312
TEST_TIME 86 9349
TEST_TIME 87 9410
TEST_TIME 88 9490
TEST_TIME 89 9469
TEST_TIME 90 9447
TEST_TIME 91 9491
TEST_TIME 92 9508
TEST_TIME 93 9493
TEST_TIME 94 9419
TEST_TIME 95 9372
TEST_TIME 96 9364
TEST_TIME 97 9338
TEST_TIME 98 9308
TEST_TIME 99 9270
TEST_TIME 100 9235
TEST_TIME 101 9270
TEST_TIME 102 9230
TEST_TIME 103 9235
TEST_TIME 104 9206
TEST_TIME 105 9210
TEST_TIME 106 9213
TEST_TIME 107 9173
TEST_TIME 108 9149
TEST_TIME 109 9176
TEST_TIME 110 9252
TEST_TIME 111 9342
TEST_TIME 112 9317
TEST_TIME 113 9366
TEST_TIME 114 9332
TEST_TIME 115 9298
TEST_TIME 116 9279
TEST_TIME 117 9242
TEST_TIME 118 9192
TEST_TIME 119 9182
TEST_TIME 120 9119

Performance per minute, chart


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

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 1504518 1504518 0 0.00 0 29747 12 0 0
doc_list_biud 1504511 1504511 0 0.00 0 30596 42 0 0
sp_add_invoice_to_stock 5028 4811 217 4.31 72 207798 24096 0 217
sp_cancel_adding_invoice 5804 5804 0 0.00 8 74593 1090 0 0
sp_cancel_client_order 1670 1670 0 0.00 6 11142 367 0 0
sp_cancel_customer_reserve 559083 559083 0 0.00 1 28549 97 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 4 3907 104 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 1081 107 0 0
sp_cancel_supplier_invoice 6401 6386 15 0.23 4 26558 912 0 15
sp_cancel_supplier_order 432 432 0 0.00 11 1445093 157295 0 0
sp_cancel_write_off 10350 10350 0 0.00 2 29748 164 0 0
sp_client_order 19694 19694 0 0.00 6 24944 447 0 0
sp_customer_reserve 448586 448586 0 0.00 1 108526 229 0 0
sp_fill_shopping_cart 29910 29910 0 0.00 1 16800 70 0 0
sp_fill_shopping_cart_clo_res 448564 448564 0 0.00 0 15004 18 0 0
sp_get_clo_for_invoice 4782 4782 0 0.00 0 28872 310 0 0
sp_kill_cost_storno 17249 17249 0 0.00 0 29745 70 0 0
sp_kill_qstorno_ret_qs2qd 1135334 1135334 0 0.00 0 28537 44 0 0
sp_kill_qty_storno 597991 597991 0 0.00 0 30594 99 0 0
sp_lock_selected_doc 19869 19869 0 0.00 0 8674 7 0 0
sp_make_cost_storno 26666 26666 0 0.00 0 25437 74 0 0
sp_multiply_rows_for_pdistr 26666 26666 0 0.00 0 8465 39 0 0
sp_multiply_rows_for_qdistr 19694 19694 0 0.00 0 22777 180 0 0
sp_pay_from_customer 6745 6745 0 0.00 5 9302 152 0 0
sp_pay_to_supplier 5655 5655 0 0.00 6 14419 172 0 0
sp_payment_common 12399 12399 0 0.00 2 6236 36 0 0
sp_qd_handle_on_cancel_clo 1670 1670 0 0.00 1 11134 305 0 0
sp_qd_handle_on_invoice_upd_sts 10585 10585 0 0.00 0 30594 466 0 0
sp_qd_handle_on_reserve_upd_sts 9485 9485 0 0.00 0 24714 71 0 0
sp_reserve_write_off 9485 9485 0 0.00 8 24749 269 0 0
sp_supplier_invoice 5127 5127 0 0.00 12 74079 3188 0 0
sp_supplier_order 5094 5094 0 0.00 8 81094 1350 0 0
srv_aggregate_perf_data 114 114 0 0.00 2244 156024 16031 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 870 159185 37436 0 0
srv_find_qd_qs_mism 1044513 1044513 0 0.00 0 17045 9 0 0
srv_make_invnt_saldo 450 450 0 0.00 46 80443 3842 0 0
srv_make_money_saldo 784 784 0 0.00 1 10809 351 0 0
srv_recalc_idx_stat 6 6 0 0.00 74168 360107 241443 0 0
t$perf-abend:sp_add_invoice_to_stock 217 217 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4817 4817 0 0.00 1 10496 731 0 0
t$perf-norm:sp_cancel_adding_invoice 398 398 0 0.00 1 6829 391 0 0
t$perf-norm:sp_cancel_client_order 1670 1670 0 0.00 0 5124 30 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 1171 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4756 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3474 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 9855 55 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 78138 5943 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 2230 18 0 0
t$perf-norm:sp_client_order 19695 19695 0 0.00 0 8851 33 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 4688 16 0 0
t$perf-norm:sp_pay_from_customer 6745 6745 0 0.00 0 11016 21 0 0
t$perf-norm:sp_pay_to_supplier 5655 5655 0 0.00 0 3911 17 0 0
t$perf-norm:sp_reserve_write_off 9485 9485 0 0.00 0 5298 18 0 0
t$perf-norm:sp_supplier_invoice 5127 5127 0 0.00 0 7719 30 0 0
t$perf-norm:sp_supplier_order 5094 5094 0 0.00 0 13302 32 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 2050 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 4874 76 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 3533 16 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 12117 23 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 3 185 71 0 0
v_add_invoice_to_stock 9984 9550 434 4.34 0 5451 23 0 434
v_all_customers 36016 36016 0 0.00 0 181 3 0 0
v_all_suppliers 28610 28610 0 0.00 0 158 3 0 0
v_all_wares 39384 39384 0 0.00 0 130 3 0 0
v_cancel_adding_invoice 796 796 0 0.00 0 296 12 0 0
v_cancel_client_order 3340 3340 0 0.00 0 5251 9 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 551 8 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 2314 12 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 711 8 0 0
v_cancel_supplier_invoice 792 762 30 3.78 0 942 22 0 30
v_cancel_supplier_order 864 864 0 0.00 0 690 8 0 0
v_cancel_write_off 1948 1948 0 0.00 0 3109 20 0 0
v_max_id_clo_ord 5091 5091 0 0.00 0 1727 4 0 0
v_max_id_clo_res 935 935 0 0.00 0 2136 17 0 0
v_max_id_ord_sup 5124 5124 0 0.00 0 1052 5 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 415 6 0 0
v_max_non_paid_realizn 3407 3407 0 0.00 0 4586 7 0 0
v_min_id_clo_ord 5091 5091 0 0.00 0 4225 8 0 0
v_min_id_clo_res 935 935 0 0.00 0 6508 15 0 0
v_min_id_ord_sup 5124 5124 0 0.00 0 6862 11 0 0
v_min_non_paid_invoice 5655 5655 0 0.00 0 3246 11 0 0
v_min_non_paid_realizn 6744 6744 0 0.00 0 6232 14 0 0
v_our_firm 10042 10042 0 0.00 0 42 4 0 0
v_reserve_write_off 18968 18968 0 0.00 0 4783 9 0 0
x_lock_depdocs_on_canc_invoice 11809 11809 0 0.00 0 12713 119 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 4579 310 0 0
x_make_qstorno_1000_1200 5094 5094 0 0.00 3 81064 1214 0 0
x_make_qstorno_1200_2000 5127 5127 0 0.00 5 74027 2989 0 0
x_make_qstorno_2100_3300 448586 448586 0 0.00 0 108525 201 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-05-2022 10:39:08 to 02-05-2022 10:39:12.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:21 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:22 25302 8192000000 9732719472 10036506624 812490624 9.023 100 88 176 0 840789120 7605184 20693312
08:09:57 11083 8192000000 9768940192 10078580736 843654192 9.336 100 90 180 0 877689120 12407712 21627216
08:11:22 21486 8192000000 9817581472 10141507584 874167072 9.642 100 84 168 1 921326304 28005632 19153600
08:12:48 8657 8192000000 9876908224 10214756352 925091456 10.146 100 81 162 0 991963904 49571568 17300880
08:13:58 4124 8192000000 9930705360 10277691392 965207488 10.540 100 91 181 0 1049952080 66080960 18663632
08:15:05 2222 8192000000 9979083984 10335543296 992497424 10.806 100 87 171 0 1103530608 93574912 17458272
08:16:09 947 8192000000 9973941312 10338947072 1026154256 11.131 100 90 173 0 1094718960 50122672 18442032
08:17:12 1037 8192000000 10031492880 10406367232 1045964624 11.322 100 95 181 1 1155174240 91023264 18186352
08:18:14 918 8192000000 10020465520 10401218560 1062603760 11.481 100 95 185 0 1141652144 61034272 18014112
08:19:16 5711 8192000000 10032280624 10407407616 1061373296 11.470 100 92 171 5 1146744432 70069744 15301392
08:20:22 2279 8192000000 10046080784 10428596224 1071356464 11.565 100 77 148 3 1160275232 74305280 14613488
08:21:26 1206 8192000000 10059667712 10444275712 1075840288 11.608 100 97 185 0 1180020256 85931680 18248288
08:22:29 1062 8192000000 10048720016 10434363392 1080361216 11.651 100 99 184 2 1172102896 73221472 18520208
08:23:31 992 8192000000 10054431952 10443530240 1081749792 11.664 100 97 182 1 1179432480 79234528 18448160
08:24:33 4626 8192000000 10062692256 10454880256 1084893264 11.694 100 73 141 2 1182333488 83998048 13442176
08:25:39 1282 8192000000 10053689904 10445049856 1090656064 11.749 100 92 179 3 1180281520 71150576 18474880
08:26:42 1240 8192000000 10075144320 10467008512 1099651520 11.834 100 96 181 1 1196734656 78651376 18431760
08:27:44 1211 8192000000 10061599984 10446745600 1078733536 11.635 99 94 182 0 1182359904 84870576 18755792
08:28:46 1106 8192000000 10054352144 10442629120 1081074944 11.658 100 87 171 2 1173519552 74522320 17922288
08:29:48 1204 8192000000 10006468144 10384867328 1052127008 11.381 99 91 172 1 1125134064 54851616 18155440
08:30:50 932 8192000000 10023567568 10404900864 1056751408 11.425 100 84 161 1 1147943696 74758384 16433904
08:31:52 1170 8192000000 10030959712 10415517696 1060192160 11.458 100 92 177 1 1154043936 75797936 18053840
08:32:55 977 8192000000 9972227952 10339192832 1028487312 11.154 100 86 169 1 1097013664 52545104 15981248
08:33:58 980 8192000000 9995638448 10368565248 1032832128 11.196 100 89 180 0 1119355072 68094336 18428608
08:35:02 1659 8192000000 9968955376 10348163072 1039749232 11.262 100 75 143 1 1097308064 42126656 15432176
08:36:05 3223 8192000000 10000779760 10383704064 1050419984 11.365 100 71 135 0 1127490112 63221904 13848224
08:37:09 9475 8192000000 10004259632 10390028288 1050258544 11.363 100 65 125 1 1128054800 65040112 12756144
08:38:25 18243 8192000000 10021452688 10401198080 1073848960 11.589 100 78 147 1 1141136912 52964608 14323344
08:39:59 11702 8192000000 10054181696 10446655488 1090884768 11.751 100 68 135 0 1173411552 67570576 14956208
08:41:13 5161 8192000000 10093198784 10486521856 1105136592 11.886 100 79 152 2 1212532688 90334224 17061872
08:42:25 15439 8192000000 10123437504 10519121920 1117779824 12.006 100 59 112 2 1247574624 117422256 12372544
08:43:47 4600 8192000000 10184091104 10587533312 1129723696 12.119 100 72 143 0 1308047072 162922592 15400784
08:44:57 6431 8192000000 10204736864 10614988800 1130827888 12.129 100 63 121 1 1321177984 176638848 13711248
08:46:12 3012 8192000000 10229437568 10646364160 1135986336 12.178 99 72 144 0 1335973568 183644240 16342992
08:47:20 1016 8192000000 10274615728 10671489024 1160712112 12.410 100 93 178 0 1389869744 208265104 20892528
08:48:24 857 8192000000 10334156528 10740039680 1174400416 12.538 100 95 187 1 1450261792 254574832 21286544
08:49:30 1358 8192000000 10321762656 10735599616 1179827728 12.589 100 80 157 1 1433753920 236161712 17764480
08:50:36 3329 8192000000 10364949984 10783195136 1184780288 12.635 100 76 150 0 1475995280 273471952 17743040
08:51:42 1445 8192000000 10360592016 10765070336 1172464240 12.520 100 86 161 1 1469124368 278267024 18393104
08:52:48 897 8192000000 10414961344 10826436608 1176843200 12.561 100 98 193 5 1522912112 322642192 23426720
08:53:53 934 8192000000 10418619632 10869850112 1190247712 12.686 100 85 169 0 1525372176 314420512 20703952
08:54:56 6601 8192000000 10396847552 10839814144 1185998464 12.646 100 56 104 2 1512336128 312961184 13376480
08:56:09 3982 8192000000 10424618544 10878713856 1187423840 12.659 100 62 122 0 1532422704 329462176 15536688
08:57:21 3219 8192000000 10412596656 10852040704 1180184160 12.592 100 65 123 0 1519362896 324557744 14620992
08:58:26 13480 8192000000 10399195920 10842640384 1176661248 12.559 100 34 68 0 1520351856 335019536 8671072
08:59:48 1166 8192000000 10441518848 10896605184 1178869840 12.580 100 91 181 2 1543880464 343689616 21321008
09:00:52 9766 8192000000 10381834096 10841522176 1164851040 12.449 100 44 86 0 1495719888 320027120 10841728
09:02:05 4654 8192000000 10370458096 10821144576 1159232784 12.396 100 52 104 0 1476519824 303960432 13326608
09:03:12 28300 8192000000 10301172688 10783105024 1147283456 12.284 99 32 64 0 1431390688 276261552 7845680
09:04:54 28939 8192000000 10226300944 10680737792 1143870192 12.252 99 22 44 0 1340864032 191252256 5741584
09:06:45 37962 8192000000 10206879312 10705965056 1135863888 12.177 100 12 24 1 1316738336 177616976 3257472
09:08:25 41314 8192000000 10038799264 10504019968 1076668512 11.616 96 14 25 2 1159695728 80475216 2552000
09:10:07 20400 8192000000 10046270976 10473951232 1116258784 11.992 99 49 92 5 1140207856 14525664 9423408
09:11:35 28638 8192000000 10090931344 10559463424 1134654160 12.165 100 13 26 0 1213123520 76037936 2431424
09:13:04 90865 8192000000 10077115216 10551320576 1097150512 11.811 96 18 34 3 1162200304 61818256 3231536
09:15:51 37178 8192000000 10128778752 10583973888 1121847632 12.044 99 8 16 2 1212004128 88224320 1932176
09:17:38 105702 8192000000 10085547872 10569560064 1125191792 12.076 99 8 16 0 1179688560 52902416 1594352
09:20:24 44873 8192000000 10080362000 10508259328 1125933120 12.083 99 33 64 3 1164427584 31406624 7087840
09:22:16 55322 8192000000 10076319168 10510876672 1103317088 11.869 98 25 49 2 1156448832 46971888 6159856
09:24:17 1203 8192000000 10140600400 10553999360 1139921744 12.215 100 81 162 2 1234674944 77627616 17125584
09:25:22 17950 8192000000 10157369856 10587799552 1138870368 12.205 100 40 77 3 1247932784 100208432 8853984
09:26:41 29800 8192000000 10207992048 10630721536 1146561088 12.277 100 36 71 0 1292003184 136229952 9212144
09:28:18 56077 8192000000 10198373072 10656301056 1148886368 12.299 100 23 46 3 1298901488 144197952 5817168
09:30:29 159183 8192000000 10133467904 10597675008 1128608704 12.108 98 24 48 0 1310616576 176394784 5613088
09:34:26 37412 8192000000 10132156640 10569195520 1137875744 12.196 99 28 56 0 1217561056 73541216 6144096
09:36:08 44596 8192000000 10197125056 10668093440 1151321664 12.322 99 19 38 1 1294244016 138538480 4383872
09:38:02 57320 8192000000 10227053808 10682728448 1159195424 12.396 99 39 78 1 1306826912 139267808 8363680
09:40:14 46308 8192000000 10166006192 10595864576 1131876784 12.139 98 45 88 1 1242242016 100285728 10079504
09:42:02 64314 8192000000 10128568944 10597531648 1159477984 12.398 100 23 46 0 1223201616 59261360 4462272
09:44:06 12909 8192000000 10127021776 10552926208 1159841568 12.402 100 60 115 2 1205296720 33102608 12352544
09:45:26 114107 8192000000 10093139040 10539208704 1132443344 12.144 97 32 62 1 1203925424 65149120 6332960
09:48:22 64919 8192000000 10113340192 10574266368 1119705920 12.024 97 13 26 0 1174165440 51754016 2705504
09:50:27 76354 8192000000 10127708496 10594406400 1131281056 12.133 98 19 38 3 1212541456 77258896 4001504
09:52:44 61990 8192000000 10107328528 10571960320 1131308400 12.134 98 12 24 0 1184307616 50420656 2578560
09:54:48 89035 8192000000 10106417584 10553106432 1114309216 11.973 98 20 40 0 1193692128 75020736 4362176
09:57:19 76158 8192000000 10069546224 10539094016 1099135152 11.829 97 7 14 1 1149636208 48959360 1541696
09:59:37 56890 8192000000 10123781696 10583871488 1116326256 11.992 98 24 48 1 1216204144 94450448 5427440
10:01:39 58980 8192000000 10159518976 10621849600 1113324160 11.964 97 28 56 0 1247314624 128522912 5467552
10:03:39 50445 8192000000 10154937888 10603667456 1095004096 11.790 97 32 64 0 1226884176 125259136 6620944
10:05:30 48590 8192000000 10082005136 10549743616 1092575248 11.767 97 32 64 0 1156270560 57255840 6439472
10:07:27 10651 8192000000 10106890448 10532638720 1125685184 12.081 100 51 102 1 1191972992 56583632 9704176
10:08:38 20310 8192000000 10122790704 10573090816 1113540864 11.966 99 47 94 0 1210117184 87145616 9430704
10:10:01 50510 8192000000 10103233584 10553196544 1101311856 11.850 99 37 74 1 1194651440 85983120 7356464
10:12:30 50762 8192000000 10088713216 10528104448 1097676976 11.816 99 30 57 1 1177354336 73866720 5810640
10:14:37 63563 8192000000 10041430720 10494627840 1091716784 11.759 98 14 28 0 1128914416 34678544 2519088
10:16:41 57578 8192000000 10088708928 10547679232 1131147888 12.132 100 28 55 0 1191850240 54733440 5968912
10:18:41 82488 8192000000 10077975760 10534305792 1096209056 11.802 97 22 44 0 1143387760 42814144 4364560
10:21:07 26577 8192000000 10105001584 10536779776 1113455008 11.965 99 34 68 1 1196652272 75889328 7307936
10:22:36 54531 8192000000 10129251648 10576650240 1100257104 11.840 96 32 64 3 1189209696 81753776 7198816
10:24:31 31065 8192000000 10195427728 10635571200 1149719440 12.307 100 46 90 1 1278565456 118937712 9908304
10:26:07 29336 8192000000 10246275392 10683031552 1153393552 12.341 100 44 88 0 1317358528 154464048 9500928
10:27:37 84493 8192000000 10079420080 10523484160 1099952624 11.837 97 11 22 0 1161244432 59049344 2242464
10:30:02 38967 8192000000 10144599072 10638848000 1139944352 12.215 100 30 60 0 1254575520 108603040 6028128
10:33:39 44579 8192000000 10063038736 10548899840 1134280608 12.162 100 10 19 1 1175832768 39113248 2438912
10:36:17 66632 8192000000 9179528304 9461760000 281528496 3.322 25 3 6 0 288100672 6219152 353024

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-05-2022 10:39:14 to 02-05-2022 10:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 217 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          218586
MON$OLDEST_ACTIVE               218587
MON$OLDEST_SNAPSHOT             218587
MON$NEXT_TRANSACTION            218587
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                       2072288
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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'

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