Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.07.2022 04: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 15635784 kB
MemAvailable 31032520 kB
Buffers 20 kB
Cached 16041924 kB
SwapCached 2552 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.2
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-26 04:37:18
DTS_END                         2022-07-26 04:37:18.8060
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50650
TRN_ID                          223331
ATT_ID                          647
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: 9326.23 30107 1119148
customer order: creation 168.23 465 20188
customer order: refuse 14.61 404 1753
order to supplier: creation 44.84 1394 5381
order to supplier: removal 3.42 189825 410
invoice (draft): creation 44.62 3016 5354
invoice (draft): removal 52.04 979 6245
invoice accept: apply 42.56 27242 5107
invoice accept: cancel 47.71 1231 5725
customer reserve: creation 3983.52 259 478022
customer reserve: removal 4627.89 111 555347
realization accept: apply 83.22 280 9986
realization accept: cancel 84.26 168 10111
payment from customer: creation 57.72 199 6926
payment from customer: removal 5.87 128 704
payment to supplier: creation 49.40 202 5928
payment to supplier: removal 3.25 124 390
service: total inventory turnovers 3.98 4238 478
service: total monetary turnovers 8.03 325 963
service: aggregate perf. data 1.04 14166 125
service: refresh index statistics 0.04 357381 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-07-2022 04:39:41 to 26-07-2022 04:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6246
TEST_TIME 2 6901
TEST_TIME 3 6680
TEST_TIME 4 6901
TEST_TIME 5 6599
TEST_TIME 6 6774
TEST_TIME 7 7265
TEST_TIME 8 7510
TEST_TIME 9 7729
TEST_TIME 10 7831
TEST_TIME 11 8025
TEST_TIME 12 8077
TEST_TIME 13 8064
TEST_TIME 14 8035
TEST_TIME 15 8090
TEST_TIME 16 8208
TEST_TIME 17 8272
TEST_TIME 18 8346
TEST_TIME 19 8303
TEST_TIME 20 8239
TEST_TIME 21 8234
TEST_TIME 22 8133
TEST_TIME 23 8058
TEST_TIME 24 8151
TEST_TIME 25 8003
TEST_TIME 26 8159
TEST_TIME 27 8502
TEST_TIME 28 8758
TEST_TIME 29 9238
TEST_TIME 30 9623
TEST_TIME 31 9696
TEST_TIME 32 10045
TEST_TIME 33 10659
TEST_TIME 34 11534
TEST_TIME 35 11673
TEST_TIME 36 11569
TEST_TIME 37 11479
TEST_TIME 38 11317
TEST_TIME 39 11535
TEST_TIME 40 11284
TEST_TIME 41 11029
TEST_TIME 42 10936
TEST_TIME 43 10886
TEST_TIME 44 10733
TEST_TIME 45 10624
TEST_TIME 46 10522
TEST_TIME 47 10433
TEST_TIME 48 10428
TEST_TIME 49 10469
TEST_TIME 50 10477
TEST_TIME 51 10595
TEST_TIME 52 10538
TEST_TIME 53 10424
TEST_TIME 54 10390
TEST_TIME 55 10357
TEST_TIME 56 10361
TEST_TIME 57 10292
TEST_TIME 58 10333
TEST_TIME 59 10314
TEST_TIME 60 10238
TEST_TIME 61 10262
TEST_TIME 62 10202
TEST_TIME 63 10351
TEST_TIME 64 10314
TEST_TIME 65 10347
TEST_TIME 66 10300
TEST_TIME 67 10218
TEST_TIME 68 10123
TEST_TIME 69 10091
TEST_TIME 70 10021
TEST_TIME 71 9972
TEST_TIME 72 9928
TEST_TIME 73 9877
TEST_TIME 74 9785
TEST_TIME 75 9730
TEST_TIME 76 9613
TEST_TIME 77 9622
TEST_TIME 78 9665
TEST_TIME 79 9633
TEST_TIME 80 9610
TEST_TIME 81 9611
TEST_TIME 82 9592
TEST_TIME 83 9573
TEST_TIME 84 9600
TEST_TIME 85 9657
TEST_TIME 86 9637
TEST_TIME 87 9758
TEST_TIME 88 9808
TEST_TIME 89 9849
TEST_TIME 90 9879
TEST_TIME 91 9805
TEST_TIME 92 9794
TEST_TIME 93 9750
TEST_TIME 94 9725
TEST_TIME 95 9692
TEST_TIME 96 9677
TEST_TIME 97 9605
TEST_TIME 98 9559
TEST_TIME 99 9513
TEST_TIME 100 9547
TEST_TIME 101 9515
TEST_TIME 102 9488
TEST_TIME 103 9497
TEST_TIME 104 9507
TEST_TIME 105 9482
TEST_TIME 106 9475
TEST_TIME 107 9461
TEST_TIME 108 9486
TEST_TIME 109 9487
TEST_TIME 110 9516
TEST_TIME 111 9599
TEST_TIME 112 9575
TEST_TIME 113 9633
TEST_TIME 114 9610
TEST_TIME 115 9560
TEST_TIME 116 9500
TEST_TIME 117 9504
TEST_TIME 118 9452
TEST_TIME 119 9415
TEST_TIME 120 9347

