Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.2021 07:47
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 16516088 kB
MemAvailable 31417228 kB
Buffers 0 kB
Cached 15135612 kB
SwapCached 0 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-12-16 07:47:16
DTS_END                         2021-12-16 07:47:16.0890
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/60382
TRN_ID                          228641
ATT_ID                          616
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: 9218.38 24632 1106206
customer order: creation 165.76 440 19891
customer order: refuse 13.93 359 1671
order to supplier: creation 44.96 1349 5395
order to supplier: removal 3.35 120923 402
invoice (draft): creation 44.80 3092 5376
invoice (draft): removal 54.37 1008 6524
invoice accept: apply 39.97 18628 4796
invoice accept: cancel 48.84 873 5861
customer reserve: creation 3749.73 180 449968
customer reserve: removal 4755.43 66 570651
realization accept: apply 82.36 180 9883
realization accept: cancel 89.03 121 10683
payment from customer: creation 58.48 120 7018
payment from customer: removal 5.48 86 658
payment to supplier: creation 48.24 129 5789
payment to supplier: removal 3.16 77 379
service: total inventory turnovers 3.78 3470 454
service: total monetary turnovers 5.84 590 701
service: aggregate perf. data 0.85 17494 102
service: refresh index statistics 0.03 323460 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2021 07:48:17 to 16-12-2021 07:48:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12787
TEST_TIME 2 9016
TEST_TIME 3 7422
TEST_TIME 4 7277
TEST_TIME 5 7059
TEST_TIME 6 6754
TEST_TIME 7 6504
TEST_TIME 8 6548
TEST_TIME 9 6162
TEST_TIME 10 6060
TEST_TIME 11 5913
TEST_TIME 12 6031
TEST_TIME 13 6267
TEST_TIME 14 6663
TEST_TIME 15 7034
TEST_TIME 16 7193
TEST_TIME 17 7616
TEST_TIME 18 7738
TEST_TIME 19 8285
TEST_TIME 20 8503
TEST_TIME 21 8700
TEST_TIME 22 8994
TEST_TIME 23 9013
TEST_TIME 24 9714
TEST_TIME 25 9860
TEST_TIME 26 10942
TEST_TIME 27 11402
TEST_TIME 28 11387
TEST_TIME 29 11278
TEST_TIME 30 11247
TEST_TIME 31 11111
TEST_TIME 32 10980
TEST_TIME 33 11057
TEST_TIME 34 10870
TEST_TIME 35 10611
TEST_TIME 36 10445
TEST_TIME 37 10368
TEST_TIME 38 10273
TEST_TIME 39 10209
TEST_TIME 40 10149
TEST_TIME 41 10189
TEST_TIME 42 10171
TEST_TIME 43 10044
TEST_TIME 44 10027
TEST_TIME 45 9883
TEST_TIME 46 9769
TEST_TIME 47 9863
TEST_TIME 48 9866
TEST_TIME 49 9813
TEST_TIME 50 9815
TEST_TIME 51 9849
TEST_TIME 52 9747
TEST_TIME 53 9805
TEST_TIME 54 9763
TEST_TIME 55 9928
TEST_TIME 56 9868
TEST_TIME 57 9946
TEST_TIME 58 9949
TEST_TIME 59 9874
TEST_TIME 60 9848
TEST_TIME 61 9699
TEST_TIME 62 9767
TEST_TIME 63 9790
TEST_TIME 64 9753
TEST_TIME 65 9643
TEST_TIME 66 9575
TEST_TIME 67 9540
TEST_TIME 68 9593
TEST_TIME 69 9614
TEST_TIME 70 9569
TEST_TIME 71 9588
TEST_TIME 72 9614
TEST_TIME 73 9647
TEST_TIME 74 9647
TEST_TIME 75 9703
TEST_TIME 76 9667
TEST_TIME 77 9727
TEST_TIME 78 9707
TEST_TIME 79 9669
TEST_TIME 80 9647
TEST_TIME 81 9583
TEST_TIME 82 9504
TEST_TIME 83 9551
TEST_TIME 84 9498
TEST_TIME 85 9433
TEST_TIME 86 9354
TEST_TIME 87 9397
TEST_TIME 88 9422
TEST_TIME 89 9376
TEST_TIME 90 9409
TEST_TIME 91 9371
TEST_TIME 92 9336
TEST_TIME 93 9310
TEST_TIME 94 9397
TEST_TIME 95 9470
TEST_TIME 96 9468
TEST_TIME 97 9517
TEST_TIME 98 9536
TEST_TIME 99 9590
TEST_TIME 100 9588
TEST_TIME 101 9497
TEST_TIME 102 9444
TEST_TIME 103 9413
TEST_TIME 104 9374
TEST_TIME 105 9344
TEST_TIME 106 9325
TEST_TIME 107 9308
TEST_TIME 108 9336
TEST_TIME 109 9287
TEST_TIME 110 9270
TEST_TIME 111 9229
TEST_TIME 112 9230
TEST_TIME 113 9242
TEST_TIME 114 9253
TEST_TIME 115 9276
TEST_TIME 116 9270
TEST_TIME 117 9270
TEST_TIME 118 9268
TEST_TIME 119 9253
TEST_TIME 120 9228

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2021 07:48:21 to 16-12-2021 07:48:24.

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 1522520 1522520 0 0.00 0 32479 7 0 0
doc_list_biud 1522520 1522520 0 0.00 0 61333 32 0 0
sp_add_invoice_to_stock 4914 4796 118 2.40 23 224547 18629 0 118
sp_cancel_adding_invoice 5861 5861 0 0.00 5 80022 874 0 0
sp_cancel_client_order 1671 1671 0 0.00 4 13333 360 0 0
sp_cancel_customer_reserve 570651 570651 0 0.00 1 61336 66 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 3 15407 86 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 1854 78 0 0
sp_cancel_supplier_invoice 6532 6524 8 0.12 2 47244 1009 0 8
sp_cancel_supplier_order 402 402 0 0.00 9 1275598 120924 0 0
sp_cancel_write_off 10683 10683 0 0.00 1 15423 121 0 0
sp_client_order 19891 19891 0 0.00 4 70951 441 0 0
sp_customer_reserve 449968 449968 0 0.00 0 48927 181 0 0
sp_fill_shopping_cart 30649 30649 0 0.00 1 13847 40 0 0
sp_fill_shopping_cart_clo_res 449954 449954 0 0.00 0 16528 16 0 0
sp_get_clo_for_invoice 4778 4778 0 0.00 0 37793 346 0 0
sp_kill_cost_storno 17581 17581 0 0.00 0 15403 63 0 0
sp_kill_qstorno_ret_qs2qd 1158911 1158911 0 0.00 0 61330 36 0 0
sp_kill_qty_storno 610452 610452 0 0.00 0 61333 78 0 0
sp_lock_selected_doc 20269 20269 0 0.00 0 9306 4 0 0
sp_make_cost_storno 27467 27467 0 0.00 0 16613 54 0 0
sp_multiply_rows_for_pdistr 27467 27467 0 0.00 0 10113 34 0 0
sp_multiply_rows_for_qdistr 19891 19891 0 0.00 0 68434 221 0 0
sp_pay_from_customer 7018 7018 0 0.00 4 15732 121 0 0
sp_pay_to_supplier 5789 5789 0 0.00 5 15298 129 0 0
sp_payment_common 12807 12807 0 0.00 1 15720 25 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 13327 328 0 0
sp_qd_handle_on_invoice_upd_sts 10638 10638 0 0.00 0 20472 359 0 0
sp_qd_handle_on_reserve_upd_sts 9883 9883 0 0.00 0 17523 54 0 0
sp_reserve_write_off 9883 9883 0 0.00 7 17538 180 0 0
sp_supplier_invoice 5376 5376 0 0.00 6 80175 3093 0 0
sp_supplier_order 5395 5395 0 0.00 5 85883 1349 0 0
srv_aggregate_perf_data 102 102 0 0.00 2990 209596 17495 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1262 211612 45170 0 0
srv_find_qd_qs_mism 1059573 1059573 0 0.00 0 32479 6 0 0
srv_make_invnt_saldo 454 454 0 0.00 79 128701 3471 0 0
srv_make_money_saldo 701 701 0 0.00 1 11167 590 0 0
srv_recalc_idx_stat 4 4 0 0.00 92864 535344 323460 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4796 4796 0 0.00 0 15177 172 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 0 8094 103 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 1914 9 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 675 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 3203 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 2895 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 3000 21 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 24773 1714 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 3221 12 0 0
t$perf-norm:sp_client_order 19891 19891 0 0.00 0 10166 22 0 0
t$perf-norm:sp_customer_reserve 1059 1059 0 0.00 0 348 3 0 0
t$perf-norm:sp_pay_from_customer 7018 7018 0 0.00 0 6651 8 0 0
t$perf-norm:sp_pay_to_supplier 5789 5789 0 0.00 0 19253 13 0 0
t$perf-norm:sp_reserve_write_off 9883 9883 0 0.00 0 7674 13 0 0
t$perf-norm:sp_supplier_invoice 5376 5376 0 0.00 0 6656 20 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 3171 14 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 96 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 244 7 0 0
t$perf-norm:srv_make_invnt_saldo 454 454 0 0.00 0 4401 16 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 2989 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 7 5 0 0
v_add_invoice_to_stock 9788 9552 236 2.41 0 9418 19 0 236
v_all_customers 36460 36460 0 0.00 0 17 1 0 0
v_all_suppliers 30382 30382 0 0.00 0 16 1 0 0
v_all_wares 39782 39782 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 531 7 0 0
v_cancel_client_order 3342 3342 0 0.00 0 3269 10 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 2292 6 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 2716 6 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 1465 9 0 0
v_cancel_supplier_invoice 856 840 16 1.86 0 5196 20 0 16
v_cancel_supplier_order 804 804 0 0.00 0 2896 7 0 0
v_cancel_write_off 2024 2024 0 0.00 0 3837 14 0 0
v_max_id_clo_ord 5395 5395 0 0.00 0 1160 1 0 0
v_max_id_clo_res 1059 1059 0 0.00 0 8394 19 0 0
v_max_id_ord_sup 5363 5363 0 0.00 0 5437 7 0 0
v_max_non_paid_invoice 1356 1356 0 0.00 0 899 3 0 0
v_max_non_paid_realizn 3586 3586 0 0.00 0 1088 3 0 0
v_min_id_clo_ord 5395 5395 0 0.00 0 3104 5 0 0
v_min_id_clo_res 1059 1059 0 0.00 0 3222 6 0 0
v_min_id_ord_sup 5363 5363 0 0.00 0 5279 7 0 0
v_min_non_paid_invoice 5789 5789 0 0.00 0 13520 17 0 0
v_min_non_paid_realizn 7018 7018 0 0.00 0 15716 19 0 0
v_our_firm 10186 10186 0 0.00 0 23 1 0 0
v_reserve_write_off 19766 19766 0 0.00 0 8384 6 0 0
x_lock_depdocs_on_canc_invoice 11965 11965 0 0.00 0 14574 100 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 14748 390 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 2 85820 1270 0 0
x_make_qstorno_1200_2000 5376 5376 0 0.00 2 80113 2942 0 0
x_make_qstorno_2100_3300 449968 449968 0 0.00 0 42277 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2021 07:48:25 to 16-12-2021 07:48:29.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:17:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:20 28231 8192000000 9726477760 10026057728 799113552 8.887 100 83 165 1 826268448 7312272 19842624
05:19:58 20143 8192000000 9764401952 10073829376 838773600 9.287 100 85 170 0 872341552 13223664 20344288
05:21:28 15064 8192000000 9808257456 10130112512 876018496 9.660 100 85 169 0 919074912 23966416 19090000
05:22:47 18640 8192000000 9883968272 10217656320 919858032 10.095 100 82 164 1 991077136 53366160 17852944
05:24:13 8700 8192000000 9952255600 10295242752 960454096 10.493 100 82 163 0 1057112464 79795776 16862592
05:25:25 3515 8192000000 9975338912 10328973312 987142320 10.754 100 81 162 1 1085328192 81766432 16419440
05:26:29 2715 8192000000 9982406800 10344329216 1017446976 11.047 100 81 159 0 1090657408 57062944 16147488
05:27:34 1727 8192000000 10018825152 10383777792 1042989344 11.293 100 86 172 1 1136568992 76490048 17089600
05:28:37 14690 8192000000 10020436080 10397589504 1058552832 11.443 100 71 142 0 1129633776 56855168 14225776
05:29:52 3091 8192000000 9999373168 10391199744 1068005920 11.533 100 40 77 2 1109255984 33148368 8101696
05:30:56 4323 8192000000 10012068000 10406621184 1081729808 11.664 100 48 98 0 1132024256 40304720 9989728
05:32:05 3974 8192000000 10013051392 10402500608 1087609328 11.720 100 41 81 1 1128698288 32381200 8707760
05:33:11 11833 8192000000 10011503216 10398687232 1092408656 11.766 100 68 135 2 1115667824 8565040 14694128
05:34:24 4801 8192000000 10024217952 10436702208 1092589856 11.767 100 41 82 1 1139198720 38343664 8265200
05:35:30 11355 8192000000 10013476576 10422910976 1094616400 11.787 100 26 52 0 1128273248 28064656 5592192
05:36:41 70767 8192000000 10011610816 10430996480 1051394928 11.374 96 30 60 0 1082678688 24887696 6396064
05:38:52 5721 8192000000 9988007568 10365239296 1031627200 11.184 97 62 124 0 1072021968 27462240 12932528
05:39:58 10161 8192000000 9961966560 10356408320 1020878544 11.080 97 28 54 5 1051875152 25206336 5790272
05:41:09 53237 8192000000 9906549600 10283028480 929607024 10.191 90 23 46 0 956867728 21581392 5679312
05:43:19 7444 8192000000 9943957584 10333495296 1020929776 11.081 99 53 102 1 1047325936 14106672 12289488
05:44:29 8726 8192000000 9979472560 10376298496 1049527488 11.356 100 50 101 0 1070563504 9958256 11077760
05:45:41 5306 8192000000 9979821264 10377691136 1058208960 11.439 100 30 61 1 1084594416 19679824 6705632
05:46:49 38008 8192000000 9994976048 10388553728 1068905232 11.542 100 31 60 2 1104287088 29409616 5972240
05:48:31 2902 8192000000 10053599472 10445799424 1100815168 11.845 100 63 124 1 1164903520 50496240 13592112
05:49:37 9252 8192000000 10054026480 10454351872 1111964768 11.951 100 49 99 0 1163343536 41610656 9768112
05:50:51 21544 8192000000 10088001648 10508570624 1128645824 12.109 100 42 82 2 1204834000 66297440 9890736
05:52:16 32405 8192000000 10138384816 10547585024 1140385856 12.219 100 34 68 0 1237221472 89264416 7571200
05:53:56 10120 8192000000 10169956992 10584780800 1155380176 12.360 100 47 94 0 1277742784 111698768 10663840
05:55:09 11104 8192000000 10216504528 10626527232 1175368496 12.547 100 52 103 0 1315473424 128965312 11139616
05:56:32 12421 8192000000 10249621648 10670219264 1185237168 12.639 100 33 66 0 1350854544 157589440 8027936
05:57:49 10746 8192000000 10293319856 10707562496 1186456832 12.650 100 57 110 2 1389504944 189945184 13102928
05:59:03 4669 8192000000 10362048048 10777776128 1183973504 12.627 99 63 126 1 1464319472 265492912 14853056
06:00:13 3768 8192000000 10397635536 10810343424 1190404832 12.687 100 77 153 2 1499788512 291642896 17740784
06:01:23 2399 8192000000 10426213200 10836312064 1192647968 12.708 100 73 142 3 1524205440 314502016 17055456
06:02:31 5697 8192000000 10423031296 10849185792 1189791008 12.681 100 75 148 1 1528775648 321257696 17726944
06:03:42 1879 8192000000 10473237376 10877935616 1199115920 12.768 100 84 167 0 1564587696 346324384 19147392
06:04:49 1261 8192000000 10471503120 10880520192 1184711200 12.634 100 85 166 2 1567060784 361071296 21278288
06:05:57 3162 8192000000 10491010832 10897526784 1160525056 12.408 100 75 150 0 1583457952 404653408 18279488
06:07:10 8650 8192000000 10436207136 10858504192 1145839616 12.270 98 51 99 2 1519304368 361251408 12213344
06:08:24 13590 8192000000 10420772016 10879070208 1132713184 12.147 99 33 66 1 1519783024 377731360 9338480
06:09:55 21147 8192000000 10415382560 10845589504 1102182160 11.858 96 34 68 1 1506499712 395342560 8974992
06:11:30 14926 8192000000 10425896704 10878853120 1136035392 12.178 100 40 78 2 1517991440 371989664 9966384
06:12:45 22500 8192000000 10349113664 10780815360 1122894176 12.054 99 43 86 2 1427099728 293250544 10955008
06:14:09 90573 8192000000 10065408608 10580254720 1109545696 11.928 99 13 26 0 1214321984 101921744 2854544
06:16:41 66054 8192000000 10065052816 10479939584 1082796368 11.674 96 19 38 0 1125943968 39118480 4029120
06:19:01 40014 8192000000 10104029952 10534281216 1134752352 12.166 100 21 42 0 1177426224 38632032 4041840
06:20:42 211612 8192000000 10064205856 10523975680 1087891744 11.723 95 17 34 0 1138478336 46935856 3650736
06:25:46 21873 8192000000 10120430240 10556948480 1115469344 11.984 98 57 114 0 1186146288 58063600 12613344
06:27:08 53078 8192000000 10120268432 10594246656 1141457920 12.229 100 15 30 0 1220256544 75300944 3497680
06:29:02 28458 8192000000 10091955648 10576089088 1128642496 12.109 99 9 18 0 1195665456 65306144 1716816
06:30:31 25090 8192000000 10086035600 10525175808 1141154496 12.226 100 20 40 1 1186791152 41325520 4311136
06:31:59 64182 8192000000 10129643952 10588504064 1149485584 12.305 100 15 30 1 1213681216 60679616 3516016
06:34:15 73597 8192000000 10116376160 10564497408 1138237568 12.199 99 15 30 0 1222689104 81367600 3083936
06:36:37 60894 8192000000 10091382240 10533138432 1153099184 12.339 100 32 64 0 1209656400 50129520 6427696
06:38:38 44668 8192000000 10119349152 10561089536 1155515392 12.361 100 15 30 0 1200172512 41596448 3060672
06:40:22 50357 8192000000 10150186608 10607214592 1139560016 12.211 98 28 55 0 1235133984 89901392 5672576
06:42:13 57052 8192000000 10121810368 10607718400 1145587440 12.268 99 22 45 0 1233756272 82934064 5234768
06:44:11 42142 8192000000 10118827664 10584145920 1143306512 12.247 99 17 34 0 1188334720 41388112 3640096
06:45:55 34164 8192000000 10112237184 10548379648 1134076960 12.160 98 26 53 0 1177007664 37721152 5209552
06:47:30 133832 8192000000 10117140880 10645344256 1152382592 12.332 100 21 43 1 1268880112 111774752 4722768
06:51:02 73752 8192000000 10071068784 10544467968 1127783888 12.100 98 15 30 0 1159816368 29078640 2953840
06:53:16 64715 8192000000 10093276480 10586275840 1091719616 11.759 95 13 26 0 1152356352 58102096 2534640
06:55:21 65361 8192000000 10093526288 10576896000 1125196496 12.076 99 22 44 0 1233139216 102673472 5269248
06:57:28 52940 8192000000 10126149520 10591514624 1118796016 12.016 98 29 58 0 1213557904 88865632 5896256
06:59:30 38941 8192000000 10115472416 10552053760 1117775408 12.006 98 26 52 0 1184158816 61165152 5218256
07:01:09 30717 8192000000 10106321584 10540228608 1126654784 12.090 99 41 82 1 1191765120 56367728 8742608
07:02:41 83278 8192000000 10101703280 10523615232 1069830832 11.550 95 26 50 0 1143879680 69153376 4895472
07:05:06 38547 8192000000 10065808992 10514018304 1091046416 11.753 98 26 52 1 1126490224 30218800 5225008
07:06:50 102996 8192000000 10045974160 10485526528 1064731008 11.502 96 19 38 0 1099100352 30550976 3818368
07:09:35 20019 8192000000 10113320608 10544861184 1107244160 11.906 99 47 94 0 1198127296 81093616 9789520
07:10:55 39057 8192000000 10072140784 10554888192 1111176864 11.944 99 9 18 0 1174174704 61203616 1794224
07:12:50 49625 8192000000 10090654368 10540494848 1105421472 11.889 99 11 22 1 1191032496 83157376 2453648
07:15:01 47157 8192000000 10038144864 10496319488 1068408512 11.537 96 17 33 0 1111910912 40301600 3200800
07:16:49 40544 8192000000 10067035664 10536136704 1118449664 12.012 100 39 78 1 1178185600 51814944 7920992
07:18:38 60626 8192000000 10155266752 10586583040 1111340512 11.945 97 21 42 0 1208940624 93181216 4418896
07:20:43 130668 8192000000 10090029520 10570305536 1112064192 11.952 98 17 34 0 1225413840 109544592 3805056
07:23:54 29902 8192000000 10146831072 10602258432 1112527456 11.956 98 27 52 2 1222465296 104392896 5544944
07:26:34 55843 8192000000 10108353328 10562592768 1121745712 12.043 99 30 60 2 1185716320 58030624 5939984
07:28:35 104046 8192000000 10052757744 10508578816 1107862688 11.912 97 35 69 1 1148451776 33591968 6997120
07:31:20 89960 8192000000 10124095968 10625191936 1134182432 12.161 99 13 26 0 1241736016 104413104 3140480
07:34:47 65005 8192000000 10062712144 10576797696 1126761600 12.091 99 11 22 0 1180110848 50503184 2846064
07:36:52 28006 8192000000 10079118416 10532474880 1143992160 12.253 100 37 73 1 1182981168 32112304 6876704
07:38:25 56943 8192000000 10092531344 10550059008 1129797376 12.119 99 15 30 0 1169696304 36623520 3275408
07:40:26 72895 8192000000 10082970896 10542034944 1149990448 12.309 100 13 26 0 1203365632 50728096 2647088
07:42:54 50975 8192000000 10089007104 10542804992 1117686560 12.005 98 16 32 0 1160742208 39757680 3297968
07:44:50 58173 8192000000 10081899952 10557693952 1093696368 11.778 96 25 50 0 1169832464 71002576 5133520
07:47:03 35510 8192000000 9047211968 9337257984 188454752 2.248 17 2 4 0 209633056 20804192 374112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-12-2021 07:48:31 to 16-12-2021 07:48:46.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2021 07:48:46 to 16-12-2021 07:48:48.

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          229151
MON$OLDEST_ACTIVE               229152
MON$OLDEST_SNAPSHOT             229152
MON$NEXT_TRANSACTION            229152
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                       2045152
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.1.2690 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2690 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2690 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2690 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'

16.12.21 07:48:49. 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-12-16 07:48:52 No severe PSQL-related problems occured
16.12.21 07:48:53. End of report.