Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.05.2022 07:48
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 16270572 kB
MemAvailable 31496368 kB
Buffers 0 kB
Cached 15422140 kB
SwapCached 2540 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-06 07:47:00
DTS_END                         2022-05-06 07:47:00.4390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56662
TRN_ID                          210042
ATT_ID                          640
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: 8448.78 28457 1013853
customer order: creation 157.90 495 18948
customer order: refuse 13.99 393 1679
order to supplier: creation 41.06 1500 4927
order to supplier: removal 3.23 197595 387
invoice (draft): creation 42.70 3455 5124
invoice (draft): removal 46.92 1106 5630
invoice accept: apply 38.57 28761 4628
invoice accept: cancel 42.38 1406 5085
customer reserve: creation 3593.72 270 431246
customer reserve: removal 4202.02 119 504242
realization accept: apply 75.66 287 9079
realization accept: cancel 72.45 176 8694
payment from customer: creation 54.19 202 6503
payment from customer: removal 5.10 160 612
payment to supplier: creation 44.04 225 5285
payment to supplier: removal 3.22 122 386
service: total inventory turnovers 3.80 4238 456
service: total monetary turnovers 7.07 417 848
service: aggregate perf. data 0.75 22387 90
service: refresh index statistics 0.03 305824 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-05-2022 07:48:27 to 06-05-2022 07:48:29.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7659
TEST_TIME 2 5956
TEST_TIME 3 6575
TEST_TIME 4 6710
TEST_TIME 5 6401
TEST_TIME 6 6446
TEST_TIME 7 6291
TEST_TIME 8 6592
TEST_TIME 9 6683
TEST_TIME 10 6753
TEST_TIME 11 6749
TEST_TIME 12 6743
TEST_TIME 13 6881
TEST_TIME 14 6839
TEST_TIME 15 6954
TEST_TIME 16 7149
TEST_TIME 17 7142
TEST_TIME 18 7233
TEST_TIME 19 7250
TEST_TIME 20 7262
TEST_TIME 21 7193
TEST_TIME 22 7418
TEST_TIME 23 7413
TEST_TIME 24 7458
TEST_TIME 25 7676
TEST_TIME 26 7950
TEST_TIME 27 8331
TEST_TIME 28 8710
TEST_TIME 29 8750
TEST_TIME 30 8990
TEST_TIME 31 9142
TEST_TIME 32 9318
TEST_TIME 33 9433
TEST_TIME 34 9889
TEST_TIME 35 10301
TEST_TIME 36 10725
TEST_TIME 37 10595
TEST_TIME 38 10550
TEST_TIME 39 10612
TEST_TIME 40 10471
TEST_TIME 41 10403
TEST_TIME 42 10251
TEST_TIME 43 10134
TEST_TIME 44 10030
TEST_TIME 45 9896
TEST_TIME 46 9773
TEST_TIME 47 9783
TEST_TIME 48 9708
TEST_TIME 49 9587
TEST_TIME 50 9685
TEST_TIME 51 9556
TEST_TIME 52 9492
TEST_TIME 53 9385
TEST_TIME 54 9253
TEST_TIME 55 9248
TEST_TIME 56 9178
TEST_TIME 57 9127
TEST_TIME 58 9181
TEST_TIME 59 9142
TEST_TIME 60 9063
TEST_TIME 61 8998
TEST_TIME 62 8894
TEST_TIME 63 8842
TEST_TIME 64 8860
TEST_TIME 65 8906
TEST_TIME 66 8898
TEST_TIME 67 8877
TEST_TIME 68 8857
TEST_TIME 69 8808
TEST_TIME 70 8798
TEST_TIME 71 8913
TEST_TIME 72 9008
TEST_TIME 73 9044
TEST_TIME 74 8996
TEST_TIME 75 9032
TEST_TIME 76 9021
TEST_TIME 77 9003
TEST_TIME 78 8952
TEST_TIME 79 8909
TEST_TIME 80 8865
TEST_TIME 81 8932
TEST_TIME 82 8868
TEST_TIME 83 8799
TEST_TIME 84 8787
TEST_TIME 85 8776
TEST_TIME 86 8710
TEST_TIME 87 8685
TEST_TIME 88 8659
TEST_TIME 89 8601
TEST_TIME 90 8641
TEST_TIME 91 8652
TEST_TIME 92 8640
TEST_TIME 93 8637
TEST_TIME 94 8623
TEST_TIME 95 8593
TEST_TIME 96 8618
TEST_TIME 97 8678
TEST_TIME 98 8669
TEST_TIME 99 8776
TEST_TIME 100 8750
TEST_TIME 101 8793
TEST_TIME 102 8769
TEST_TIME 103 8803
TEST_TIME 104 8778
TEST_TIME 105 8713
TEST_TIME 106 8668
TEST_TIME 107 8642
TEST_TIME 108 8603
TEST_TIME 109 8596
TEST_TIME 110 8573
TEST_TIME 111 8630
TEST_TIME 112 8614
TEST_TIME 113 8617
TEST_TIME 114 8595
TEST_TIME 115 8569
TEST_TIME 116 8541
TEST_TIME 117 8541
TEST_TIME 118 8550
TEST_TIME 119 8532
TEST_TIME 120 8472

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 06-05-2022 07:48:30 to 06-05-2022 07:48:34.

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 1411825 1411825 0 0.00 0 16955 15 0 0
doc_list_biud 1411805 1411805 0 0.00 0 30245 48 0 0
sp_add_invoice_to_stock 4710 4628 82 1.74 49 260050 28762 0 82
sp_cancel_adding_invoice 5085 5085 0 0.00 5 89161 1407 0 0
sp_cancel_client_order 1679 1679 0 0.00 7 7452 393 0 0
sp_cancel_customer_reserve 504242 504242 0 0.00 1 30268 119 0 0
sp_cancel_pay_from_customer 612 612 0 0.00 3 6008 160 0 0
sp_cancel_pay_to_supplier 386 386 0 0.00 4 1384 123 0 0
sp_cancel_supplier_invoice 5638 5630 8 0.14 2 17693 1106 0 8
sp_cancel_supplier_order 387 387 0 0.00 10 1485642 197596 0 0
sp_cancel_write_off 8694 8694 0 0.00 2 10717 176 0 0
sp_client_order 18948 18948 0 0.00 6 23440 496 0 0
sp_customer_reserve 431246 431246 0 0.00 1 75321 270 0 0
sp_fill_shopping_cart 28981 28981 0 0.00 1 9996 87 0 0
sp_fill_shopping_cart_clo_res 431214 431214 0 0.00 0 19073 22 0 0
sp_get_clo_for_invoice 4588 4588 0 0.00 0 33021 380 0 0
sp_kill_cost_storno 14777 14777 0 0.00 0 7592 89 0 0
sp_kill_qstorno_ret_qs2qd 1023195 1023195 0 0.00 0 29676 51 0 0
sp_kill_qty_storno 539380 539380 0 0.00 0 30243 117 0 0
sp_lock_selected_doc 19006 19006 0 0.00 0 8228 7 0 0
sp_make_cost_storno 25454 25454 0 0.00 0 16811 96 0 0
sp_multiply_rows_for_pdistr 25451 25451 0 0.00 0 7667 43 0 0
sp_multiply_rows_for_qdistr 18947 18947 0 0.00 0 19456 175 0 0
sp_pay_from_customer 6503 6503 0 0.00 6 14489 203 0 0
sp_pay_to_supplier 5285 5285 0 0.00 7 16670 226 0 0
sp_payment_common 11787 11787 0 0.00 2 7830 49 0 0
sp_qd_handle_on_cancel_clo 1679 1679 0 0.00 1 7431 326 0 0
sp_qd_handle_on_invoice_upd_sts 9670 9670 0 0.00 0 29711 609 0 0
sp_qd_handle_on_reserve_upd_sts 9078 9078 0 0.00 0 20417 65 0 0
sp_reserve_write_off 9079 9079 0 0.00 7 20601 288 0 0
sp_supplier_invoice 5124 5124 0 0.00 9 97058 3455 0 0
sp_supplier_order 4927 4927 0 0.00 6 57918 1501 0 0
srv_aggregate_perf_data 90 90 0 0.00 2529 224279 22388 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 837 146626 36246 0 0
srv_find_qd_qs_mism 969222 969222 0 0.00 0 15164 11 0 0
srv_make_invnt_saldo 456 456 0 0.00 105 117520 4239 0 0
srv_make_money_saldo 848 848 0 0.00 1 16081 418 0 0
srv_recalc_idx_stat 4 4 0 0.00 158102 453990 305825 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4630 4630 0 0.00 1 23672 1057 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 1 7332 510 0 0
t$perf-norm:sp_cancel_client_order 1679 1679 0 0.00 0 1403 31 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 3741 37 0 0
t$perf-norm:sp_cancel_pay_from_customer 612 612 0 0.00 0 28734 61 0 0
t$perf-norm:sp_cancel_pay_to_supplier 386 386 0 0.00 0 866 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 742 31 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 0 87986 8675 0 0
t$perf-norm:sp_cancel_write_off 904 904 0 0.00 0 5117 23 0 0
t$perf-norm:sp_client_order 18949 18949 0 0.00 0 10100 42 0 0
t$perf-norm:sp_customer_reserve 941 941 0 0.00 0 1413 16 0 0
t$perf-norm:sp_pay_from_customer 6503 6503 0 0.00 0 16374 24 0 0
t$perf-norm:sp_pay_to_supplier 5286 5286 0 0.00 0 33068 33 0 0
t$perf-norm:sp_reserve_write_off 9079 9079 0 0.00 0 7941 24 0 0
t$perf-norm:sp_supplier_invoice 5124 5124 0 0.00 0 6760 35 0 0
t$perf-norm:sp_supplier_order 4927 4927 0 0.00 0 31018 43 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 1827 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 195 7 0 0
t$perf-norm:srv_make_invnt_saldo 456 456 0 0.00 0 19286 84 0 0
t$perf-norm:srv_make_money_saldo 848 848 0 0.00 0 284 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 139 58 0 0
v_add_invoice_to_stock 9324 9160 164 1.75 0 1805 22 0 164
v_all_customers 34558 34558 0 0.00 0 60 4 0 0
v_all_suppliers 27950 27950 0 0.00 0 231 4 0 0
v_all_wares 37880 37880 0 0.00 0 63 4 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 478 13 0 0
v_cancel_client_order 3358 3358 0 0.00 0 1664 9 0 0
v_cancel_customer_prepayment 1224 1224 0 0.00 0 264 9 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 1 1931 12 0 0
v_cancel_payment_to_supplier 772 772 0 0.00 0 617 12 0 0
v_cancel_supplier_invoice 756 740 16 2.11 0 6189 34 0 16
v_cancel_supplier_order 774 774 0 0.00 0 732 11 0 0
v_cancel_write_off 1808 1808 0 0.00 0 2778 22 0 0
v_max_id_clo_ord 4918 4918 0 0.00 0 483 5 0 0
v_max_id_clo_res 941 941 0 0.00 0 2895 15 0 0
v_max_id_ord_sup 5117 5117 0 0.00 0 2932 7 0 0
v_max_non_paid_invoice 1343 1343 0 0.00 0 4616 11 0 0
v_max_non_paid_realizn 3448 3448 0 0.00 0 2777 9 0 0
v_min_id_clo_ord 4918 4918 0 0.00 0 3491 8 0 0
v_min_id_clo_res 941 941 0 0.00 0 1297 9 0 0
v_min_id_ord_sup 5117 5117 0 0.00 0 8477 11 0 0
v_min_non_paid_invoice 5283 5283 0 0.00 0 7447 18 0 0
v_min_non_paid_realizn 6503 6503 0 0.00 0 7813 22 0 0
v_our_firm 9428 9428 0 0.00 0 50 4 0 0
v_reserve_write_off 18154 18154 0 0.00 0 2775 9 0 0
x_lock_depdocs_on_canc_invoice 10345 10345 0 0.00 0 15363 147 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 17485 459 0 0
x_make_qstorno_1000_1200 4926 4926 0 0.00 2 57597 1320 0 0
x_make_qstorno_1200_2000 5124 5124 0 0.00 5 90264 3210 0 0
x_make_qstorno_2100_3300 431246 431246 0 0.00 0 75320 234 0 0

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

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:17:04 8 8192000000 8800818384 8813072384 4478816 0.054 2 1 2 0 4578208 63616 35776
05:18:05 34172 8192000000 9727775984 10029576192 803945120 8.936 100 92 184 1 834995776 8767312 22283344
05:19:43 25856 8192000000 9773174528 10084192256 845151584 9.351 100 89 178 0 881607424 15551840 20904000
05:21:28 12904 8192000000 9818835984 10143371264 867489552 9.575 100 80 160 0 927456784 41519408 18447824
05:22:51 9274 8192000000 9867084352 10209902592 920427008 10.100 100 75 146 2 986796672 49893200 16476464
05:24:04 2244 8192000000 9925701296 10274877440 974666896 10.632 100 85 160 1 1036432816 45347920 16418000
05:25:08 1144 8192000000 9993760000 10350129152 1010766000 10.983 100 92 174 4 1110907472 82344816 17796656
05:26:12 1519 8192000000 10019624320 10383937536 1037789456 11.243 100 97 191 1 1135997584 79563056 18645072
05:27:15 1368 8192000000 10002477344 10377023488 1048338768 11.345 100 88 163 0 1123913312 57944960 17629584
05:28:17 1126 8192000000 10040575952 10414931968 1055064432 11.409 100 96 187 0 1162768720 88241152 19463136
05:29:20 1050 8192000000 10052570784 10433556480 1062203312 11.478 100 94 186 0 1173720624 91876352 19640960
05:30:22 13063 8192000000 10019876864 10404409344 1067570688 11.529 100 59 118 0 1141391104 62427440 11392976
05:31:36 3197 8192000000 10011407536 10389176320 1074516944 11.595 100 89 166 0 1134930416 44719664 15693808
05:32:40 1149 8192000000 10040874384 10426007552 1079921712 11.647 100 84 163 0 1164615408 68817184 15876512
05:33:42 1404 8192000000 10057292992 10449227776 1084922640 11.694 100 79 156 0 1179979008 78972528 16083840
05:34:51 1105 8192000000 10056891728 10448515072 1094943840 11.790 100 92 164 2 1180122832 68919040 16259952
05:35:54 965 8192000000 10069313792 10457636864 1094460624 11.785 100 91 171 1 1193213456 81115456 17637376
05:36:55 1189 8192000000 10043400528 10431737856 1093049008 11.772 100 97 191 1 1167437296 54891040 19497248
05:37:57 1237 8192000000 10041547456 10429198336 1085122192 11.696 100 95 183 0 1165335504 59760912 20452400
05:39:00 1138 8192000000 10058208144 10448670720 1083899872 11.685 100 84 163 1 1179854320 79185056 16769392
05:40:02 6474 8192000000 10013698816 10395213824 1064304016 11.498 99 55 104 1 1118860128 44395984 10160128
05:41:09 1109 8192000000 10010693328 10393333760 1047770800 11.339 100 81 151 3 1129945056 65832144 16342112
05:42:11 1174 8192000000 9997687936 10382221312 1053641936 11.396 100 83 157 1 1125894544 56576720 15675888
05:43:13 1630 8192000000 9992376672 10367737856 1033358688 11.201 99 84 159 0 1114649920 63287200 18004032
05:44:16 2041 8192000000 9969922768 10342535168 1024405008 11.115 100 77 144 3 1093044416 54193344 14446064
05:45:20 1272 8192000000 9962269376 10339225600 1034679440 11.213 100 80 158 0 1082619024 32053776 15885808
05:46:23 11434 8192000000 9965515136 10344452096 1028060128 11.150 99 75 141 3 1084428704 41393136 14975440
05:47:40 7275 8192000000 10000358384 10375741440 1056076896 11.419 100 93 171 2 1122146928 49585792 16484240
05:48:59 3586 8192000000 10057090944 10435645440 1078068608 11.629 100 83 150 0 1178781856 85700736 15012512
05:50:29 1932 8192000000 10095530672 10478829568 1096840208 11.808 100 90 168 1 1213811440 97592208 19379024
05:51:36 1361 8192000000 10117818656 10510409728 1112380384 11.955 100 90 174 1 1245467792 113508752 19578656
05:52:42 5485 8192000000 10176958688 10563506176 1120480256 12.032 100 74 144 1 1291098304 155918128 14699920
05:53:53 13735 8192000000 10172332416 10573561856 1123305584 12.058 99 59 111 2 1283022528 147682752 12034192
05:55:16 897 8192000000 10210192352 10620149760 1151602912 12.325 100 92 176 1 1325725056 153438752 20683392
05:56:21 1191 8192000000 10227068336 10623127552 1149548960 12.305 100 78 151 2 1342871376 176674896 16647520
05:57:28 829 8192000000 10263573744 10661634048 1155391904 12.360 100 96 190 0 1377995888 200389056 22214928
05:58:35 1638 8192000000 10330062336 10732138496 1163581312 12.437 100 95 182 1 1438697456 255058432 20057712
05:59:43 1179 8192000000 10308950176 10726965248 1172598960 12.521 100 82 155 0 1418007296 228056832 17351504
06:00:48 1153 8192000000 10356039424 10756407296 1174402240 12.538 100 97 193 1 1468721216 273298976 21020000
06:01:53 2177 8192000000 10384164448 10799845376 1178778448 12.579 100 94 183 1 1493897088 292669936 22448704
06:02:59 1094 8192000000 10371820240 10793193472 1177678048 12.569 100 95 185 0 1482409408 283069968 21661392
06:04:05 2994 8192000000 10376766816 10791526400 1160455616 12.408 99 82 159 0 1487468704 308216816 18796272
06:05:15 1717 8192000000 10417817744 10842251264 1194458384 12.725 100 93 182 1 1528148464 310245264 23444816
06:06:19 1100 8192000000 10428069904 10859986944 1201468736 12.790 100 90 178 0 1536230896 313955872 20806288
06:07:28 1221 8192000000 10462546816 10872836096 1193829472 12.719 100 99 199 0 1570013136 353299440 22884224
06:08:32 873 8192000000 10499417744 10934595584 1200052976 12.777 100 91 176 0 1605622320 383776832 21792512
06:09:38 911 8192000000 10540690192 10951905280 1187030416 12.656 100 96 190 0 1647362032 436208304 24123312
06:10:41 1338 8192000000 10587293792 10996015104 1187005088 12.655 100 98 189 1 1689512320 478999104 23508128
06:11:47 855 8192000000 10591899168 11042631680 1185277008 12.639 100 98 190 0 1696059792 487267696 23515088
06:12:51 2599 8192000000 10585171952 11041107968 1194252336 12.723 100 78 157 3 1692890224 478530128 20107760
06:13:56 1396 8192000000 10569319744 11072114688 1190177312 12.685 100 73 144 1 1674171920 465101760 18892848
06:15:03 13879 8192000000 10530503072 10989637632 1186430736 12.650 100 50 100 0 1644954576 445468512 13055328
06:16:25 15471 8192000000 10499249792 10975281152 1166575344 12.465 99 41 80 0 1590814896 412950112 11289440
06:17:44 12001 8192000000 10462960496 10968469504 1177367312 12.566 100 41 80 2 1555201440 367611440 10222688
06:18:59 51904 8192000000 10451209152 11004346368 1166781744 12.467 99 27 54 0 1573849424 400164496 6903184
06:21:20 24181 8192000000 10315845040 10784669696 1127081104 12.094 99 28 54 3 1424614208 290033568 7499536
06:22:55 29317 8192000000 10152606368 10625339392 1129709968 12.119 99 29 58 0 1249678464 113351200 6617296
06:25:02 56719 8192000000 10142071648 10613108736 1145133328 12.264 100 36 72 0 1252426576 99105920 8187328
06:27:10 50649 8192000000 10073433840 10541879296 1112011600 11.951 98 14 28 0 1150584416 35382720 3190096
06:29:53 45578 8192000000 10065112496 10510462976 1131106016 12.132 100 28 52 0 1162209936 25631120 5472800
06:31:40 48717 8192000000 10102894080 10547822592 1148825584 12.298 100 18 36 0 1203837328 51403728 3608016
06:33:39 80004 8192000000 10101710624 10580365312 1090709856 11.749 95 10 20 0 1165528592 72728256 2090480
06:36:05 146626 8192000000 10056574064 10554187776 1110683536 11.939 98 21 42 1 1202356528 86546928 5126064
06:39:33 64381 8192000000 10065056608 10496135168 1092972656 11.771 96 43 86 0 1135285392 33718512 8594224
06:41:53 3491 8192000000 10073829872 10507534336 1132927168 12.149 100 56 103 2 1178540288 34835360 10777760
06:43:00 34415 8192000000 10082380080 10530156544 1135640736 12.175 100 36 67 2 1196455440 53469504 7345200
06:44:35 49706 8192000000 10095524688 10561916928 1124480512 12.069 99 25 50 2 1211974400 81717792 5776096
06:46:25 89651 8192000000 10069967056 10536751104 1118596128 12.014 98 17 32 2 1147804368 26092224 3116016
06:49:00 91498 8192000000 10076453824 10548465664 1125978640 12.083 99 23 47 1 1184284576 53052432 5253504
06:51:41 85885 8192000000 10076892912 10563592192 1142896704 12.243 100 15 31 0 1194005232 48077968 3030560
06:54:12 23038 8192000000 10078876976 10526396416 1121669072 12.043 99 35 71 1 1164309680 35287552 7353056
06:55:53 32401 8192000000 10126484704 10572726272 1119223872 12.020 98 37 72 1 1214894480 87848128 7822480
06:57:31 58402 8192000000 10137710144 10640969728 1125798400 12.082 99 28 56 0 1269846416 137591312 6456704
06:59:31 117934 8192000000 10060265648 10491166720 1063833456 11.493 94 15 30 3 1117927456 51267696 2826304
07:02:29 61551 8192000000 10076518064 10525466624 1075170480 11.601 95 36 70 0 1137107264 55254720 6682064
07:04:36 15087 8192000000 10096904992 10520809472 1112100304 11.952 100 49 96 1 1196509520 75029072 9380144
07:05:52 100579 8192000000 10128605456 10579988480 1114824304 11.978 99 38 73 0 1211377360 88770800 7782256
07:09:18 104558 8192000000 10055918336 10525052928 1106460560 11.899 98 16 32 1 1159507296 49662080 3384656
07:12:07 50920 8192000000 10030801552 10502897664 1107582800 11.910 99 13 24 1 1150007200 39128256 3296144
07:13:59 48837 8192000000 10041293248 10507784192 1100125696 11.839 98 13 26 1 1154694432 51630528 2938208
07:15:48 76164 8192000000 10052618688 10522697728 1085640880 11.701 98 9 18 0 1141460144 53982784 1836480
07:18:10 45036 8192000000 10044046688 10505555968 1115124272 11.981 100 11 22 0 1161528240 44153312 2250656
07:20:00 28585 8192000000 10068606528 10500034560 1107738048 11.911 98 30 61 2 1144258384 30429376 6090960
07:21:31 52460 8192000000 10143230416 10577334272 1106844400 11.903 97 27 54 1 1172345792 59342400 6158992
07:23:29 34249 8192000000 10160702208 10615103488 1127271280 12.096 99 32 64 1 1251120416 116700192 7148944
07:25:08 77305 8192000000 10134559408 10589929472 1112689088 11.958 98 25 50 0 1247621664 129315216 5617360
07:27:26 25984 8192000000 10109260560 10552475648 1126368944 12.087 100 32 62 1 1198361360 65522432 6469984
07:28:56 18980 8192000000 10094888832 10519552000 1118859120 12.016 99 34 68 3 1186150832 60453472 6838240
07:30:17 28474 8192000000 10090618704 10519797760 1116865072 11.997 99 48 96 0 1185726480 59667904 9193504
07:31:47 141721 8192000000 10091151872 10533744640 1111464960 11.946 98 42 84 0 1189956240 70228608 8262672
07:35:14 55527 8192000000 10138463504 10594865152 1126554016 12.089 100 13 26 0 1247614720 118068976 2991728
07:37:11 74821 8192000000 10081871600 10624401408 1124866720 12.073 100 16 32 0 1239282752 110550912 3865120
07:39:26 55163 8192000000 10072491648 10515951616 1132024256 12.140 100 25 50 0 1178958800 41875360 5059184
07:41:36 48085 8192000000 10132747792 10566975488 1137237024 12.190 98 22 44 0 1200630336 58149568 5243744
07:43:29 27307 8192000000 10196535232 10633781248 1166559040 12.465 100 48 92 1 1285177104 107780160 10837904
07:44:56 48380 8192000000 10213678944 10665816064 1128294400 12.105 96 33 66 0 1267061152 130538032 8228720
07:46:49 32743 8192000000 9243949632 9640452096 369039872 4.310 31 17 31 1 497545616 124127920 4377824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-05-2022 07:48:41 to 06-05-2022 07:48:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-05-2022 07:48:58 to 06-05-2022 07:49:00.

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          210334
MON$OLDEST_ACTIVE               210335
MON$OLDEST_SNAPSHOT             210335
MON$NEXT_TRANSACTION            210335
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                       2073632
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             754
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

06.05.22 07:49:01. 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-06 07:49:03 No severe PSQL-related problems occured
06.05.22 07:49:05. End of report.