Performance per minute, chart


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

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 1557549 1557549 0 0.00 0 16578 15 0 0
doc_list_biud 1557547 1557547 0 0.00 0 39168 44 0 0
sp_add_invoice_to_stock 5276 5107 169 3.20 48 236606 27242 0 169
sp_cancel_adding_invoice 5725 5725 0 0.00 7 74778 1231 0 0
sp_cancel_client_order 1753 1753 0 0.00 5 9568 405 0 0
sp_cancel_customer_reserve 555347 555347 0 0.00 1 39186 111 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 4 3460 128 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 2647 124 0 0
sp_cancel_supplier_invoice 6254 6245 9 0.14 3 25883 979 0 9
sp_cancel_supplier_order 410 410 0 0.00 23 1480785 189826 0 0
sp_cancel_write_off 10111 10111 0 0.00 2 8145 169 0 0
sp_client_order 20188 20188 0 0.00 5 22254 465 0 0
sp_customer_reserve 478022 478022 0 0.00 0 109510 259 0 0
sp_fill_shopping_cart 30918 30918 0 0.00 1 16421 83 0 0
sp_fill_shopping_cart_clo_res 477981 477981 0 0.00 0 18068 18 0 0
sp_get_clo_for_invoice 5057 5057 0 0.00 0 19999 277 0 0
sp_kill_cost_storno 16930 16930 0 0.00 0 11044 72 0 0
sp_kill_qstorno_ret_qs2qd 1127460 1127460 0 0.00 0 39148 47 0 0
sp_kill_qty_storno 594632 594632 0 0.00 0 39166 107 0 0
sp_lock_selected_doc 20768 20768 0 0.00 0 9050 9 0 0
sp_make_cost_storno 27897 27897 0 0.00 0 16536 82 0 0
sp_multiply_rows_for_pdistr 27897 27897 0 0.00 0 11974 40 0 0
sp_multiply_rows_for_qdistr 20188 20188 0 0.00 0 9648 171 0 0
sp_pay_from_customer 6926 6926 0 0.00 5 18597 199 0 0
sp_pay_to_supplier 5928 5928 0 0.00 6 13897 202 0 0
sp_payment_common 12853 12853 0 0.00 2 7284 54 0 0
sp_qd_handle_on_cancel_clo 1753 1753 0 0.00 1 9552 325 0 0
sp_qd_handle_on_invoice_upd_sts 10780 10780 0 0.00 0 21973 535 0 0
sp_qd_handle_on_reserve_upd_sts 9986 9986 0 0.00 0 13595 60 0 0
sp_reserve_write_off 9986 9986 0 0.00 8 13619 280 0 0
sp_supplier_invoice 5354 5354 0 0.00 8 83422 3017 0 0
sp_supplier_order 5381 5381 0 0.00 9 43067 1395 0 0
srv_aggregate_perf_data 125 125 0 0.00 2744 141411 14167 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 694 121654 31151 0 0
srv_find_qd_qs_mism 1069127 1069127 0 0.00 0 9885 12 0 0
srv_make_invnt_saldo 478 478 0 0.00 40 68841 4238 0 0
srv_make_money_saldo 963 963 0 0.00 1 6860 325 0 0
srv_recalc_idx_stat 5 5 0 0.00 211266 477565 357381 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 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 5109 5109 0 0.00 0 25103 951 0 0
t$perf-norm:sp_cancel_adding_invoice 448 448 0 0.00 1 6106 422 0 0
t$perf-norm:sp_cancel_client_order 1753 1753 0 0.00 0 21493 37 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 3887 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 1399 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 1262 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 2598 39 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 84960 6801 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 3541 25 0 0
t$perf-norm:sp_client_order 20189 20189 0 0.00 0 21740 36 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 21389 48 0 0
t$perf-norm:sp_pay_from_customer 6926 6926 0 0.00 0 20185 22 0 0
t$perf-norm:sp_pay_to_supplier 5928 5928 0 0.00 0 7047 21 0 0
t$perf-norm:sp_reserve_write_off 9986 9986 0 0.00 0 6551 24 0 0
t$perf-norm:sp_supplier_invoice 5354 5354 0 0.00 0 7207 32 0 0
t$perf-norm:sp_supplier_order 5381 5381 0 0.00 0 10015 34 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 420 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 3638 65 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 1728 20 0 0
t$perf-norm:srv_make_money_saldo 963 963 0 0.00 0 461 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 220 91 0 0
v_add_invoice_to_stock 10442 10104 338 3.23 0 6438 32 0 338
v_all_customers 36430 36430 0 0.00 0 132 4 0 0
v_all_suppliers 30414 30414 0 0.00 0 134 5 0 0
v_all_wares 40372 40372 0 0.00 0 228 4 0 0
v_cancel_adding_invoice 896 896 0 0.00 0 1314 17 0 0
v_cancel_client_order 3506 3506 0 0.00 0 3841 15 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 954 13 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 1628 13 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 2529 21 0 0
v_cancel_supplier_invoice 806 788 18 2.23 0 3613 33 0 18
v_cancel_supplier_order 820 820 0 0.00 0 1218 14 0 0
v_cancel_write_off 1976 1976 0 0.00 0 747 23 0 0
v_max_id_clo_ord 5380 5380 0 0.00 0 1497 6 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 3133 23 0 0
v_max_id_ord_sup 5352 5352 0 0.00 0 11319 9 0 0
v_max_non_paid_invoice 1452 1452 0 0.00 0 534 9 0 0
v_max_non_paid_realizn 3722 3722 0 0.00 0 4689 10 0 0
v_min_id_clo_ord 5380 5380 0 0.00 0 1339 9 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 1074 9 0 0
v_min_id_ord_sup 5352 5352 0 0.00 0 6947 11 0 0
v_min_non_paid_invoice 5927 5927 0 0.00 0 5531 20 0 0
v_min_non_paid_realizn 6926 6926 0 0.00 0 7277 25 0 0
v_our_firm 10350 10350 0 0.00 0 84 5 0 0
v_reserve_write_off 19970 19970 0 0.00 0 5576 13 0 0
x_lock_depdocs_on_canc_invoice 11576 11576 0 0.00 0 11196 130 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 3564 365 0 0
x_make_qstorno_1000_1200 5381 5381 0 0.00 2 43054 1222 0 0
x_make_qstorno_1200_2000 5354 5354 0 0.00 4 73930 2802 0 0
x_make_qstorno_2100_3300 478022 478022 0 0.00 0 109475 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-07-2022 04:39:49 to 26-07-2022 04:39:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:22 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:23 21241 8192000000 9716958464 10014007296 795880144 8.855 100 86 172 0 824816240 7495456 21440640
02:09:49 16440 8192000000 9761534720 10070036480 837106320 9.271 100 90 180 0 869943872 11046880 21790672
02:11:20 15685 8192000000 9796968496 10117992448 871648368 9.616 100 79 158 0 907316208 17284864 18382976
02:12:40 7933 8192000000 9855026496 10186035200 914529168 10.042 100 81 160 0 966111232 33998096 17583968
02:13:50 6246 8192000000 9913638192 10257903616 954304944 10.433 100 81 161 1 1027166928 55834496 17027488
02:14:58 1701 8192000000 9965426720 10318528512 998160752 10.861 100 90 172 0 1077872016 62094656 17616608
02:16:02 1690 8192000000 10003819136 10367180800 1022343664 11.095 100 97 193 0 1123698560 81971184 19383712
02:17:06 2216 8192000000 10013240864 10385633280 1043799296 11.301 100 80 146 0 1133465264 75784512 13881456
02:18:08 967 8192000000 10025923520 10403528704 1065337184 11.508 100 93 169 2 1147685472 66213904 16134384
02:19:10 1245 8192000000 10048204512 10433011712 1073843376 11.589 100 93 174 1 1171822528 79924512 18054640
02:20:13 1012 8192000000 10056639536 10439548928 1073906272 11.589 100 95 189 2 1177052592 84356576 18789744
02:21:14 1096 8192000000 10061298208 10450571264 1081596912 11.663 100 90 170 1 1182555776 83058848 17900016
02:22:17 4225 8192000000 10045142128 10443993088 1087683104 11.721 100 70 126 1 1167602048 67229968 12688976
02:23:23 4278 8192000000 10024010096 10412945408 1091125104 11.753 100 75 149 1 1143514016 38199376 14189536
02:24:28 1216 8192000000 10055169392 10440454144 1086110880 11.706 100 93 178 0 1180335760 75386256 18838624
02:25:31 1244 8192000000 10087762192 10480582656 1091598288 11.758 100 98 193 1 1207596992 97526224 18472480
02:26:34 2413 8192000000 10066578704 10462220288 1095188704 11.792 100 81 158 1 1186762384 76524608 15049072
02:27:37 1149 8192000000 10043154560 10433572864 1092704512 11.768 100 97 185 2 1164776480 54042352 18029616
02:28:39 779 8192000000 10059732576 10446831616 1099215312 11.830 100 95 184 0 1180091568 61750304 19125952
02:29:41 928 8192000000 10065235776 10453397504 1089828880 11.741 100 91 171 0 1191223040 82150288 19243872
02:30:43 1176 8192000000 10036793568 10423554048 1077720528 11.626 100 92 177 0 1161717984 65665040 18332416
02:31:45 2540 8192000000 10021396336 10407788544 1050500848 11.365 100 75 148 2 1151353872 86132048 14720976
02:32:48 1229 8192000000 10003075824 10387308544 1059648032 11.453 100 88 166 2 1127568896 50046496 17874368
02:33:50 6900 8192000000 10002077872 10388439040 1052910704 11.389 100 64 123 0 1125856928 61097920 11848304
02:35:01 1189 8192000000 9954776048 10330271744 1015702688 11.031 98 76 138 3 1080009072 48931536 15374848
02:36:05 959 8192000000 9975820480 10352885760 1028975072 11.159 100 93 181 1 1097373536 48949456 19449008
02:37:06 1413 8192000000 9966351760 10344480768 1037475520 11.240 100 78 144 2 1091196032 39093280 14627232
02:38:13 7515 8192000000 10005581264 10384416768 1050545232 11.366 100 72 133 2 1126403184 63181936 12676016
02:39:25 8868 8192000000 10016029072 10396278784 1055869424 11.417 99 71 139 2 1141775152 71367360 14538368
02:40:40 2640 8192000000 10045413136 10435747840 1074083008 11.591 100 79 145 1 1170829648 81572640 15174000
02:41:47 1219 8192000000 10059570256 10441801728 1080911968 11.656 100 95 179 1 1172907232 73898192 18097072
02:42:51 4321 8192000000 10082082512 10476109824 1089212304 11.735 100 68 128 0 1201653712 99121968 13319440
02:44:00 2006 8192000000 10096415696 10497847296 1105337008 11.888 100 75 142 4 1215796016 93641456 16817552
02:45:05 2912 8192000000 10094003008 10498883584 1112072128 11.952 100 74 144 2 1217551584 89549088 15930368
02:46:12 1272 8192000000 10159296752 10556211200 1130863152 12.129 100 93 182 0 1272246992 121703008 19680832
02:47:15 787 8192000000 10188986096 10589179904 1155615408 12.362 100 95 185 3 1305405792 129315648 20474736
02:48:19 861 8192000000 10220222464 10628108288 1175840048 12.551 100 94 184 1 1332492736 135233600 21419088
02:49:23 1084 8192000000 10261207168 10663546880 1178344304 12.575 100 96 184 0 1369188880 171498352 19346224
02:50:26 1814 8192000000 10271569216 10677518336 1185533600 12.642 100 87 172 1 1385676320 181293888 18848832
02:51:33 818 8192000000 10317780768 10724966400 1199867280 12.775 100 94 182 4 1429723296 208700896 21155120
02:52:37 993 8192000000 10372612368 10778955776 1204784080 12.821 100 93 182 1 1483788288 258502384 20501824
02:53:41 1556 8192000000 10391333408 10805989376 1207143808 12.843 100 97 187 1 1497128704 268421168 21563728
02:54:44 1078 8192000000 10430400384 10837692416 1209363120 12.863 100 93 174 0 1538567216 309689616 19514480
02:55:50 1119 8192000000 10438746048 10853859328 1210181200 12.871 100 96 188 3 1545768384 313163136 22424048
02:56:54 863 8192000000 10510703328 10919239680 1219170816 12.954 100 97 188 0 1617662640 376445328 22046496
02:57:58 687 8192000000 10524951664 10936352768 1216299632 12.927 100 97 187 0 1632477232 392550768 23626832
02:59:01 946 8192000000 10579656960 10984800256 1213436352 12.901 100 96 188 0 1684095792 448656064 22003376
03:00:07 1317 8192000000 10657510688 11070889984 1220752288 12.969 100 95 191 0 1761335712 516444080 24139344
03:01:13 996 8192000000 10667745824 11100909568 1215426624 12.919 99 92 185 1 1769800528 531871248 22502656
03:02:19 1115 8192000000 10692412288 11146264576 1225068864 13.009 100 93 181 2 1795431184 547930768 22431552
03:03:23 2582 8192000000 10750743984 11171676160 1216065952 12.925 100 88 171 0 1852390080 614375728 21948400
03:04:29 890 8192000000 10749776864 11247042560 1223885872 12.998 100 91 181 0 1866037888 618905600 23246416
03:05:37 12260 8192000000 10730949056 11213541376 1212520816 12.892 100 58 116 0 1843411088 615065952 15824320
03:06:51 13959 8192000000 10637553952 11182825472 1200543392 12.781 100 47 94 1 1755166768 541849968 12773408
03:08:32 15256 8192000000 10571630272 11071324160 1185316208 12.640 100 34 68 0 1687670016 493197408 9156400
03:11:02 38404 8192000000 10160671184 10678833152 1101351296 11.850 97 14 28 1 1357239024 251978832 3908896
03:12:53 49527 8192000000 10101459584 10571923456 1131806448 12.138 100 27 55 0 1209107072 70767408 6533216
03:14:51 58885 8192000000 10149445440 10593259520 1135475680 12.173 99 24 48 0 1219671632 79316752 4879200
03:17:03 121653 8192000000 10060312480 10504503296 1093592544 11.777 96 27 48 0 1125816656 26604624 5619488
03:20:07 16462 8192000000 10114900912 10540339200 1117956816 12.008 99 68 129 1 1194495856 62986672 13552368
03:21:24 22292 8192000000 10061171456 10504617984 1101804624 11.855 98 26 52 0 1149828416 42354368 5669424
03:22:48 19567 8192000000 10123213232 10562068480 1124747248 12.072 100 54 109 0 1222091968 85731792 11612928
03:24:16 39686 8192000000 10194841904 10634489856 1152125424 12.329 100 37 70 0 1281080016 121362352 7592240
03:25:56 51154 8192000000 10195975008 10675019776 1133559072 12.155 99 29 53 1 1305555408 166108880 5887456
03:27:49 105001 8192000000 10066341232 10558439424 1124859744 12.073 99 16 32 0 1177563824 49139776 3564304
03:30:38 37808 8192000000 10084968576 10567217152 1144663584 12.259 100 16 32 0 1192932224 45100784 3167856
03:32:16 59171 8192000000 10098587120 10562867200 1141820752 12.233 99 13 26 0 1211712192 66757408 3134032
03:34:16 41970 8192000000 10122177072 10564472832 1135413680 12.172 99 39 78 1 1213077696 70205520 7458496
03:36:08 43659 8192000000 10081461184 10543337472 1114831568 11.978 98 26 52 0 1189223808 69142592 5249648
03:37:54 23195 8192000000 10169338384 10628567040 1151095792 12.320 100 40 80 1 1246135680 86417920 8621968
03:39:22 44432 8192000000 10143234368 10625146880 1134249504 12.161 99 25 50 1 1213197584 73567184 5380896
03:41:16 44203 8192000000 10106924992 10557890560 1116247008 11.992 97 25 50 0 1139104464 17374992 5482464
03:43:00 107574 8192000000 10079325264 10554064896 1098994336 11.828 96 30 60 1 1143085488 37589424 6501728
03:46:20 60472 8192000000 10043615152 10525839360 1103380752 11.870 98 13 26 0 1154762336 48853328 2528256
03:48:25 53898 8192000000 10074163200 10532286464 1119137328 12.019 99 28 57 0 1174203360 49460512 5605520
03:50:19 58867 8192000000 10067927760 10544234496 1111132944 11.943 98 16 32 0 1149902688 35154240 3615504
03:53:19 70911 8192000000 10107526240 10614259712 1127734112 12.100 99 15 30 0 1238710960 107815776 3161072
03:55:31 46025 8192000000 10075324128 10527145984 1111874304 11.950 97 29 56 1 1158167792 40085536 6207952
03:57:18 15777 8192000000 10137389136 10584416256 1141314400 12.228 99 38 71 1 1235711504 86860288 7536816
03:58:36 19552 8192000000 10143053344 10580054016 1106252160 11.897 98 37 72 0 1217320688 103116432 7952096
03:59:56 12863 8192000000 10199399440 10630021120 1130487168 12.126 99 60 117 1 1282727744 140062448 12178128
04:01:09 16474 8192000000 10200342896 10644467712 1135737264 12.175 99 38 76 0 1282038784 138138576 8162944
04:02:27 10582 8192000000 10223456672 10670891008 1146954352 12.281 100 55 108 0 1330901536 172293472 11653712
04:03:39 15686 8192000000 10250891424 10694307840 1155046016 12.357 100 36 70 0 1341926992 179127568 7753408
04:04:56 42964 8192000000 10131679328 10603892736 1148259120 12.293 100 39 76 1 1267899856 111062608 8578128
04:06:53 62896 8192000000 10146419680 10602409984 1135040864 12.169 99 56 108 0 1253813104 107163184 11609056
04:09:02 87174 8192000000 10087096544 10555703296 1119107424 12.019 98 17 34 0 1162620864 40660656 2852784
04:11:33 42095 8192000000 10073640800 10524061696 1104654400 11.882 97 21 42 0 1140745392 31613024 4477968
04:13:19 69355 8192000000 10068096144 10546151424 1108608880 11.919 98 6 12 0 1146414432 36540432 1265120
04:15:29 58131 8192000000 10091018640 10556715008 1125045552 12.075 99 25 46 0 1195555952 66218784 4291616
04:17:46 57141 8192000000 10053735808 10496811008 1119728000 12.024 99 23 42 0 1145971408 22445664 3797744
04:19:43 32452 8192000000 10114815824 10556719104 1124295456 12.068 99 31 61 0 1191753152 61132560 6325136
04:21:19 67171 8192000000 10088545200 10571386880 1126576608 12.089 99 21 42 0 1204455264 73196912 4681744
04:23:34 16834 8192000000 10127331536 10559041536 1115182016 11.981 98 48 96 0 1209857184 84698864 9976304
04:24:51 36843 8192000000 10140358640 10594701312 1138611568 12.202 100 41 80 2 1226135856 79640176 7884112
04:26:29 51340 8192000000 10182686624 10673385472 1151764400 12.326 100 30 58 0 1253778400 96228752 5785248
04:28:20 42207 8192000000 10118180720 10590273536 1147727952 12.288 100 26 48 0 1224790928 73006320 4056656
04:30:03 40486 8192000000 10091365120 10583195648 1108656928 11.920 97 19 37 0 1185709072 73508496 3543648
04:31:47 107685 8192000000 10101456928 10561445888 1127147280 12.094 99 27 54 0 1196076256 62317264 6611712
04:34:55 28097 8192000000 10081086912 10544291840 1128892608 12.111 100 10 20 0 1182013376 50662864 2457904
04:36:24 64418 8192000000 9464562800 9863196672 584935856 6.664 51 8 16 0 644962096 58118144 1908096

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 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 26-07-2022 04:40:12 to 26-07-2022 04:40:14.

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          223415
MON$OLDEST_ACTIVE               223636
MON$OLDEST_SNAPSHOT             223636
MON$NEXT_TRANSACTION            223636
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                       2075104
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2807 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2807 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2807 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2807 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 ---
:117: 
:118: mscs-test-161.budzdorov.ru    Tue Jul 26 04:37:37 2022
:119:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:120: 
--- end of diff output ---

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

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