Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.10.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 15560848 kB
MemAvailable 30643780 kB
Buffers 20 kB
Cached 16111476 kB
SwapCached 3716 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-10-19 04:37:09
DTS_END                         2021-10-19 04:37:09.7350
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/59546
TRN_ID                          232246
ATT_ID                          673
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9153.92 23804 1098470
customer order: creation 167.48 385 20098
customer order: refuse 14.78 351 1773
order to supplier: creation 44.54 1364 5345
order to supplier: removal 3.43 127873 411
invoice (draft): creation 44.83 3670 5379
invoice (draft): removal 51.38 1155 6166
invoice accept: apply 40.42 19208 4851
invoice accept: cancel 46.59 932 5591
customer reserve: creation 3816.08 185 457930
customer reserve: removal 4630.86 73 555703
realization accept: apply 80.96 207 9715
realization accept: cancel 87.68 129 10522
payment from customer: creation 58.32 118 6998
payment from customer: removal 5.52 73 662
payment to supplier: creation 47.78 142 5733
payment to supplier: removal 3.16 74 379
service: total inventory turnovers 3.40 4103 408
service: total monetary turnovers 5.86 598 703
service: aggregate perf. data 0.83 16169 99
service: refresh index statistics 0.03 299261 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-10-2021 04:39:11 to 19-10-2021 04:39:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3436
TEST_TIME 2 3721
TEST_TIME 3 4485
TEST_TIME 4 4578
TEST_TIME 5 4663
TEST_TIME 6 4522
TEST_TIME 7 4979
TEST_TIME 8 4842
TEST_TIME 9 5075
TEST_TIME 10 5230
TEST_TIME 11 5262
TEST_TIME 12 5637
TEST_TIME 13 5636
TEST_TIME 14 6015
TEST_TIME 15 6452
TEST_TIME 16 6747
TEST_TIME 17 6782
TEST_TIME 18 7308
TEST_TIME 19 7287
TEST_TIME 20 7521
TEST_TIME 21 7804
TEST_TIME 22 7789
TEST_TIME 23 7804
TEST_TIME 24 8107
TEST_TIME 25 8822
TEST_TIME 26 9178
TEST_TIME 27 9993
TEST_TIME 28 10509
TEST_TIME 29 10778
TEST_TIME 30 10902
TEST_TIME 31 10676
TEST_TIME 32 10689
TEST_TIME 33 10584
TEST_TIME 34 10788
TEST_TIME 35 10668
TEST_TIME 36 10546
TEST_TIME 37 10571
TEST_TIME 38 10551
TEST_TIME 39 10546
TEST_TIME 40 10490
TEST_TIME 41 10433
TEST_TIME 42 10404
TEST_TIME 43 10206
TEST_TIME 44 10054
TEST_TIME 45 9936
TEST_TIME 46 9925
TEST_TIME 47 9804
TEST_TIME 48 9786
TEST_TIME 49 9701
TEST_TIME 50 9690
TEST_TIME 51 9716
TEST_TIME 52 9704
TEST_TIME 53 9753
TEST_TIME 54 9782
TEST_TIME 55 9688
TEST_TIME 56 9705
TEST_TIME 57 9706
TEST_TIME 58 9657
TEST_TIME 59 9538
TEST_TIME 60 9488
TEST_TIME 61 9437
TEST_TIME 62 9356
TEST_TIME 63 9329
TEST_TIME 64 9301
TEST_TIME 65 9338
TEST_TIME 66 9307
TEST_TIME 67 9269
TEST_TIME 68 9285
TEST_TIME 69 9504
TEST_TIME 70 9494
TEST_TIME 71 9477
TEST_TIME 72 9460
TEST_TIME 73 9534
TEST_TIME 74 9569
TEST_TIME 75 9620
TEST_TIME 76 9577
TEST_TIME 77 9494
TEST_TIME 78 9498
TEST_TIME 79 9439
TEST_TIME 80 9407
TEST_TIME 81 9401
TEST_TIME 82 9396
TEST_TIME 83 9350
TEST_TIME 84 9286
TEST_TIME 85 9267
TEST_TIME 86 9217
TEST_TIME 87 9239
TEST_TIME 88 9200
TEST_TIME 89 9171
TEST_TIME 90 9259
TEST_TIME 91 9216
TEST_TIME 92 9255
TEST_TIME 93 9290
TEST_TIME 94 9273
TEST_TIME 95 9348
TEST_TIME 96 9341
TEST_TIME 97 9343
TEST_TIME 98 9389
TEST_TIME 99 9395
TEST_TIME 100 9350
TEST_TIME 101 9286
TEST_TIME 102 9275
TEST_TIME 103 9231
TEST_TIME 104 9200
TEST_TIME 105 9212
TEST_TIME 106 9178
TEST_TIME 107 9132
TEST_TIME 108 9188
TEST_TIME 109 9154
TEST_TIME 110 9146
TEST_TIME 111 9137
TEST_TIME 112 9146
TEST_TIME 113 9184
TEST_TIME 114 9192
TEST_TIME 115 9174
TEST_TIME 116 9223
TEST_TIME 117 9209
TEST_TIME 118 9242
TEST_TIME 119 9226
TEST_TIME 120 9156

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-10-2021 04:39:14 to 19-10-2021 04:39:18.

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 1522710 1522710 0 0.00 0 24728 7 0 0
doc_list_biud 1522707 1522707 0 0.00 0 38172 34 0 0
sp_add_invoice_to_stock 5026 4851 175 3.48 31 221897 19208 0 175
sp_cancel_adding_invoice 5591 5591 0 0.00 6 64881 933 0 0
sp_cancel_client_order 1773 1773 0 0.00 5 9923 351 0 0
sp_cancel_customer_reserve 555703 555703 0 0.00 1 38174 73 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 3372 73 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 2 2494 74 0 0
sp_cancel_supplier_invoice 6187 6166 21 0.33 3 31586 1156 0 21
sp_cancel_supplier_order 411 411 0 0.00 8 1406847 127874 0 0
sp_cancel_write_off 10522 10522 0 0.00 1 12431 129 0 0
sp_client_order 20098 20098 0 0.00 4 22027 385 0 0
sp_customer_reserve 457930 457930 0 0.00 0 72223 185 0 0
sp_fill_shopping_cart 30801 30801 0 0.00 1 18075 42 0 0
sp_fill_shopping_cart_clo_res 457918 457918 0 0.00 0 34653 16 0 0
sp_get_clo_for_invoice 4839 4839 0 0.00 0 37855 374 0 0
sp_kill_cost_storno 17154 17154 0 0.00 0 24717 69 0 0
sp_kill_qstorno_ret_qs2qd 1128505 1128505 0 0.00 0 38170 40 0 0
sp_kill_qty_storno 594718 594718 0 0.00 0 38172 86 0 0
sp_lock_selected_doc 20233 20233 0 0.00 0 9884 7 0 0
sp_make_cost_storno 27285 27285 0 0.00 0 16300 63 0 0
sp_multiply_rows_for_pdistr 27283 27283 0 0.00 0 8229 31 0 0
sp_multiply_rows_for_qdistr 20098 20098 0 0.00 0 16791 201 0 0
sp_pay_from_customer 6998 6998 0 0.00 4 15445 118 0 0
sp_pay_to_supplier 5733 5733 0 0.00 5 22351 142 0 0
sp_payment_common 12731 12731 0 0.00 1 20084 24 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 9895 305 0 0
sp_qd_handle_on_invoice_upd_sts 10428 10428 0 0.00 0 36081 398 0 0
sp_qd_handle_on_reserve_upd_sts 9715 9715 0 0.00 0 20699 67 0 0
sp_reserve_write_off 9715 9715 0 0.00 7 20750 208 0 0
sp_supplier_invoice 5379 5379 0 0.00 6 101969 3671 0 0
sp_supplier_order 5345 5345 0 0.00 6 24638 1365 0 0
srv_aggregate_perf_data 99 99 0 0.00 2938 133791 16169 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 963 139388 41534 0 0
srv_find_qd_qs_mism 1051842 1051842 0 0.00 0 12491 6 0 0
srv_make_invnt_saldo 408 408 0 0.00 50 99446 4103 0 0
srv_make_money_saldo 703 703 0 0.00 1 15407 599 0 0
srv_recalc_idx_stat 4 4 0 0.00 186694 420376 299261 0 0
t$perf-abend:sp_add_invoice_to_stock 175 175 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 21 21 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4852 4852 0 0.00 0 14060 173 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 5503 123 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 5862 17 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 2119 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 2468 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 4511 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 2557 29 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 24793 1797 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 2477 9 0 0
t$perf-norm:sp_client_order 20098 20098 0 0.00 0 10380 22 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 4467 9 0 0
t$perf-norm:sp_pay_from_customer 6998 6998 0 0.00 0 15658 12 0 0
t$perf-norm:sp_pay_to_supplier 5733 5733 0 0.00 0 4074 9 0 0
t$perf-norm:sp_reserve_write_off 9715 9715 0 0.00 0 9513 12 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 9494 21 0 0
t$perf-norm:sp_supplier_order 5345 5345 0 0.00 0 4959 18 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 339 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 255 6 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 2140 13 0 0
t$perf-norm:srv_make_money_saldo 703 703 0 0.00 0 296 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 2 0 0
v_add_invoice_to_stock 10024 9674 350 3.49 0 10320 21 0 350
v_all_customers 37060 37060 0 0.00 0 74 1 0 0
v_all_suppliers 30154 30154 0 0.00 0 28 1 0 0
v_all_wares 40194 40194 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 267 6 0 0
v_cancel_client_order 3546 3546 0 0.00 0 3765 7 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2116 8 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 2327 5 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2075 7 0 0
v_cancel_supplier_invoice 776 734 42 5.41 0 3307 24 0 42
v_cancel_supplier_order 822 822 0 0.00 0 10876 18 0 0
v_cancel_write_off 2052 2052 0 0.00 0 4426 21 0 0
v_max_id_clo_ord 5338 5338 0 0.00 0 745 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 878 10 0 0
v_max_id_ord_sup 5366 5366 0 0.00 0 6049 6 0 0
v_max_non_paid_invoice 1360 1360 0 0.00 0 378 3 0 0
v_max_non_paid_realizn 3502 3502 0 0.00 0 521 2 0 0
v_min_id_clo_ord 5338 5338 0 0.00 0 2331 4 0 0
v_min_id_clo_res 992 992 0 0.00 0 5926 26 0 0
v_min_id_ord_sup 5366 5366 0 0.00 0 7043 15 0 0
v_min_non_paid_invoice 5733 5733 0 0.00 0 20078 21 0 0
v_min_non_paid_realizn 6998 6998 0 0.00 0 7770 15 0 0
v_our_firm 10126 10126 0 0.00 0 15 1 0 0
v_reserve_write_off 19430 19430 0 0.00 0 10949 7 0 0
x_lock_depdocs_on_canc_invoice 11390 11390 0 0.00 0 16481 122 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 7936 355 0 0
x_make_qstorno_1000_1200 5345 5345 0 0.00 2 24630 1285 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 2 101860 3512 0 0
x_make_qstorno_2100_3300 457930 457930 0 0.00 0 72221 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-10-2021 04:39:19 to 19-10-2021 04:39:23.

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:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 24413 8192000000 9730054720 10029785088 799605040 8.892 100 81 162 0 827531040 7627216 20298784
02:09:48 22851 8192000000 9770272032 10077831168 840619712 9.306 100 90 178 1 875087760 13003888 21464160
02:11:18 17492 8192000000 9804279264 10124259328 871180880 9.612 100 85 170 0 914192400 23451392 19560128
02:12:41 16147 8192000000 9874382288 10208538624 914038544 10.037 100 73 146 0 979595952 49762928 15794480
02:14:05 7388 8192000000 9923757824 10265255936 956408368 10.454 100 80 160 1 1037429216 64230112 16790736
02:15:16 4624 8192000000 9953487680 10306543616 996528816 10.845 100 88 177 0 1068723648 54324928 17869904
02:16:23 3832 8192000000 9993551328 10358804480 1022882528 11.100 100 81 160 1 1104758784 65239680 16636576
02:17:29 1696 8192000000 10013001840 10381795328 1047856064 11.340 100 91 180 0 1132319904 65889856 18573984
02:18:33 1821 8192000000 10035374160 10403991552 1064656512 11.501 100 91 183 0 1139902464 57550032 17695920
02:19:37 6085 8192000000 10004675792 10387460096 1071739920 11.569 100 50 98 0 1128876336 47161488 9974928
02:20:44 1402 8192000000 10020603248 10395877376 1080075856 11.648 100 92 186 3 1122050272 22308704 19665712
02:21:46 3170 8192000000 10000707280 10401992704 1084623936 11.692 100 42 83 2 1121331696 27882912 8824848
02:22:52 4820 8192000000 9999026272 10390888448 1088705152 11.730 100 44 85 2 1107742752 9314928 9722672
02:23:57 21764 8192000000 10011534784 10402750464 1092617984 11.768 100 72 146 4 1124722208 16556112 15548112
02:25:20 7524 8192000000 10029515936 10429227008 1103264976 11.869 100 52 101 1 1135500272 21733328 10501968
02:26:30 3107 8192000000 10018893904 10416521216 1101246608 11.849 100 43 82 0 1128634448 16676432 10711408
02:27:35 57394 8192000000 9984268544 10386472960 1060719136 11.463 98 43 86 2 1083725568 13981104 9025328
02:29:36 6624 8192000000 9969567184 10358026240 1041040416 11.275 98 49 97 2 1079667856 27420384 11207056
02:30:46 5916 8192000000 9939736224 10324176896 1017344224 11.046 97 42 80 2 1049826736 22519280 9963232
02:31:54 4074 8192000000 9949821776 10323644416 1027936064 11.149 100 37 74 2 1066757680 29340544 9481072
02:33:00 33686 8192000000 9938949008 10334683136 987394160 10.756 96 20 40 0 1025714528 34430128 3890240
02:34:36 5204 8192000000 9968541296 10362060800 1052309008 11.383 100 39 77 1 1081245296 20271312 8664976
02:35:42 6342 8192000000 9996732800 10389643264 1067837904 11.531 100 49 100 0 1102024608 23483072 10703632
02:36:50 14068 8192000000 10000537216 10404319232 1075668400 11.606 100 43 85 1 1096550464 10627440 10254624
02:38:07 83634 8192000000 10033392752 10428903424 1095935840 11.799 100 48 93 1 1121343504 15985424 9422240
02:40:33 12342 8192000000 10097744480 10502713344 1122522752 12.051 100 54 106 0 1204270896 71422432 10325712
02:41:50 14153 8192000000 10119244912 10527784960 1130870656 12.130 100 40 80 1 1228992992 89651296 8471040
02:43:08 17198 8192000000 10184347600 10589573120 1147428016 12.285 100 56 112 0 1282743776 123383616 11932144
02:44:29 13708 8192000000 10186918832 10602012672 1160702416 12.410 100 46 92 1 1300929248 129681536 10545296
02:45:45 17063 8192000000 10233775232 10653102080 1159902656 12.402 99 51 102 0 1338549728 166283776 12363296
02:47:11 7034 8192000000 10291561072 10698317824 1174136736 12.535 100 60 120 0 1394252320 206163344 13952240
02:48:27 10647 8192000000 10348750112 10755547136 1183572464 12.624 100 85 168 1 1448318416 245694816 19051136
02:49:57 18360 8192000000 10384905248 10795610112 1172648448 12.522 99 66 132 0 1465164944 277480832 15035664
02:51:27 10366 8192000000 10418665952 10839924736 1186776832 12.653 100 67 134 0 1520240128 317183408 16279888
02:52:47 2834 8192000000 10422729552 10835161088 1183494592 12.623 99 81 158 2 1517616208 315992784 18128832
02:53:55 961 8192000000 10439463552 10849198080 1198897536 12.766 100 85 170 1 1540486880 321453408 20135936
02:55:01 2262 8192000000 10467635120 10887065600 1184209392 12.629 100 78 156 0 1569406528 367125488 18071648
02:56:07 5487 8192000000 10453472144 10869694464 1162370096 12.425 99 47 94 1 1549410528 375753472 11286960
02:57:20 2729 8192000000 10515305424 10930429952 1173323376 12.528 100 58 113 1 1611749232 425262512 13163344
02:58:29 4661 8192000000 10483294944 10899746816 1164768752 12.448 100 49 97 1 1581417520 404695856 11952912
02:59:39 10424 8192000000 10518743312 10959499264 1157542960 12.380 99 43 86 0 1621024048 451880832 11600256
03:01:02 7563 8192000000 10547761360 10974068736 1136709632 12.185 99 64 129 0 1630697488 478025008 15962848
03:02:22 5784 8192000000 10470699360 10947903488 1123591536 12.061 99 52 104 1 1557789216 420485712 13711968
03:03:39 18414 8192000000 10415737200 10866753536 1143102480 12.245 100 24 48 1 1504302480 354643120 6556880
03:05:07 31981 8192000000 10162769696 10682851328 1113532064 11.966 99 10 20 0 1268485712 152647024 2306624
03:07:44 70685 8192000000 10105177104 10541940736 1118773792 12.015 100 14 28 0 1210249072 88867072 2608208
03:09:55 49695 8192000000 10125035280 10548166656 1140646624 12.222 100 26 53 1 1207869856 61780384 5442848
03:11:55 68007 8192000000 10078328464 10514272256 1099293104 11.831 97 19 36 5 1123142752 20507840 3341808
03:14:09 54009 8192000000 10061406736 10483126272 1093977856 11.780 97 31 60 0 1128392800 28027856 6387088
03:16:05 139388 8192000000 10069972064 10531364864 1087167888 11.716 96 9 18 0 1122641152 33881424 1591840
03:19:25 131333 8192000000 10092039328 10522587136 1121288432 12.039 99 23 46 1 1156386784 29620560 5477792
03:22:48 7873 8192000000 10102010176 10529505280 1132115200 12.141 100 52 104 2 1196494624 52722288 11657136
03:23:57 50144 8192000000 10082599648 10520436736 1141406160 12.229 100 21 40 1 1175723360 29831904 4485296
03:25:50 27365 8192000000 10138727872 10579189760 1154490544 12.352 100 29 58 0 1237602704 76891360 6220800
03:27:22 23968 8192000000 10160074352 10599325696 1161142176 12.414 100 36 71 1 1251036832 82778704 7115952
03:28:49 37547 8192000000 10178401152 10677923840 1160087184 12.404 100 18 36 0 1280071248 116133568 3850496
03:30:27 40884 8192000000 10134718944 10612105216 1144948864 12.262 99 26 51 1 1230480352 79499504 6031984
03:32:08 49493 8192000000 10123967488 10578685952 1151270576 12.321 100 19 38 2 1215324128 59468000 4585552
03:33:58 53728 8192000000 10139961952 10621325312 1130274000 12.124 97 29 58 0 1237131696 99650848 7206848
03:36:58 89310 8192000000 10087542512 10558226432 1117203728 12.001 97 26 52 2 1192216784 69614080 5398976
03:39:55 35515 8192000000 10073206864 10567618560 1137795088 12.195 100 9 18 0 1166218176 26850560 1572528
03:42:56 38493 8192000000 10095066624 10539794432 1086904288 11.713 95 29 58 0 1142990240 49657872 6428080
03:44:38 22440 8192000000 10151807776 10576412672 1134828416 12.167 100 45 90 1 1239523248 95287424 9407408
03:46:01 29325 8192000000 10103603168 10629345280 1119824176 12.025 100 17 34 1 1229124624 105841792 3458656
03:47:31 46908 8192000000 10099934528 10538418176 1084677888 11.692 96 24 48 1 1117188720 27945168 4565664
03:49:38 43806 8192000000 10099649312 10587648000 1090850864 11.751 96 31 62 0 1168693536 71809904 6032768
03:51:28 104838 8192000000 10076530016 10557927424 1053637744 11.396 95 11 22 1 1126643360 70992320 2013296
03:54:16 110527 8192000000 10068702560 10498396160 1090247232 11.745 98 29 57 3 1146034432 49394720 6392480
03:57:15 31578 8192000000 10104107888 10543669248 1125351520 12.078 100 35 70 0 1215048512 82058176 7638816
03:58:48 57217 8192000000 10049603408 10527121408 1112952848 11.960 99 17 34 0 1164054112 47816272 3284992
04:00:46 94195 8192000000 10087950592 10544029696 1128590560 12.108 100 13 26 0 1196573600 65140144 2842896
04:03:24 64797 8192000000 10047050448 10525626368 1119245504 12.020 99 16 31 1 1162183904 39791184 3147216
04:05:37 31215 8192000000 10089647888 10521612288 1131516784 12.136 99 37 74 0 1172120112 32916480 7686848
04:07:13 53626 8192000000 10090115808 10584547328 1126730112 12.091 99 18 36 1 1245496208 114659680 4106416
04:09:20 46024 8192000000 10089217584 10526367744 1112623888 11.957 98 23 46 0 1183059280 66132336 4303056
04:11:13 24334 8192000000 10117203120 10543222784 1106507088 11.899 97 52 104 1 1173094912 56403888 10183936
04:12:40 56558 8192000000 10098971696 10573008896 1129156544 12.113 99 15 30 0 1172439424 40167792 3115088
04:14:37 45465 8192000000 10121246608 10573402112 1131367728 12.134 98 36 72 0 1206721056 68083808 7269520
04:16:24 60466 8192000000 10079169568 10510979072 1118832848 12.016 97 14 28 0 1151579568 29669856 3076864
04:18:27 87714 8192000000 10084046048 10561765376 1148070176 12.291 100 23 46 0 1205554480 52080880 5403424
04:20:57 49994 8192000000 10104383504 10571882496 1152168432 12.330 100 20 40 1 1219954752 64018976 3767344
04:22:59 50733 8192000000 10097077072 10557153280 1123754640 12.062 97 12 24 0 1176265824 49763152 2748032
04:24:50 92061 8192000000 10090213760 10602762240 1125479152 12.079 98 7 14 0 1169840640 43029904 1331584
04:27:28 42968 8192000000 10093212976 10535714816 1119695552 12.024 98 31 62 0 1161044016 34739088 6609376
04:29:16 27546 8192000000 10107217696 10574061568 1126003776 12.084 98 19 39 2 1193035328 63059136 3972416
04:30:53 58851 8192000000 10059664416 10527838208 1091857936 11.760 96 16 32 0 1150293424 55187680 3247808
04:32:55 35079 8192000000 10104928560 10571726848 1107902240 11.913 98 30 60 1 1217453120 102710496 6840384
04:34:38 19756 8192000000 10101871024 10537803776 1119582432 12.023 99 44 87 2 1185832688 57920272 8329984
04:35:58 46540 8192000000 10069231152 10511220736 1065186944 11.506 95 31 62 0 1116904624 45923552 5794128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-10-2021 04:39:25 to 19-10-2021 04:39:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 175 v_add_invoice_to_stock 335544517
except 21 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-10-2021 04:39:41 to 19-10-2021 04:39:43.

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          231076
MON$OLDEST_ACTIVE               233246
MON$OLDEST_SNAPSHOT             233246
MON$NEXT_TRANSACTION            233246
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                       2070176
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:313: 
:314: mscs-test-161.budzdorov.ru    Tue Oct 19 04:37:16 2021
:315:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:316: 
:317: 
:318: mscs-test-161.budzdorov.ru    Tue Oct 19 04:37:28 2021
:319:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:320: 
--- end of diff output ---

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

19.10.21 04:39:44. 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-10-19 04:39:46 No severe PSQL-related problems occured
19.10.21 04:39:48. End of report.