Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.06.2022 13:39
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 15778216 kB
MemAvailable 31230988 kB
Buffers 0 kB
Cached 15955440 kB
SwapCached 2508 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-06-28 13:37:16
DTS_END                         2022-06-28 13:37:16.6460
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/54084
TRN_ID                          224811
ATT_ID                          602
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: 9153.26 23513 1098391
customer order: creation 178.92 453 21470
customer order: refuse 14.77 400 1772
order to supplier: creation 46.87 1252 5624
order to supplier: removal 3.59 153209 431
invoice (draft): creation 46.85 2904 5622
invoice (draft): removal 51.57 909 6188
invoice accept: apply 41.70 24563 5004
invoice accept: cancel 46.78 1124 5614
customer reserve: creation 3870.16 233 464419
customer reserve: removal 4542.47 94 545096
realization accept: apply 84.89 270 10187
realization accept: cancel 90.78 148 10894
payment from customer: creation 61.48 170 7378
payment from customer: removal 6.04 126 725
payment to supplier: creation 50.00 186 6000
payment to supplier: removal 3.62 96 434
service: total inventory turnovers 4.14 3671 497
service: total monetary turnovers 7.74 371 929
service: aggregate perf. data 0.86 16194 103
service: refresh index statistics 0.03 263884 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-06-2022 13:39:37 to 28-06-2022 13:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6427
TEST_TIME 2 5025
TEST_TIME 3 5246
TEST_TIME 4 5151
TEST_TIME 5 5243
TEST_TIME 6 5517
TEST_TIME 7 5901
TEST_TIME 8 6166
TEST_TIME 9 6359
TEST_TIME 10 6333
TEST_TIME 11 6586
TEST_TIME 12 6766
TEST_TIME 13 6648
TEST_TIME 14 6752
TEST_TIME 15 6771
TEST_TIME 16 7135
TEST_TIME 17 7286
TEST_TIME 18 7612
TEST_TIME 19 7751
TEST_TIME 20 7754
TEST_TIME 21 8059
TEST_TIME 22 7998
TEST_TIME 23 8025
TEST_TIME 24 8052
TEST_TIME 25 8066
TEST_TIME 26 8584
TEST_TIME 27 8838
TEST_TIME 28 9055
TEST_TIME 29 9063
TEST_TIME 30 9278
TEST_TIME 31 10385
TEST_TIME 32 10396
TEST_TIME 33 10719
TEST_TIME 34 10667
TEST_TIME 35 11012
TEST_TIME 36 11078
TEST_TIME 37 10977
TEST_TIME 38 10918
TEST_TIME 39 10981
TEST_TIME 40 10818
TEST_TIME 41 10680
TEST_TIME 42 10487
TEST_TIME 43 10377
TEST_TIME 44 10536
TEST_TIME 45 10355
TEST_TIME 46 10265
TEST_TIME 47 10104
TEST_TIME 48 9990
TEST_TIME 49 10004
TEST_TIME 50 9940
TEST_TIME 51 10069
TEST_TIME 52 9979
TEST_TIME 53 10011
TEST_TIME 54 10000
TEST_TIME 55 10028
TEST_TIME 56 9919
TEST_TIME 57 9853
TEST_TIME 58 9769
TEST_TIME 59 9703
TEST_TIME 60 9655
TEST_TIME 61 9539
TEST_TIME 62 9590
TEST_TIME 63 9612
TEST_TIME 64 9594
TEST_TIME 65 9589
TEST_TIME 66 9595
TEST_TIME 67 9712
TEST_TIME 68 9678
TEST_TIME 69 9686
TEST_TIME 70 9694
TEST_TIME 71 9720
TEST_TIME 72 9714
TEST_TIME 73 9698
TEST_TIME 74 9657
TEST_TIME 75 9722
TEST_TIME 76 9627
TEST_TIME 77 9584
TEST_TIME 78 9598
TEST_TIME 79 9534
TEST_TIME 80 9441
TEST_TIME 81 9398
TEST_TIME 82 9386
TEST_TIME 83 9430
TEST_TIME 84 9471
TEST_TIME 85 9428
TEST_TIME 86 9437
TEST_TIME 87 9403
TEST_TIME 88 9379
TEST_TIME 89 9418
TEST_TIME 90 9474
TEST_TIME 91 9560
TEST_TIME 92 9548
TEST_TIME 93 9573
TEST_TIME 94 9530
TEST_TIME 95 9471
TEST_TIME 96 9520
TEST_TIME 97 9462
TEST_TIME 98 9447
TEST_TIME 99 9409
TEST_TIME 100 9395
TEST_TIME 101 9431
TEST_TIME 102 9380
TEST_TIME 103 9417
TEST_TIME 104 9447
TEST_TIME 105 9400
TEST_TIME 106 9423
TEST_TIME 107 9482
TEST_TIME 108 9429
TEST_TIME 109 9522
TEST_TIME 110 9529
TEST_TIME 111 9474
TEST_TIME 112 9411
TEST_TIME 113 9417
TEST_TIME 114 9382
TEST_TIME 115 9335
TEST_TIME 116 9339
TEST_TIME 117 9314
TEST_TIME 118 9251
TEST_TIME 119 9232
TEST_TIME 120 9180

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-06-2022 13:39:40 to 28-06-2022 13:39:43.

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 1528656 1528656 0 0.00 0 14083 13 0 0
doc_list_biud 1528648 1528648 0 0.00 0 23001 39 0 0
sp_add_invoice_to_stock 5152 5004 148 2.87 40 182973 24564 0 148
sp_cancel_adding_invoice 5614 5614 0 0.00 7 56460 1124 0 0
sp_cancel_client_order 1772 1772 0 0.00 6 9156 400 0 0
sp_cancel_customer_reserve 545096 545096 0 0.00 1 23014 94 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 3 3729 127 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 4 1686 96 0 0
sp_cancel_supplier_invoice 6197 6188 9 0.14 3 21300 910 0 9
sp_cancel_supplier_order 431 431 0 0.00 11 1467634 153210 0 0
sp_cancel_write_off 10894 10894 0 0.00 2 11727 149 0 0
sp_client_order 21470 21470 0 0.00 5 16377 454 0 0
sp_customer_reserve 464419 464419 0 0.00 0 45730 233 0 0
sp_fill_shopping_cart 32702 32702 0 0.00 1 11248 78 0 0
sp_fill_shopping_cart_clo_res 464390 464390 0 0.00 0 15705 18 0 0
sp_get_clo_for_invoice 4965 4965 0 0.00 0 28808 320 0 0
sp_kill_cost_storno 17667 17667 0 0.00 0 6864 67 0 0
sp_kill_qstorno_ret_qs2qd 1107705 1107705 0 0.00 0 21282 40 0 0
sp_kill_qty_storno 585147 585147 0 0.00 0 23000 93 0 0
sp_lock_selected_doc 20938 20938 0 0.00 0 6349 7 0 0
sp_make_cost_storno 28530 28530 0 0.00 0 13597 73 0 0
sp_multiply_rows_for_pdistr 28530 28530 0 0.00 0 7870 37 0 0
sp_multiply_rows_for_qdistr 21470 21470 0 0.00 0 13301 170 0 0
sp_pay_from_customer 7378 7378 0 0.00 5 9976 170 0 0
sp_pay_to_supplier 6000 6000 0 0.00 6 9517 187 0 0
sp_payment_common 13377 13377 0 0.00 2 9912 48 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 9146 321 0 0
sp_qd_handle_on_invoice_upd_sts 10579 10579 0 0.00 0 15304 495 0 0
sp_qd_handle_on_reserve_upd_sts 10187 10187 0 0.00 0 9445 58 0 0
sp_reserve_write_off 10187 10187 0 0.00 8 11493 270 0 0
sp_supplier_invoice 5622 5622 0 0.00 9 96058 2905 0 0
sp_supplier_order 5624 5624 0 0.00 7 18920 1253 0 0
srv_aggregate_perf_data 103 103 0 0.00 2655 154690 16194 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 740 147714 36504 0 0
srv_find_qd_qs_mism 1049644 1049644 0 0.00 0 10573 10 0 0
srv_make_invnt_saldo 497 497 0 0.00 65 65405 3672 0 0
srv_make_money_saldo 929 929 0 0.00 1 10464 371 0 0
srv_recalc_idx_stat 4 4 0 0.00 104534 442113 263885 0 0
t$perf-abend:sp_add_invoice_to_stock 148 148 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5010 5010 0 0.00 0 18023 867 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 1 9216 434 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 1524 28 0 0
t$perf-norm:sp_cancel_customer_reserve 657 657 0 0.00 0 991 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 701 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 4997 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 817 30 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 1 99631 6935 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 1325 18 0 0
t$perf-norm:sp_client_order 21470 21470 0 0.00 0 7037 35 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 2519 15 0 0
t$perf-norm:sp_pay_from_customer 7378 7378 0 0.00 0 2367 18 0 0
t$perf-norm:sp_pay_to_supplier 6000 6000 0 0.00 0 4445 19 0 0
t$perf-norm:sp_reserve_write_off 10187 10187 0 0.00 0 10448 24 0 0
t$perf-norm:sp_supplier_invoice 5623 5623 0 0.00 0 3579 28 0 0
t$perf-norm:sp_supplier_order 5624 5624 0 0.00 0 3389 31 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 628 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 655 15 0 0
t$perf-norm:srv_make_invnt_saldo 497 497 0 0.00 0 15747 42 0 0
t$perf-norm:srv_make_money_saldo 929 929 0 0.00 0 727 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 174 56 0 0
v_add_invoice_to_stock 10220 9924 296 2.89 0 8254 26 0 296
v_all_customers 39438 39438 0 0.00 0 108 4 0 0
v_all_suppliers 31542 31542 0 0.00 0 121 4 0 0
v_all_wares 42938 42938 0 0.00 0 72 4 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 2497 18 0 0
v_cancel_client_order 3544 3544 0 0.00 0 5508 13 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 3690 18 0 0
v_cancel_customer_reserve 1314 1314 0 0.00 0 4526 17 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 653 10 0 0
v_cancel_supplier_invoice 796 778 18 2.26 0 3896 34 0 18
v_cancel_supplier_order 862 862 0 0.00 0 440 11 0 0
v_cancel_write_off 1912 1912 0 0.00 0 1511 26 0 0
v_max_id_clo_ord 5621 5621 0 0.00 0 585 5 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1881 10 0 0
v_max_id_ord_sup 5610 5610 0 0.00 0 4611 6 0 0
v_max_non_paid_invoice 1457 1457 0 0.00 0 1109 8 0 0
v_max_non_paid_realizn 3671 3671 0 0.00 0 2961 8 0 0
v_min_id_clo_ord 5621 5621 0 0.00 0 2979 9 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 2745 14 0 0
v_min_id_ord_sup 5609 5609 0 0.00 0 8387 14 0 0
v_min_non_paid_invoice 5999 5999 0 0.00 0 6732 20 0 0
v_min_non_paid_realizn 7377 7377 0 0.00 0 7541 17 0 0
v_our_firm 10906 10906 0 0.00 0 50 5 0 0
v_reserve_write_off 20374 20374 0 0.00 0 4627 11 0 0
x_lock_depdocs_on_canc_invoice 11413 11413 0 0.00 0 40364 119 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 3413 321 0 0
x_make_qstorno_1000_1200 5624 5624 0 0.00 2 18814 1089 0 0
x_make_qstorno_1200_2000 5622 5622 0 0.00 4 87586 2698 0 0
x_make_qstorno_2100_3300 464419 464419 0 0.00 0 45728 204 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-06-2022 13:39:44 to 28-06-2022 13:39:48.

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
11:07:20 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 21996 8192000000 9727039072 10024640512 806508416 8.962 100 88 174 0 835347616 7974336 20864864
11:09:52 9771 8192000000 9757236192 10069266432 841565408 9.315 100 83 166 1 873656160 13031424 19059328
11:11:10 19731 8192000000 9810027232 10128326656 875381616 9.654 100 89 179 0 920608992 24376080 20851296
11:12:35 8764 8192000000 9856168528 10187853824 913090464 10.028 100 80 157 0 964127120 33182352 17854304
11:13:49 4644 8192000000 9902045360 10247610368 957918352 10.469 100 87 170 0 1013670720 37782080 17970288
11:14:59 2514 8192000000 9962604640 10313609216 989609552 10.778 100 90 178 0 1082538848 75430800 17498496
11:16:05 1530 8192000000 9991804272 10349441024 1014313552 11.017 100 93 179 1 1106063360 73957904 17791904
11:17:09 6380 8192000000 9991258592 10356420608 1035527152 11.222 100 82 156 0 1108746928 58600336 14619440
11:18:18 1370 8192000000 10030172960 10401517568 1054784032 11.407 100 90 176 2 1150068112 78450192 16833888
11:19:20 1312 8192000000 10040410624 10424160256 1072866864 11.579 100 90 175 1 1161058112 69886000 18305248
11:20:24 1011 8192000000 10051781856 10435104768 1078846384 11.636 100 98 186 0 1177158400 79850016 18462000
11:21:26 1454 8192000000 10079979712 10469289984 1079332896 11.641 100 97 183 1 1201934208 103183120 19418192
11:22:29 1302 8192000000 10070008752 10458415104 1083934384 11.685 100 97 192 0 1191948592 89542416 18471792
11:23:32 1174 8192000000 10050123536 10435735552 1083189872 11.678 100 95 184 3 1169542800 67194096 19158832
11:24:35 1198 8192000000 10077899888 10466897920 1088226800 11.726 100 98 194 1 1197194192 89781760 19185632
11:25:37 1286 8192000000 10068540576 10456100864 1084822944 11.693 99 79 154 0 1181635008 81322128 15489936
11:26:40 1283 8192000000 10060691856 10447773696 1096131408 11.801 100 97 189 2 1182011680 66385232 19495040
11:27:43 1143 8192000000 10078348304 10468601856 1096596240 11.805 100 96 184 1 1198083040 83677376 17809424
11:28:44 1139 8192000000 10043414160 10427781120 1089954416 11.742 100 96 181 1 1162192704 54918816 17319472
11:29:47 1867 8192000000 10059403120 10448252928 1097391536 11.813 100 76 147 2 1181977584 69793168 14792880
11:30:52 992 8192000000 10039827328 10424135680 1067718848 11.530 100 95 189 2 1160216096 73193600 19303648
11:31:54 1413 8192000000 10039914816 10424262656 1070738112 11.559 100 95 180 0 1163123648 74093296 18292240
11:32:57 1456 8192000000 10000132736 10381250560 1052370016 11.383 100 83 160 2 1124950736 57649616 14931104
11:33:59 891 8192000000 10009941232 10387963904 1042733296 11.291 100 89 165 1 1133324496 74253664 16337536
11:35:00 877 8192000000 9994598208 10373017600 1047998896 11.341 100 93 176 0 1116791632 51906320 16886416
11:36:02 1081 8192000000 9992757328 10368856064 1041385776 11.278 100 95 181 2 1116298480 55863408 19049296
11:37:04 2405 8192000000 10017147328 10402590720 1053304272 11.392 100 78 147 1 1137394384 69247088 14843024
11:38:14 31129 8192000000 10019184640 10402115584 1061669440 11.472 100 71 128 3 1145579776 71987296 11923040
11:39:51 2274 8192000000 10032629360 10420736000 1079495776 11.643 100 71 134 3 1155965664 61675264 14794624
11:41:01 2111 8192000000 10074574016 10458324992 1095765648 11.797 100 78 147 1 1194388480 83342768 15280064
11:42:07 1696 8192000000 10123256720 10512429056 1111002032 11.942 100 72 142 0 1243082944 116977664 15103248
11:43:12 3013 8192000000 10146304880 10547802112 1111407424 11.946 100 73 142 0 1257558528 130527536 15623568
11:44:20 1246 8192000000 10162449008 10555113472 1118304064 12.011 100 87 165 1 1280387952 143481552 18602336
11:45:26 1743 8192000000 10192259184 10585767936 1139165792 12.208 100 85 157 0 1305548400 149646448 16736160
11:46:31 3164 8192000000 10219402528 10633854976 1148739104 12.298 100 83 167 2 1336186048 168626928 18820016
11:47:40 1077 8192000000 10247024768 10653540352 1162542912 12.427 100 95 183 3 1358091808 174723760 20825136
11:48:44 737 8192000000 10299418752 10700390400 1173551808 12.530 100 91 176 0 1412652944 219520288 19580848
11:49:49 2104 8192000000 10305169680 10711527424 1170613376 12.503 100 79 151 0 1425188016 237561136 17013504
11:50:55 1436 8192000000 10385539184 10787864576 1178544512 12.577 100 92 176 2 1491363872 292277808 20541552
11:52:00 1920 8192000000 10396023536 10808586240 1174122848 12.535 100 79 154 0 1502477840 310548976 17806016
11:53:04 911 8192000000 10396986912 10833272832 1182028112 12.609 100 91 172 2 1506993232 303107792 21857328
11:54:08 2041 8192000000 10409550176 10863366144 1192666640 12.708 100 85 164 0 1527026352 314691856 19667856
11:55:15 1658 8192000000 10446863504 10901032960 1204435328 12.818 100 89 176 0 1550784128 325483760 20865040
11:56:23 848 8192000000 10449640448 10878865408 1201782512 12.793 100 91 176 0 1555612240 332943568 20886160
11:57:26 1273 8192000000 10519945264 10938466304 1207186496 12.843 100 98 194 0 1625259136 394595776 23476864
11:58:30 860 8192000000 10493649632 10931736576 1195901728 12.738 100 95 183 1 1595130288 377941936 21286624
11:59:33 2766 8192000000 10513748400 10935242752 1187407184 12.659 99 79 146 1 1617955088 412618304 17929600
12:00:45 2219 8192000000 10543945568 10966966272 1187796336 12.663 100 75 135 1 1654401952 449936528 16669088
12:01:50 4581 8192000000 10596289648 11036364800 1173253888 12.527 99 72 140 0 1694205600 502955344 17996368
12:03:03 1414 8192000000 10539890464 11043745792 1182254368 12.611 100 86 169 0 1641955600 439184128 20517104
12:04:09 25247 8192000000 10521140736 10961211392 1179782736 12.588 100 47 92 1 1621878336 430298080 11797520
12:05:36 18144 8192000000 10489513600 10950402048 1163202848 12.433 99 31 62 0 1587004928 415291408 8510672
12:06:56 16878 8192000000 10439513728 10893475840 1138626544 12.203 99 34 68 0 1541986032 394392928 8966560
12:08:14 15892 8192000000 10225373440 10796007424 1133552800 12.155 99 23 45 1 1412302800 272574304 6175696
12:09:36 49867 8192000000 10145986400 10582355968 1102299632 11.859 96 19 37 0 1226399216 120140928 3958656
12:11:39 63339 8192000000 10139767824 10717974528 1127307536 12.096 99 14 28 0 1284968672 154044592 3616544
12:14:10 80944 8192000000 10084500672 10567110656 1132370144 12.144 99 23 45 0 1184982256 47025568 5586544
12:16:48 147714 8192000000 10048908576 10506878976 1052875744 11.388 93 24 46 1 1096949472 38858384 5215344
12:20:50 50298 8192000000 10125988768 10586583040 1107791744 11.912 98 30 60 0 1176537072 62789184 5956144
12:22:48 89827 8192000000 10073252112 10516205568 1055652128 11.415 93 21 42 0 1097433088 37198240 4582720
12:25:20 8734 8192000000 10126917120 10545160192 1121136960 12.038 100 55 102 4 1209614128 77709616 10767552
12:26:30 22069 8192000000 10156472912 10589847552 1128892704 12.111 100 43 77 3 1253497568 116145872 8458992
12:27:59 21190 8192000000 10173226112 10617614336 1121142176 12.038 100 50 96 1 1288947696 156604080 11201440
12:30:15 44510 8192000000 10115350720 10612080640 1122573520 12.051 100 15 30 0 1201723968 76100656 3049792
12:32:03 67605 8192000000 10038515808 10493300736 1109297280 11.926 99 20 40 1 1170812128 57102656 4412192
12:34:22 29562 8192000000 10058660640 10509008896 1111808064 11.950 99 23 47 1 1152712240 36307952 4596224
12:35:52 93206 8192000000 10097892688 10570813440 1094359312 11.784 97 9 18 0 1178255376 81892096 2003968
12:38:25 101939 8192000000 10048062608 10530299904 1116644464 11.995 99 10 20 0 1181291936 63098048 1549424
12:41:16 55400 8192000000 10099567520 10555981824 1100698736 11.844 97 20 37 0 1156188128 52072288 3417104
12:43:25 35451 8192000000 10148544960 10612797440 1129392704 12.116 99 33 66 1 1238926256 102556784 6976768
12:45:06 1572 8192000000 10063859216 10494185472 1129980144 12.121 100 68 124 4 1163929088 19263296 14685648
12:46:08 6042 8192000000 10105878672 10529800192 1135455792 12.173 100 64 122 2 1191927040 44553824 11917424
12:47:16 13063 8192000000 10123258304 10544386048 1113202480 11.963 99 54 108 0 1217398624 93370704 10825440
12:48:29 22136 8192000000 10082115584 10539888640 1131927984 12.140 100 34 67 1 1178542720 39869312 6745424
12:49:54 36699 8192000000 10073986208 10513907712 1091729520 11.759 96 36 70 0 1132066352 33274928 7061904
12:51:37 32360 8192000000 10122198912 10571620352 1108798688 11.921 98 44 88 0 1195321776 77625728 8897360
12:53:16 110011 8192000000 10093471104 10562113536 1074955184 11.599 95 25 50 0 1153957344 74178896 4823264
12:56:15 54629 8192000000 10090758912 10614968320 1120454704 12.031 100 6 12 0 1189654720 68152896 1047120
12:58:10 41241 8192000000 10106082128 10559631360 1123297152 12.058 100 14 26 0 1205075088 79039648 2738288
13:00:49 25594 8192000000 10096336592 10532098048 1134987264 12.168 100 29 58 1 1194815776 53030896 6797616
13:02:21 34531 8192000000 10093915856 10522173440 1136461296 12.182 100 48 95 0 1186037280 39892480 9683504
13:03:59 42683 8192000000 10110545408 10550063104 1118768192 12.015 98 25 50 0 1192437776 68808112 4861472
13:05:42 43360 8192000000 10140030368 10607366144 1110302208 11.935 98 34 66 1 1229018144 111933392 6782544
13:07:26 97834 8192000000 10055820320 10503868416 1097563440 11.815 97 18 34 1 1186049360 84654176 3831744
13:10:26 26894 8192000000 10097904512 10528628736 1121027584 12.037 99 36 70 0 1168478624 40847616 6603424
13:11:53 98054 8192000000 10089203568 10590294016 1110463920 11.937 98 32 63 0 1207198592 89820400 6914272
13:14:31 72382 8192000000 10031511424 10493161472 1059452928 11.451 94 13 26 0 1094491360 32530976 2507456
13:16:44 59306 8192000000 10102117440 10540642304 1109657168 11.929 99 24 48 0 1171113280 56503056 4953056
13:18:44 105495 8192000000 10150957104 10601775104 1118235584 12.010 99 17 33 0 1176851216 55557472 3058160
13:21:36 58249 8192000000 10067381040 10490036224 1075365696 11.603 95 26 52 0 1122960624 42063584 5531344
13:23:35 82365 8192000000 10080555648 10604150784 1134267296 12.162 100 15 30 0 1203386560 66076816 3042448
13:26:05 69185 8192000000 10091707168 10590302208 1089460240 11.738 96 40 80 0 1190712576 93227136 8025200
13:28:49 106245 8192000000 10071981696 10546802688 1118379872 12.012 99 34 60 0 1181691264 56275920 7035472
13:31:50 60174 8192000000 10088171712 10532499456 1102508064 11.861 97 19 36 1 1165953264 59257696 4187504
13:34:20 55358 8192000000 10107003360 10607800320 1106692800 11.901 97 15 29 0 1172171024 62180752 3297472
13:36:36 53076 8192000000 9453513008 9841369088 579112304 6.602 51 8 14 0 620713088 40298800 1301984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-06-2022 13:39:50 to 28-06-2022 13:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 148 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-06-2022 13:40:07 to 28-06-2022 13:40:09.

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          225140
MON$OLDEST_ACTIVE               225141
MON$OLDEST_SNAPSHOT             225141
MON$NEXT_TRANSACTION            225141
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                       2085920
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

28.06.22 13:40:10. 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-06-28 13:40:12 No severe PSQL-related problems occured
28.06.22 13:40:13. End of report.