Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.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 14871548 kB
MemAvailable 30518804 kB
Buffers 0 kB
Cached 16854240 kB
SwapCached 2140 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.3
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-10-17 10:37:18
DTS_END                         2022-10-17 10:37:18.1080
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41740
TRN_ID                          216276
ATT_ID                          656
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: 9259.39 25144 1111127
customer order: creation 167.77 471 20132
customer order: refuse 14.15 485 1698
order to supplier: creation 44.47 1447 5336
order to supplier: removal 3.48 186250 417
invoice (draft): creation 44.92 3297 5390
invoice (draft): removal 51.25 1097 6150
invoice accept: apply 41.28 27107 4953
invoice accept: cancel 46.83 1329 5619
customer reserve: creation 3881.08 251 465730
customer reserve: removal 4671.82 107 560618
realization accept: apply 82.48 328 9897
realization accept: cancel 84.55 198 10146
payment from customer: creation 57.09 185 6851
payment from customer: removal 5.54 143 665
payment to supplier: creation 47.08 215 5650
payment to supplier: removal 3.33 134 399
service: total inventory turnovers 3.43 5247 411
service: total monetary turnovers 7.96 463 955
service: aggregate perf. data 0.88 16501 105
service: refresh index statistics 0.04 257615 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-10-2022 10:39:07 to 17-10-2022 10:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8536
TEST_TIME 2 7458
TEST_TIME 3 5850
TEST_TIME 4 5220
TEST_TIME 5 5533
TEST_TIME 6 5567
TEST_TIME 7 5695
TEST_TIME 8 5851
TEST_TIME 9 6422
TEST_TIME 10 6638
TEST_TIME 11 6709
TEST_TIME 12 6982
TEST_TIME 13 6963
TEST_TIME 14 7069
TEST_TIME 15 7056
TEST_TIME 16 7135
TEST_TIME 17 7382
TEST_TIME 18 7610
TEST_TIME 19 8084
TEST_TIME 20 7993
TEST_TIME 21 7922
TEST_TIME 22 7782
TEST_TIME 23 7667
TEST_TIME 24 7629
TEST_TIME 25 7929
TEST_TIME 26 7917
TEST_TIME 27 8315
TEST_TIME 28 8394
TEST_TIME 29 8411
TEST_TIME 30 8563
TEST_TIME 31 8670
TEST_TIME 32 9005
TEST_TIME 33 9207
TEST_TIME 34 9514
TEST_TIME 35 9766
TEST_TIME 36 9639
TEST_TIME 37 9746
TEST_TIME 38 10281
TEST_TIME 39 10754
TEST_TIME 40 10927
TEST_TIME 41 10918
TEST_TIME 42 10742
TEST_TIME 43 10656
TEST_TIME 44 10615
TEST_TIME 45 10474
TEST_TIME 46 10407
TEST_TIME 47 10441
TEST_TIME 48 10384
TEST_TIME 49 10377
TEST_TIME 50 10286
TEST_TIME 51 10198
TEST_TIME 52 10232
TEST_TIME 53 10289
TEST_TIME 54 10282
TEST_TIME 55 10156
TEST_TIME 56 10035
TEST_TIME 57 9985
TEST_TIME 58 9919
TEST_TIME 59 9856
TEST_TIME 60 9862
TEST_TIME 61 9796
TEST_TIME 62 9744
TEST_TIME 63 9780
TEST_TIME 64 9731
TEST_TIME 65 9776
TEST_TIME 66 9681
TEST_TIME 67 9640
TEST_TIME 68 9716
TEST_TIME 69 9604
TEST_TIME 70 9710
TEST_TIME 71 9676
TEST_TIME 72 9674
TEST_TIME 73 9686
TEST_TIME 74 9717
TEST_TIME 75 9699
TEST_TIME 76 9660
TEST_TIME 77 9626
TEST_TIME 78 9764
TEST_TIME 79 9737
TEST_TIME 80 9773
TEST_TIME 81 9802
TEST_TIME 82 9804
TEST_TIME 83 9753
TEST_TIME 84 9694
TEST_TIME 85 9647
TEST_TIME 86 9584
TEST_TIME 87 9607
TEST_TIME 88 9578
TEST_TIME 89 9534
TEST_TIME 90 9471
TEST_TIME 91 9430
TEST_TIME 92 9351
TEST_TIME 93 9370
TEST_TIME 94 9400
TEST_TIME 95 9377
TEST_TIME 96 9372
TEST_TIME 97 9432
TEST_TIME 98 9462
TEST_TIME 99 9421
TEST_TIME 100 9447
TEST_TIME 101 9447
TEST_TIME 102 9533
TEST_TIME 103 9505
TEST_TIME 104 9528
TEST_TIME 105 9565
TEST_TIME 106 9536
TEST_TIME 107 9539
TEST_TIME 108 9570
TEST_TIME 109 9508
TEST_TIME 110 9492
TEST_TIME 111 9431
TEST_TIME 112 9452
TEST_TIME 113 9410
TEST_TIME 114 9368
TEST_TIME 115 9357
TEST_TIME 116 9316
TEST_TIME 117 9282
TEST_TIME 118 9247
TEST_TIME 119 9249
TEST_TIME 120 9293

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2022 10:39:11 to 17-10-2022 10:39:15.

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 1539541 1539541 0 0.00 0 28653 14 0 0
doc_list_biud 1539520 1539520 0 0.00 0 23270 45 0 0
sp_add_invoice_to_stock 5098 4953 145 2.84 43 171911 27107 0 145
sp_cancel_adding_invoice 5619 5619 0 0.00 6 99280 1329 0 0
sp_cancel_client_order 1698 1698 0 0.00 6 9107 486 0 0
sp_cancel_customer_reserve 560618 560618 0 0.00 1 22195 107 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 4 4491 144 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 4 1558 134 0 0
sp_cancel_supplier_invoice 6160 6150 10 0.16 3 18344 1098 0 10
sp_cancel_supplier_order 417 417 0 0.00 18 1545852 186251 0 0
sp_cancel_write_off 10146 10146 0 0.00 2 9517 198 0 0
sp_client_order 20132 20132 0 0.00 5 34184 471 0 0
sp_customer_reserve 465730 465730 0 0.00 0 52372 251 0 0
sp_fill_shopping_cart 30852 30852 0 0.00 1 15080 90 0 0
sp_fill_shopping_cart_clo_res 465682 465682 0 0.00 0 12314 18 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 21879 281 0 0
sp_kill_cost_storno 16829 16829 0 0.00 0 6078 88 0 0
sp_kill_qstorno_ret_qs2qd 1137949 1137949 0 0.00 0 22065 46 0 0
sp_kill_qty_storno 599436 599436 0 0.00 0 23270 107 0 0
sp_lock_selected_doc 20387 20387 0 0.00 0 8629 9 0 0
sp_make_cost_storno 27292 27292 0 0.00 0 18978 92 0 0
sp_multiply_rows_for_pdistr 27288 27288 0 0.00 0 8722 45 0 0
sp_multiply_rows_for_qdistr 20132 20132 0 0.00 0 11692 167 0 0
sp_pay_from_customer 6851 6851 0 0.00 5 12965 186 0 0
sp_pay_to_supplier 5650 5650 0 0.00 6 13497 215 0 0
sp_payment_common 12500 12500 0 0.00 2 7165 49 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 9100 391 0 0
sp_qd_handle_on_invoice_upd_sts 10512 10512 0 0.00 0 22155 606 0 0
sp_qd_handle_on_reserve_upd_sts 9895 9895 0 0.00 0 23269 76 0 0
sp_reserve_write_off 9897 9897 0 0.00 8 23357 328 0 0
sp_supplier_invoice 5390 5390 0 0.00 8 78512 3297 0 0
sp_supplier_order 5336 5336 0 0.00 8 32434 1448 0 0
srv_aggregate_perf_data 105 105 0 0.00 2370 183861 16501 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 670 91024 29565 0 0
srv_find_qd_qs_mism 1062717 1062717 0 0.00 0 28653 10 0 0
srv_make_invnt_saldo 411 411 0 0.00 36 84097 5247 0 0
srv_make_money_saldo 955 955 0 0.00 1 17965 464 0 0
srv_recalc_idx_stat 5 5 0 0.00 63327 454092 257615 0 0
t$perf-abend:sp_add_invoice_to_stock 145 145 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4961 4961 0 0.00 1 17194 1113 0 0
t$perf-norm:sp_cancel_adding_invoice 381 381 0 0.00 1 6480 518 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 2811 31 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 883 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 1443 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 1614 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 2978 45 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 119293 9374 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 4058 25 0 0
t$perf-norm:sp_client_order 20132 20132 0 0.00 0 8690 42 0 0
t$perf-norm:sp_customer_reserve 977 977 0 0.00 0 901 19 0 0
t$perf-norm:sp_pay_from_customer 6851 6851 0 0.00 0 11800 23 0 0
t$perf-norm:sp_pay_to_supplier 5650 5650 0 0.00 0 3709 19 0 0
t$perf-norm:sp_reserve_write_off 9897 9897 0 0.00 0 11487 26 0 0
t$perf-norm:sp_supplier_invoice 5390 5390 0 0.00 0 3686 32 0 0
t$perf-norm:sp_supplier_order 5337 5337 0 0.00 0 12358 39 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 558 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 419 10 0 0
t$perf-norm:srv_make_invnt_saldo 411 411 0 0.00 0 1363 22 0 0
t$perf-norm:srv_make_money_saldo 955 955 0 0.00 0 4023 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 192 82 0 0
v_add_invoice_to_stock 10074 9784 290 2.87 0 6805 32 0 290
v_all_customers 36913 36913 0 0.00 0 91 4 0 0
v_all_suppliers 29876 29876 0 0.00 0 106 5 0 0
v_all_wares 40258 40258 0 0.00 0 170 4 0 0
v_cancel_adding_invoice 762 762 0 0.00 0 3353 20 0 0
v_cancel_client_order 3396 3396 0 0.00 0 3066 15 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 2799 18 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 1 442 12 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1061 13 0 0
v_cancel_supplier_invoice 800 780 20 2.50 0 1667 30 0 20
v_cancel_supplier_order 834 834 0 0.00 0 1239 15 0 0
v_cancel_write_off 2012 2012 0 0.00 0 3427 29 0 0
v_max_id_clo_ord 5334 5334 0 0.00 0 809 5 0 0
v_max_id_clo_res 977 977 0 0.00 0 7526 24 0 0
v_max_id_ord_sup 5387 5387 0 0.00 0 6657 11 0 0
v_max_non_paid_invoice 1432 1432 0 0.00 0 1025 9 0 0
v_max_non_paid_realizn 3474 3474 0 0.00 0 923 8 0 0
v_min_id_clo_ord 5334 5334 0 0.00 0 1473 8 0 0
v_min_id_clo_res 977 977 0 0.00 0 5076 20 0 0
v_min_id_ord_sup 5387 5387 0 0.00 0 832 6 0 0
v_min_non_paid_invoice 5649 5649 0 0.00 0 5374 17 0 0
v_min_non_paid_realizn 6851 6851 0 0.00 0 6406 18 0 0
v_our_firm 10098 10098 0 0.00 0 72 5 0 0
v_reserve_write_off 19788 19788 0 0.00 0 5460 14 0 0
x_lock_depdocs_on_canc_invoice 11379 11379 0 0.00 0 30116 146 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 29353 441 0 0
x_make_qstorno_1000_1200 5336 5336 0 0.00 3 32419 1260 0 0
x_make_qstorno_1200_2000 5390 5390 0 0.00 4 78489 3051 0 0
x_make_qstorno_2100_3300 465725 465725 0 0.00 0 43469 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2022 10:39:16 to 17-10-2022 10:39:20.

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:22 3 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:22 29256 8192000000 9726320784 10025697280 801725152 8.914 100 87 174 0 834804960 11575728 21504080
08:09:59 17097 8192000000 9766798944 10074533888 838603936 9.286 100 92 185 0 874130384 13410640 22115808
08:11:23 11126 8192000000 9792889120 10109726720 862304064 9.523 100 87 172 0 902514176 20224368 19985744
08:12:41 6749 8192000000 9846148304 10173890560 894365552 9.842 100 78 156 0 956685456 45131360 17188544
08:13:54 8162 8192000000 9895484272 10237517824 938388080 10.277 100 75 144 1 1006877808 52783936 15705792
08:15:06 2113 8192000000 9945293792 10296893440 983500816 10.718 100 95 187 0 1069639968 66662448 19476704
08:16:10 2071 8192000000 9991551840 10350641152 1003452480 10.912 100 98 189 1 1107240736 84980352 18807904
08:17:14 3806 8192000000 10000459456 10363969536 1028728080 11.156 100 86 169 0 1122427552 76978848 16720624
08:18:20 1093 8192000000 10013623952 10385854464 1051769712 11.378 100 95 186 0 1134547616 64202096 18575808
08:19:22 1346 8192000000 10028719312 10411216896 1060859264 11.465 100 93 172 1 1148630256 70526160 17244832
08:20:24 802 8192000000 10045361312 10422804480 1071625296 11.568 100 99 194 1 1163125664 71934000 19566368
08:21:26 1306 8192000000 10029468256 10415177728 1077891696 11.627 100 81 152 1 1151559456 58451232 15216528
08:22:28 974 8192000000 10052656896 10445537280 1082690784 11.673 100 78 149 1 1175436208 77069664 15675760
08:23:30 1158 8192000000 10064665888 10451767296 1091759392 11.759 100 97 191 2 1187686256 76010464 19916400
08:24:32 1155 8192000000 10051177056 10436034560 1094669024 11.787 100 97 187 2 1170747728 56988896 19089808
08:25:35 1023 8192000000 10065857168 10456567808 1097834000 11.817 100 92 174 1 1187240208 72400736 17005472
08:26:37 1031 8192000000 10038536368 10425950208 1101044640 11.848 100 93 182 2 1155196160 35306896 18844624
08:27:40 2754 8192000000 10077684432 10469289984 1103463296 11.870 100 77 145 1 1195784064 77862704 14458064
08:28:45 866 8192000000 10061998768 10454216704 1100990288 11.847 100 88 167 1 1186792624 69553056 16249280
08:29:47 1005 8192000000 10069776400 10463477760 1101823504 11.855 100 81 154 1 1193676400 75178736 16674160
08:30:49 849 8192000000 10042164256 10430066688 1069552304 11.548 99 94 179 2 1162991712 75640832 17798576
08:31:51 1254 8192000000 10046890384 10426490880 1076715056 11.616 100 92 178 0 1167511568 73584096 17212416
08:32:54 1207 8192000000 10032389952 10412199936 1065843600 11.512 100 94 181 0 1154305520 69793808 18668112
08:33:55 1188 8192000000 10025814896 10404773888 1054223776 11.401 100 88 168 2 1149390736 77499392 17667568
08:34:58 754 8192000000 9998436784 10376642560 1049039328 11.351 99 82 153 3 1118603952 52980320 16584304
08:35:59 1012 8192000000 10003458032 10383671296 1040575552 11.270 100 89 162 3 1127376096 69088864 17711680
08:37:03 1190 8192000000 10013248480 10387173376 1055823952 11.416 100 97 184 5 1131123168 57439584 17859632
08:38:08 2977 8192000000 10033962256 10415464448 1069102256 11.544 100 84 157 2 1157446224 72798752 15545216
08:39:19 9982 8192000000 10025171424 10413383680 1077741504 11.626 100 62 122 2 1153389536 63985248 11662784
08:40:34 5641 8192000000 10067442128 10459594752 1102250928 11.859 100 71 134 1 1182831344 67136928 13443488
08:42:00 11948 8192000000 10123634352 10514731008 1096617952 11.806 99 67 132 0 1235447200 124727504 14101744
08:43:16 2486 8192000000 10159292496 10555162624 1116976928 11.998 100 82 155 1 1282257168 148492016 16788224
08:44:23 949 8192000000 10190789488 10581807104 1134939056 12.168 100 94 183 1 1308383776 153774496 19670224
08:45:30 1683 8192000000 10220423712 10613424128 1136970256 12.187 100 95 178 2 1332054320 176837712 18246352
08:46:36 3084 8192000000 10219784048 10636550144 1147376832 12.285 100 81 158 0 1341134592 176100816 17656944
08:47:42 1193 8192000000 10254258624 10651660288 1145121584 12.264 99 93 184 3 1368565136 203822336 19621216
08:48:46 1353 8192000000 10282892256 10685546496 1154679456 12.353 100 90 175 1 1397483776 223458352 19345968
08:49:54 2321 8192000000 10285500288 10696118272 1166955584 12.468 100 83 163 0 1390807792 206440160 17412048
08:51:00 825 8192000000 10318811120 10718375936 1165507872 12.455 100 93 182 3 1427550320 241500736 20541712
08:52:04 1324 8192000000 10342854496 10748227584 1168909552 12.487 100 98 190 0 1451981424 260870496 22201376
08:53:10 660 8192000000 10367456256 10772459520 1170916640 12.505 100 94 182 0 1475333424 284077712 20339072
08:54:15 682 8192000000 10351840720 10806956032 1179055168 12.581 100 90 169 1 1459815184 261182032 19577984
08:55:20 4580 8192000000 10343453936 10814795776 1174503984 12.539 99 77 147 2 1452355728 260140976 17710768
08:56:30 3073 8192000000 10357221312 10779566080 1188604048 12.670 100 91 179 1 1464540576 254908672 21027856
08:57:36 1135 8192000000 10388362960 10793373696 1185887072 12.645 100 96 190 1 1496263664 288518704 21857888
08:58:44 1663 8192000000 10412949600 10834702336 1187279184 12.658 100 68 131 0 1524248976 321401712 15568080
08:59:54 773 8192000000 10468468576 10884026368 1188693344 12.671 100 75 146 1 1577143184 369957216 18492624
09:01:04 1622 8192000000 10494519344 10943647744 1191401984 12.696 100 85 162 0 1602690848 391466064 19822800
09:02:10 2056 8192000000 10547641712 11006844928 1203525488 12.809 100 97 192 0 1648505776 420944512 24035776
09:03:16 1282 8192000000 10508750224 10984349696 1211575104 12.884 100 95 187 1 1615928720 379832448 24521168
09:04:20 1652 8192000000 10518234672 10982154240 1196903392 12.748 99 70 140 0 1624257728 409687744 17666592
09:05:26 1956 8192000000 10551780512 10991104000 1201639632 12.792 100 82 163 0 1655685632 434081168 19964832
09:06:34 7338 8192000000 10555907824 11002048512 1184252928 12.630 100 53 104 1 1664285616 466022896 14009792
09:07:44 10671 8192000000 10513632912 10982019072 1168501360 12.483 99 52 102 0 1626889152 445302688 13085104
09:08:58 1030 8192000000 10475671136 10971181056 1166549584 12.465 99 86 158 0 1584083744 397631360 19902800
09:10:02 827 8192000000 10508432848 10944520192 1171405808 12.510 100 92 185 1 1607757280 414279984 22071488
09:11:04 12460 8192000000 10473499504 10941825024 1166015376 12.460 100 40 78 0 1564274768 388113216 10146176
09:12:30 31964 8192000000 10412392304 10855710720 1159423856 12.398 99 31 62 0 1509893584 342499664 7970064
09:14:03 15797 8192000000 10437438560 10932715520 1159964960 12.403 100 26 51 0 1572798736 405972144 6861632
09:15:38 27516 8192000000 10198410528 10780811264 1153254672 12.340 100 27 55 0 1373871264 213643984 6972608
09:17:11 25939 8192000000 10126248112 10611666944 1151984384 12.328 100 16 30 0 1264886432 109340448 3561600
09:18:52 51640 8192000000 10070613920 10511409152 1116403360 11.993 97 35 64 1 1145004304 22200608 6400336
09:20:45 91023 8192000000 10082076208 10537562112 1136734880 12.185 99 16 32 0 1176604240 36276880 3592480
09:23:33 85107 8192000000 10089560448 10582011904 1141350288 12.228 100 20 40 0 1226650400 81204672 4095440
09:25:59 56440 8192000000 10083846816 10573967360 1146867984 12.280 100 24 48 0 1182491904 30098880 5525040
09:28:05 53823 8192000000 10129717296 10591375360 1147797488 12.289 99 29 58 0 1218434384 64309760 6327136
09:30:12 80021 8192000000 10081494960 10567491584 1061414192 11.470 93 14 28 0 1145777616 80456192 3907232
09:32:43 46966 8192000000 10072075776 10492645376 1112623152 11.957 99 56 109 3 1137775760 12975200 12177408
09:34:34 29917 8192000000 10091577024 10537537536 1111083968 11.943 99 44 86 0 1182508224 62479504 8944752
09:36:13 15284 8192000000 10060671376 10503917568 1120672608 12.033 100 39 74 1 1159405584 31801440 6931536
09:37:30 24104 8192000000 10095463472 10549374976 1139829488 12.214 100 36 69 0 1213035696 65832400 7373808
09:38:56 15006 8192000000 10128748352 10567102464 1145991376 12.272 100 33 66 0 1223655296 70287120 7376800
09:40:14 38140 8192000000 10157589680 10615787520 1146849280 12.280 100 33 64 0 1258308304 104667056 6791968
09:41:52 29995 8192000000 10138848592 10648559616 1146016784 12.272 100 11 22 0 1261189728 112579408 2593536
09:43:57 38276 8192000000 10146816544 10619535360 1137256368 12.190 99 21 40 0 1271593968 129773824 4563776
09:45:36 58842 8192000000 10102487328 10594078720 1120258992 12.029 98 17 34 0 1196975664 73116736 3599936
09:48:04 90160 8192000000 10129174240 10587529216 1135960272 12.178 99 13 28 0 1198826848 59808448 3058128
09:50:41 63893 8192000000 10065034384 10512474112 1072226688 11.573 94 20 40 0 1125043600 49050400 3766512
09:52:47 19444 8192000000 10096563248 10536112128 1122681296 12.052 99 28 52 0 1181118640 53012048 5425296
09:54:09 23172 8192000000 10145604880 10578010112 1114599520 11.976 98 48 94 0 1209370976 85776672 8994784
09:55:39 68594 8192000000 10089229648 10519851008 1078452496 11.633 95 21 42 0 1119144144 35364784 5326864
09:57:59 54277 8192000000 10087944832 10564153344 1093379840 11.775 97 18 36 0 1156955216 60007904 3567472
09:59:54 38615 8192000000 10080457120 10514198528 1114314384 11.973 99 37 74 0 1170372064 48382784 7674896
10:01:47 78182 8192000000 10084738112 10528481280 1099214496 11.830 97 36 72 0 1133436560 27038336 7183728
10:04:08 16968 8192000000 10093363216 10537336832 1125850752 12.082 100 28 56 0 1198693920 67235552 5607616
10:05:32 77578 8192000000 10046566896 10498924544 1106607120 11.900 98 17 34 0 1152209008 42097520 3504368
10:07:51 44201 8192000000 10122993040 10582626304 1138453840 12.201 100 14 28 0 1192522608 51300336 2768432
10:09:39 59511 8192000000 10165064272 10653466624 1146641952 12.278 100 16 32 0 1263805808 113630016 3533840
10:11:49 32738 8192000000 10119113376 10559582208 1126984576 12.093 99 29 57 0 1203397296 70536432 5876288
10:13:23 35944 8192000000 10150774032 10599469056 1141767344 12.232 99 29 58 0 1221640736 73312752 6560640
10:15:01 54067 8192000000 10074062480 10524565504 1118210880 12.010 98 31 50 1 1162397888 37903248 6283760
10:16:56 34059 8192000000 10146053648 10610712576 1138295424 12.199 99 47 92 0 1242378592 94639424 9443744
10:18:42 39595 8192000000 10154950864 10628734976 1152825296 12.336 100 27 54 0 1245265840 87111760 5328784
10:20:37 42614 8192000000 10124845920 10599014400 1133276336 12.152 99 35 70 1 1213869088 73372464 7220288
10:22:40 29272 8192000000 10106925456 10538037248 1120406048 12.031 98 43 86 1 1169245344 40369520 8469776
10:24:13 24831 8192000000 10123838240 10568040448 1141824432 12.233 100 55 104 1 1214507552 62125808 10557312
10:25:42 76885 8192000000 10099393392 10548228096 1121504320 12.041 99 42 84 2 1188644288 58106400 9033568
10:28:15 23976 8192000000 10082517488 10530119680 1136766208 12.185 100 42 82 0 1179345456 34102000 8477248
10:29:40 79706 8192000000 10104567056 10606350336 1139567376 12.211 100 10 20 1 1221361408 79606192 2187840
10:32:00 70146 8192000000 10108510512 10561761280 1123475696 12.060 98 16 32 0 1166887216 40226736 3184784
10:34:17 88514 8192000000 10160603856 10638524416 1150079600 12.310 100 13 26 0 1267880256 114710272 3090384
10:36:57 34997 8192000000 9125744944 9450483712 235702608 2.796 22 2 4 0 281329728 45233296 393824

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-10-2022 10:39:22 to 17-10-2022 10:39:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 145 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2022 10:39:40 to 17-10-2022 10:39:42.

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          216275
MON$OLDEST_ACTIVE               217444
MON$OLDEST_SNAPSHOT             217444
MON$NEXT_TRANSACTION            217444
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                       2102848
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 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 ---
:43: 
:44: mscs-test-161.budzdorov.ru    Mon Oct 17 10:37:35 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:46: 
:47: 
:48: mscs-test-161.budzdorov.ru    Mon Oct 17 10:37:35 2022
:49:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:50: 
--- end of diff output ---

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

17.10.22 10:39:43. There are 6 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-10-17 10:39:45 No severe PSQL-related problems occured
17.10.22 10:39:47. End of report.