Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 04: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15900556 kB
MemAvailable 30883464 kB
Buffers 20 kB
Cached 15763792 kB
SwapCached 340 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.1
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 2021-09-07 04:37:06
DTS_END                         2021-09-07 04:37:06.1640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53818
TRN_ID                          239521
ATT_ID                          606
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: 9082.70 28324 1089924
customer order: creation 167.73 400 20127
customer order: refuse 14.64 390 1757
order to supplier: creation 45.68 1436 5482
order to supplier: removal 3.44 141183 413
invoice (draft): creation 45.95 3866 5514
invoice (draft): removal 51.03 1177 6124
invoice accept: apply 41.48 22871 4978
invoice accept: cancel 46.40 1049 5568
customer reserve: creation 3846.01 224 461521
customer reserve: removal 4526.11 82 543133
realization accept: apply 83.28 218 9994
realization accept: cancel 82.73 139 9928
payment from customer: creation 58.58 128 7030
payment from customer: removal 5.67 91 680
payment to supplier: creation 49.33 148 5919
payment to supplier: removal 3.62 130 434
service: total inventory turnovers 3.74 4322 449
service: total monetary turnovers 6.40 650 768
service: aggregate perf. data 0.84 19937 101
service: refresh index statistics 0.03 368034 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-09-2021 04:38:55 to 07-09-2021 04:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3800
TEST_TIME 2 4169
TEST_TIME 3 4338
TEST_TIME 4 4409
TEST_TIME 5 4605
TEST_TIME 6 4996
TEST_TIME 7 5148
TEST_TIME 8 4995
TEST_TIME 9 5333
TEST_TIME 10 5680
TEST_TIME 11 5914
TEST_TIME 12 5940
TEST_TIME 13 6364
TEST_TIME 14 6716
TEST_TIME 15 6932
TEST_TIME 16 7003
TEST_TIME 17 6967
TEST_TIME 18 7147
TEST_TIME 19 7174
TEST_TIME 20 7309
TEST_TIME 21 7597
TEST_TIME 22 7835
TEST_TIME 23 7903
TEST_TIME 24 8120
TEST_TIME 25 8083
TEST_TIME 26 8631
TEST_TIME 27 8694
TEST_TIME 28 8648
TEST_TIME 29 9230
TEST_TIME 30 10729
TEST_TIME 31 10913
TEST_TIME 32 11094
TEST_TIME 33 11092
TEST_TIME 34 10934
TEST_TIME 35 10841
TEST_TIME 36 10804
TEST_TIME 37 10688
TEST_TIME 38 10484
TEST_TIME 39 10360
TEST_TIME 40 10374
TEST_TIME 41 10335
TEST_TIME 42 10156
TEST_TIME 43 10078
TEST_TIME 44 10076
TEST_TIME 45 10051
TEST_TIME 46 10087
TEST_TIME 47 10274
TEST_TIME 48 10187
TEST_TIME 49 10108
TEST_TIME 50 10098
TEST_TIME 51 10034
TEST_TIME 52 9966
TEST_TIME 53 9829
TEST_TIME 54 9816
TEST_TIME 55 9716
TEST_TIME 56 9665
TEST_TIME 57 9593
TEST_TIME 58 9492
TEST_TIME 59 9416
TEST_TIME 60 9576
TEST_TIME 61 9499
TEST_TIME 62 9548
TEST_TIME 63 9550
TEST_TIME 64 9498
TEST_TIME 65 9619
TEST_TIME 66 9705
TEST_TIME 67 9727
TEST_TIME 68 9691
TEST_TIME 69 9608
TEST_TIME 70 9715
TEST_TIME 71 9653
TEST_TIME 72 9644
TEST_TIME 73 9579
TEST_TIME 74 9532
TEST_TIME 75 9450
TEST_TIME 76 9411
TEST_TIME 77 9332
TEST_TIME 78 9250
TEST_TIME 79 9244
TEST_TIME 80 9193
TEST_TIME 81 9407
TEST_TIME 82 9399
TEST_TIME 83 9343
TEST_TIME 84 9334
TEST_TIME 85 9464
TEST_TIME 86 9430
TEST_TIME 87 9429
TEST_TIME 88 9419
TEST_TIME 89 9483
TEST_TIME 90 9506
TEST_TIME 91 9542
TEST_TIME 92 9517
TEST_TIME 93 9460
TEST_TIME 94 9409
TEST_TIME 95 9381
TEST_TIME 96 9389
TEST_TIME 97 9338
TEST_TIME 98 9285
TEST_TIME 99 9243
TEST_TIME 100 9243
TEST_TIME 101 9192
TEST_TIME 102 9177
TEST_TIME 103 9231
TEST_TIME 104 9225
TEST_TIME 105 9220
TEST_TIME 106 9223
TEST_TIME 107 9254
TEST_TIME 108 9238
TEST_TIME 109 9297
TEST_TIME 110 9292
TEST_TIME 111 9264
TEST_TIME 112 9353
TEST_TIME 113 9364
TEST_TIME 114 9323
TEST_TIME 115 9271
TEST_TIME 116 9237
TEST_TIME 117 9237
TEST_TIME 118 9194
TEST_TIME 119 9156
TEST_TIME 120 9085

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-09-2021 04:38:58 to 07-09-2021 04:39:02.

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 1517612 1517612 0 0.00 0 16550 9 0 0
doc_list_biud 1517612 1517612 0 0.00 0 25522 37 0 0
sp_add_invoice_to_stock 5139 4978 161 3.13 47 215494 22871 0 161
sp_cancel_adding_invoice 5568 5568 0 0.00 5 71298 1049 0 0
sp_cancel_client_order 1757 1757 0 0.00 5 16048 391 0 0
sp_cancel_customer_reserve 543133 543133 0 0.00 1 23490 83 0 0
sp_cancel_pay_from_customer 680 680 0 0.00 2 3493 92 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 6231 131 0 0
sp_cancel_supplier_invoice 6133 6124 9 0.14 2 21799 1177 0 9
sp_cancel_supplier_order 413 413 0 0.00 6 1199178 141183 0 0
sp_cancel_write_off 9928 9928 0 0.00 1 13745 140 0 0
sp_client_order 20127 20127 0 0.00 4 29069 401 0 0
sp_customer_reserve 461521 461521 0 0.00 0 78879 224 0 0
sp_fill_shopping_cart 31096 31096 0 0.00 1 15554 41 0 0
sp_fill_shopping_cart_clo_res 461509 461509 0 0.00 0 15927 18 0 0
sp_get_clo_for_invoice 4966 4966 0 0.00 0 23718 336 0 0
sp_kill_cost_storno 16610 16610 0 0.00 0 13700 78 0 0
sp_kill_qstorno_ret_qs2qd 1102731 1102731 0 0.00 0 23485 45 0 0
sp_kill_qty_storno 581883 581883 0 0.00 0 25522 96 0 0
sp_lock_selected_doc 20765 20765 0 0.00 0 9798 6 0 0
sp_make_cost_storno 27909 27909 0 0.00 0 16540 68 0 0
sp_multiply_rows_for_pdistr 27909 27909 0 0.00 0 6460 38 0 0
sp_multiply_rows_for_qdistr 20127 20127 0 0.00 0 13764 186 0 0
sp_pay_from_customer 7030 7030 0 0.00 4 17454 128 0 0
sp_pay_to_supplier 5919 5919 0 0.00 4 12903 148 0 0
sp_payment_common 12949 12949 0 0.00 1 7513 23 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 16036 338 0 0
sp_qd_handle_on_invoice_upd_sts 10534 10534 0 0.00 0 9924 438 0 0
sp_qd_handle_on_reserve_upd_sts 9994 9994 0 0.00 0 25522 72 0 0
sp_reserve_write_off 9994 9994 0 0.00 7 25548 218 0 0
sp_supplier_invoice 5514 5514 0 0.00 7 85113 3867 0 0
sp_supplier_order 5482 5482 0 0.00 5 41413 1436 0 0
srv_aggregate_perf_data 101 101 0 0.00 2768 340651 19938 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1217 128880 40119 0 0
srv_find_qd_qs_mism 1042724 1042724 0 0.00 0 13659 8 0 0
srv_make_invnt_saldo 449 449 0 0.00 38 149656 4323 0 0
srv_make_money_saldo 768 768 0 0.00 1 23574 650 0 0
srv_recalc_idx_stat 4 4 0 0.00 156029 693290 368035 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 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 4978 4978 0 0.00 0 27437 226 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 28576 245 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 716 11 0 0
t$perf-norm:sp_cancel_customer_reserve 676 676 0 0.00 0 4064 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 680 680 0 0.00 0 525 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 1691 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 4532 31 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 36646 2178 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 16093 24 0 0
t$perf-norm:sp_client_order 20127 20127 0 0.00 0 8367 20 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 829 6 0 0
t$perf-norm:sp_pay_from_customer 7030 7030 0 0.00 0 6239 8 0 0
t$perf-norm:sp_pay_to_supplier 5919 5919 0 0.00 0 11471 11 0 0
t$perf-norm:sp_reserve_write_off 9994 9994 0 0.00 0 13469 15 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 17856 40 0 0
t$perf-norm:sp_supplier_order 5482 5482 0 0.00 0 6913 20 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 703 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 754 12 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 2232 19 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 5878 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 60 20 0 0
v_add_invoice_to_stock 10254 9932 322 3.14 0 6337 20 0 322
v_all_customers 36602 36602 0 0.00 0 34 1 0 0
v_all_suppliers 30650 30650 0 0.00 0 44 1 0 0
v_all_wares 40254 40254 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1906 12 0 0
v_cancel_client_order 3514 3514 0 0.00 0 3804 7 0 0
v_cancel_customer_prepayment 1360 1360 0 0.00 0 3468 12 0 0
v_cancel_customer_reserve 1352 1352 0 0.00 0 1298 6 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 2194 14 0 0
v_cancel_supplier_invoice 838 820 18 2.14 0 4434 31 0 18
v_cancel_supplier_order 826 826 0 0.00 0 2080 13 0 0
v_cancel_write_off 2032 2032 0 0.00 0 3493 14 0 0
v_max_id_clo_ord 5478 5478 0 0.00 0 1305 2 0 0
v_max_id_clo_res 986 986 0 0.00 0 1762 18 0 0
v_max_id_ord_sup 5491 5491 0 0.00 0 2714 4 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 4356 5 0 0
v_max_non_paid_realizn 3691 3691 0 0.00 0 4341 5 0 0
v_min_id_clo_ord 5478 5478 0 0.00 0 2544 9 0 0
v_min_id_clo_res 986 986 0 0.00 0 4076 9 0 0
v_min_id_ord_sup 5491 5491 0 0.00 0 9901 8 0 0
v_min_non_paid_invoice 5919 5919 0 0.00 0 6139 14 0 0
v_min_non_paid_realizn 7030 7030 0 0.00 0 6157 16 0 0
v_our_firm 10330 10330 0 0.00 0 18 1 0 0
v_reserve_write_off 19988 19988 0 0.00 0 5611 8 0 0
x_lock_depdocs_on_canc_invoice 11282 11282 0 0.00 0 32070 151 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 10875 441 0 0
x_make_qstorno_1000_1200 5482 5482 0 0.00 2 41397 1337 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 4 85103 3726 0 0
x_make_qstorno_2100_3300 461521 461521 0 0.00 0 78877 202 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 04:39:03 to 07-09-2021 04:39:07.

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:10 3 8192000000 8800308336 8812158976 5507808 0.067 3 1 2 0 5608976 65392 35776
02:08:11 26330 8192000000 9728443280 10025377792 804984704 8.947 100 87 173 0 834075824 7994752 21096368
02:09:45 25800 8192000000 9778122080 10086903808 853943824 9.440 100 90 180 0 888368272 13189888 21234560
02:11:13 19668 8192000000 9811326512 10127011840 867328240 9.573 100 86 172 0 913889216 27297200 19263776
02:12:38 13312 8192000000 9877987440 10209939456 919008240 10.086 100 82 164 0 984280128 47402192 17869696
02:13:57 8682 8192000000 9927359440 10271514624 962374048 10.512 100 79 156 1 1036531376 58187312 15970016
02:15:10 5647 8192000000 9969607136 10324135936 998930240 10.868 100 84 168 0 1088073856 72161920 16981696
02:16:18 4194 8192000000 10002674960 10364502016 1019619360 11.068 100 90 180 1 1114397520 75835488 18942672
02:17:24 1556 8192000000 10015996464 10385608704 1043305024 11.296 100 85 168 1 1128677856 68178864 17193968
02:18:27 1620 8192000000 10046629296 10419175424 1069187168 11.544 100 91 180 2 1149646208 61645776 18813264
02:19:29 4199 8192000000 10003593504 10402455552 1073658112 11.587 100 50 98 1 1124798688 41492720 9647856
02:20:38 4316 8192000000 9993578416 10397016064 1081231568 11.659 100 43 87 0 1132695936 42156464 9307904
02:21:45 4510 8192000000 10005475888 10405371904 1085654416 11.701 100 38 75 0 1123477920 29291840 8531664
02:22:50 31819 8192000000 10001633760 10406334464 1086722720 11.711 100 31 62 0 1110331824 16933152 6675952
02:24:28 10589 8192000000 10027793456 10437267456 1091298192 11.755 100 28 55 1 1117872624 21102976 5471456
02:25:39 5928 8192000000 10009748528 10409508864 1092553728 11.767 100 30 56 1 1119632976 20644416 6434832
02:26:50 5166 8192000000 10013191152 10402717696 1091239600 11.754 100 53 108 3 1124126816 20916960 11970256
02:27:58 15560 8192000000 9953083216 10359144448 992714912 10.808 92 17 34 0 1011091344 14674368 3702064
02:29:16 6430 8192000000 9948131392 10342625280 1013150272 11.006 96 43 85 2 1047678960 24269152 10259536
02:30:23 6880 8192000000 9949277968 10337939456 1012670864 11.001 97 30 60 1 1050498240 31519232 6308144
02:31:31 12395 8192000000 9926087456 10317500416 1018784832 11.060 99 37 72 1 1040489584 13123216 8581536
02:32:45 31624 8192000000 9922407264 10323152896 965725408 10.545 94 22 43 2 992406496 22311264 4369824
02:34:18 5095 8192000000 9993055088 10375471104 1049520720 11.356 100 52 104 1 1094883856 33423712 11939424
02:35:24 10457 8192000000 9974742288 10378141696 1060488704 11.461 100 44 89 1 1088523568 18075216 9959648
02:36:39 69767 8192000000 9997975312 10391412736 1071141744 11.563 100 50 98 4 1103300688 21853280 10305664
02:38:59 31994 8192000000 10053368736 10456969216 1094413664 11.785 100 50 100 0 1160919328 55939648 10566016
02:40:40 31227 8192000000 10087763920 10495840256 1107175936 11.906 100 49 98 1 1191791664 74175728 10440000
02:42:27 13113 8192000000 10132736512 10540908544 1125529472 12.079 100 56 112 1 1246376064 108960176 11886416
02:43:45 24559 8192000000 10161888784 10572390400 1137656832 12.193 100 54 108 3 1264373600 115152016 11564752
02:45:15 18855 8192000000 10178625792 10597621760 1148827360 12.298 100 38 76 0 1283632208 126231392 8573456
02:46:41 4228 8192000000 10222683008 10630950912 1156732336 12.373 100 63 126 1 1326670368 156220240 13717792
02:47:56 5521 8192000000 10253532160 10666283008 1161124512 12.414 100 59 117 1 1360147248 185771152 13251584
02:49:07 10919 8192000000 10290984784 10710265856 1172911104 12.524 100 54 108 1 1404276224 218489552 12875568
02:50:27 6010 8192000000 10318979280 10733035520 1168090928 12.479 99 80 154 0 1418058576 233266784 16700864
02:51:39 2952 8192000000 10283273984 10701426688 1168327536 12.481 100 85 169 0 1397519488 210828048 18363904
02:52:49 2533 8192000000 10324781968 10728001536 1179722928 12.588 100 78 154 1 1422888320 225719408 17445984
02:53:58 1215 8192000000 10340535152 10742124544 1179089024 12.582 100 88 172 1 1441072000 242183616 19799360
02:55:05 2956 8192000000 10435149792 10833448960 1183728656 12.625 100 91 182 0 1533927920 329156704 21042560
02:56:14 1496 8192000000 10473727744 10875523072 1190367472 12.687 100 91 181 0 1566295120 354524112 21403536
02:57:22 2944 8192000000 10485257840 10889379840 1193230896 12.713 100 80 159 0 1581728528 369350320 19147312
02:58:32 2375 8192000000 10536313040 10934427648 1188838512 12.673 100 95 186 4 1629525856 418477392 22209952
02:59:41 2272 8192000000 10560052880 10981908480 1197722160 12.755 100 88 176 0 1655911600 437768592 20420848
03:00:52 3950 8192000000 10580668752 11037696000 1195093264 12.731 100 49 97 2 1677729440 469594272 13041904
03:01:57 5419 8192000000 10553595184 10981617664 1189136640 12.675 100 47 94 1 1648997008 447797856 12062512
03:03:04 3991 8192000000 10595263392 11024347136 1187994480 12.665 100 59 116 2 1689277328 486496912 14785936
03:04:16 12889 8192000000 10548371584 10994155520 1184173216 12.629 100 35 70 0 1636658608 442544800 9940592
03:06:12 5613 8192000000 10567144624 11017281536 1153971808 12.347 100 50 99 1 1662574416 495859072 12743536
03:07:29 16655 8192000000 10250726208 10828664832 1141737872 12.232 100 41 82 0 1397290912 244851184 10701856
03:08:56 56232 8192000000 10074537568 10600718336 1090003840 11.743 97 9 18 2 1182280976 90094912 2182224
03:10:53 63767 8192000000 10071772992 10522877952 1102329360 11.860 98 24 48 1 1135199040 27746000 5123680
03:12:58 39762 8192000000 10045603328 10500698112 1113151696 11.962 99 34 70 2 1139401904 18105792 8144416
03:14:39 87089 8192000000 10095546368 10533400576 1123532224 12.060 99 38 77 1 1173154992 41791584 7831184
03:17:22 86112 8192000000 10144264688 10612924416 1089912768 11.742 95 37 72 2 1160040256 62442016 7685472
03:19:55 28241 8192000000 10155974032 10589122560 1142956496 12.243 100 40 81 5 1236983680 85091952 8935232
03:21:26 31170 8192000000 10166604896 10632994816 1136720080 12.185 99 31 62 0 1258072800 114459408 6893312
03:23:06 36099 8192000000 10171937840 10639695872 1123741312 12.062 99 39 78 0 1264311984 132503312 8067360
03:24:52 108681 8192000000 10087910768 10551332864 1128388352 12.106 98 25 51 0 1178036352 44206400 5441600
03:28:27 29043 8192000000 10094644368 10565353472 1151112016 12.320 100 29 52 2 1195903472 39328800 5462656
03:30:36 81198 8192000000 10055844912 10532552704 1112256640 11.954 97 22 44 0 1150470336 33601984 4611712
03:33:04 19138 8192000000 10075675312 10530582528 1143228544 12.246 100 32 63 1 1164603072 15017520 6357008
03:34:24 57899 8192000000 10104000000 10568466432 1137905696 12.196 99 16 32 0 1201292112 59399248 3987168
03:36:33 65581 8192000000 10117646032 10613395456 1127303952 12.096 99 16 32 0 1252819168 122016912 3498304
03:38:41 42385 8192000000 10117437040 10583633920 1127245088 12.095 99 45 90 1 1189609008 53387184 8976736
03:40:26 31388 8192000000 10119702896 10571821056 1125385072 12.078 99 25 48 1 1189744528 59132160 5227296
03:41:57 28209 8192000000 10138889168 10631348224 1124782032 12.072 99 38 76 1 1258146208 125104480 8259696
03:43:28 91078 8192000000 10086626112 10578255872 1070689936 11.559 95 13 26 0 1159161936 85697648 2774352
03:46:00 71404 8192000000 10083429168 10574462976 1094456304 11.785 97 23 46 0 1164805824 65723344 4626176
03:48:38 33102 8192000000 10083211456 10524807168 1113450912 11.965 99 49 98 0 1165818160 42453232 9914016
03:50:12 128879 8192000000 10044140224 10485350400 1079802368 11.646 96 41 82 0 1132883504 45198320 7882816
03:53:26 63851 8192000000 10119147536 10580705280 1102069488 11.857 98 25 50 3 1209902384 101932272 5900624
03:55:39 70546 8192000000 10155018128 10621947904 1096983552 11.809 98 19 37 2 1234560720 133356672 4220496
03:58:24 52195 8192000000 10049955776 10505101312 1109638704 11.929 100 12 25 0 1174578608 62564480 2375424
04:00:29 52919 8192000000 10116028496 10559885312 1134145264 12.160 100 37 74 0 1197779424 55721616 7912544
04:02:25 47294 8192000000 10062576464 10543882240 1124415200 12.069 99 9 18 0 1220018496 93491888 2111408
04:04:13 44402 8192000000 10116878080 10577616896 1119102528 12.019 98 35 70 0 1190947632 65004608 6840496
04:06:06 30456 8192000000 10157197360 10612154368 1144667232 12.259 100 49 98 1 1218037152 63624912 9745008
04:07:54 71886 8192000000 10090569024 10537148416 1097860512 11.817 96 37 72 4 1149799776 43740160 8199104
04:10:06 97124 8192000000 10074177792 10513170432 1128834064 12.110 100 26 53 0 1204280064 69993712 5452288
04:12:47 51860 8192000000 10041455792 10515025920 1125284752 12.077 100 33 66 0 1175488768 43700176 6503840
04:14:44 35955 8192000000 10045123472 10509361152 1114737536 11.977 99 13 27 0 1147626080 29923408 2965136
04:16:20 68308 8192000000 10074432784 10554015744 1109104224 11.924 98 15 30 0 1183741040 71762992 2873824
04:19:01 53510 8192000000 10094012976 10546757632 1121072880 12.037 98 21 41 0 1177235056 51842896 4319280
04:20:59 54828 8192000000 10080056272 10541658112 1137079552 12.188 100 36 73 1 1193420800 48892928 7448320
04:22:54 15765 8192000000 10112940880 10552721408 1138555856 12.202 100 45 90 0 1214598576 67298096 8744624
04:24:11 30221 8192000000 10080150480 10558304256 1129184912 12.114 99 29 58 1 1210729264 75239360 6304992
04:25:41 29946 8192000000 10140881936 10577518592 1118988224 12.017 98 39 78 0 1227191760 100455248 7748288
04:27:12 59338 8192000000 10115572848 10560012288 1100720704 11.844 97 31 62 1 1163811552 57100560 5990288
04:29:56 84432 8192000000 10074874512 10521653248 1116756176 11.996 99 11 22 1 1168048272 48984032 2308064
04:32:21 95849 8192000000 10063597376 10528854016 1110407312 11.936 98 25 50 1 1153319216 37495984 5415920
04:34:57 51709 8192000000 10053145856 10525220864 1132450448 12.144 100 6 12 0 1164089168 30428960 1209760
04:36:51 25540 8192000000 9109472368 9375694848 276121072 3.260 25 2 2 0 281483648 5318960 43616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-09-2021 04:39:09 to 07-09-2021 04:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 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 07-09-2021 04:39:25 to 07-09-2021 04:39:27.

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          239837
MON$OLDEST_ACTIVE               239838
MON$OLDEST_SNAPSHOT             239838
MON$NEXT_TRANSACTION            239838
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                       2062048
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             805
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

07.09.21 04:39:28. 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
2021-09-07 04:39:30 No severe PSQL-related problems occured
07.09.21 04:39:32. End of report.