Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.08.2021 10:37
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 15774296 kB
MemAvailable 30984040 kB
Buffers 0 kB
Cached 15885012 kB
SwapCached 2580 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-08-16 10:37:05
DTS_END                         2021-08-16 10:37:05.7670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48726
TRN_ID                          233956
ATT_ID                          572
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: 9255.45 21741 1110654
customer order: creation 170.74 421 20489
customer order: refuse 14.13 395 1696
order to supplier: creation 45.37 1633 5444
order to supplier: removal 3.54 114381 425
invoice (draft): creation 45.40 4126 5448
invoice (draft): removal 54.14 981 6497
invoice accept: apply 41.74 19579 5009
invoice accept: cancel 48.58 909 5830
customer reserve: creation 3821.93 193 458632
customer reserve: removal 4717.43 66 566091
realization accept: apply 81.23 263 9748
realization accept: cancel 86.11 128 10333
payment from customer: creation 58.13 148 6976
payment from customer: removal 5.62 115 674
payment to supplier: creation 47.78 169 5734
payment to supplier: removal 3.37 120 404
service: total inventory turnovers 3.42 4829 410
service: total monetary turnovers 5.90 650 708
service: aggregate perf. data 0.85 17808 102
service: refresh index statistics 0.03 267903 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-08-2021 10:37:53 to 16-08-2021 10:37:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4983
TEST_TIME 2 3222
TEST_TIME 3 4512
TEST_TIME 4 4519
TEST_TIME 5 4345
TEST_TIME 6 4650
TEST_TIME 7 5153
TEST_TIME 8 4987
TEST_TIME 9 5492
TEST_TIME 10 5840
TEST_TIME 11 6576
TEST_TIME 12 6790
TEST_TIME 13 6845
TEST_TIME 14 7064
TEST_TIME 15 7009
TEST_TIME 16 7367
TEST_TIME 17 7770
TEST_TIME 18 7772
TEST_TIME 19 7830
TEST_TIME 20 8388
TEST_TIME 21 8627
TEST_TIME 22 8901
TEST_TIME 23 9005
TEST_TIME 24 9203
TEST_TIME 25 9329
TEST_TIME 26 9480
TEST_TIME 27 9778
TEST_TIME 28 10029
TEST_TIME 29 9728
TEST_TIME 30 10125
TEST_TIME 31 10236
TEST_TIME 32 10278
TEST_TIME 33 10650
TEST_TIME 34 10763
TEST_TIME 35 10636
TEST_TIME 36 10623
TEST_TIME 37 10624
TEST_TIME 38 10541
TEST_TIME 39 10389
TEST_TIME 40 10583
TEST_TIME 41 10460
TEST_TIME 42 10314
TEST_TIME 43 10137
TEST_TIME 44 10000
TEST_TIME 45 10078
TEST_TIME 46 10023
TEST_TIME 47 9911
TEST_TIME 48 9868
TEST_TIME 49 9894
TEST_TIME 50 9807
TEST_TIME 51 9848
TEST_TIME 52 9875
TEST_TIME 53 9949
TEST_TIME 54 10006
TEST_TIME 55 9957
TEST_TIME 56 9889
TEST_TIME 57 9747
TEST_TIME 58 9702
TEST_TIME 59 9705
TEST_TIME 60 9668
TEST_TIME 61 9588
TEST_TIME 62 9599
TEST_TIME 63 9538
TEST_TIME 64 9543
TEST_TIME 65 9477
TEST_TIME 66 9480
TEST_TIME 67 9578
TEST_TIME 68 9512
TEST_TIME 69 9484
TEST_TIME 70 9546
TEST_TIME 71 9618
TEST_TIME 72 9708
TEST_TIME 73 9714
TEST_TIME 74 9743
TEST_TIME 75 9749
TEST_TIME 76 9696
TEST_TIME 77 9587
TEST_TIME 78 9593
TEST_TIME 79 9573
TEST_TIME 80 9522
TEST_TIME 81 9523
TEST_TIME 82 9465
TEST_TIME 83 9446
TEST_TIME 84 9390
TEST_TIME 85 9325
TEST_TIME 86 9297
TEST_TIME 87 9316
TEST_TIME 88 9311
TEST_TIME 89 9332
TEST_TIME 90 9359
TEST_TIME 91 9377
TEST_TIME 92 9485
TEST_TIME 93 9498
TEST_TIME 94 9466
TEST_TIME 95 9526
TEST_TIME 96 9519
TEST_TIME 97 9568
TEST_TIME 98 9559
TEST_TIME 99 9565
TEST_TIME 100 9666
TEST_TIME 101 9627
TEST_TIME 102 9552
TEST_TIME 103 9493
TEST_TIME 104 9488
TEST_TIME 105 9437
TEST_TIME 106 9400
TEST_TIME 107 9381
TEST_TIME 108 9330
TEST_TIME 109 9302
TEST_TIME 110 9323
TEST_TIME 111 9302
TEST_TIME 112 9259
TEST_TIME 113 9239
TEST_TIME 114 9288
TEST_TIME 115 9289
TEST_TIME 116 9287
TEST_TIME 117 9299
TEST_TIME 118 9273
TEST_TIME 119 9335
TEST_TIME 120 9258

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-08-2021 10:37:57 to 16-08-2021 10:38:00.

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 1535074 1535074 0 0.00 0 18308 8 0 0
doc_list_biud 1535074 1535074 0 0.00 0 47321 32 0 0
sp_add_invoice_to_stock 5128 5009 119 2.32 24 222232 19579 0 119
sp_cancel_adding_invoice 5830 5830 0 0.00 4 83855 910 0 0
sp_cancel_client_order 1696 1696 0 0.00 5 10086 395 0 0
sp_cancel_customer_reserve 566091 566091 0 0.00 1 24813 66 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 4273 116 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 6556 120 0 0
sp_cancel_supplier_invoice 6506 6497 9 0.13 1 22129 982 0 9
sp_cancel_supplier_order 425 425 0 0.00 7 1069470 114382 0 0
sp_cancel_write_off 10333 10333 0 0.00 1 12368 129 0 0
sp_client_order 20489 20489 0 0.00 4 28501 422 0 0
sp_customer_reserve 458632 458632 0 0.00 0 57908 193 0 0
sp_fill_shopping_cart 31364 31364 0 0.00 1 16586 51 0 0
sp_fill_shopping_cart_clo_res 458624 458624 0 0.00 0 18744 16 0 0
sp_get_clo_for_invoice 5000 5000 0 0.00 0 20679 356 0 0
sp_kill_cost_storno 17241 17241 0 0.00 0 7162 70 0 0
sp_kill_qstorno_ret_qs2qd 1149437 1149437 0 0.00 0 24780 36 0 0
sp_kill_qty_storno 605619 605619 0 0.00 0 47321 79 0 0
sp_lock_selected_doc 20345 20345 0 0.00 0 9054 5 0 0
sp_make_cost_storno 27457 27457 0 0.00 0 17299 79 0 0
sp_multiply_rows_for_pdistr 27457 27457 0 0.00 0 11563 42 0 0
sp_multiply_rows_for_qdistr 20489 20489 0 0.00 0 25885 219 0 0
sp_pay_from_customer 6976 6976 0 0.00 4 22194 149 0 0
sp_pay_to_supplier 5734 5734 0 0.00 5 12909 170 0 0
sp_payment_common 12710 12710 0 0.00 1 12407 41 0 0
sp_qd_handle_on_cancel_clo 1696 1696 0 0.00 0 10071 361 0 0
sp_qd_handle_on_invoice_upd_sts 10829 10829 0 0.00 0 47321 431 0 0
sp_qd_handle_on_reserve_upd_sts 9748 9748 0 0.00 0 20275 84 0 0
sp_reserve_write_off 9748 9748 0 0.00 6 20292 263 0 0
sp_supplier_invoice 5448 5448 0 0.00 7 112148 4127 0 0
sp_supplier_order 5444 5444 0 0.00 7 36082 1633 0 0
srv_aggregate_perf_data 102 102 0 0.00 2249 182439 17809 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1553 140473 40573 0 0
srv_find_qd_qs_mism 1063463 1063463 0 0.00 0 17020 6 0 0
srv_make_invnt_saldo 410 410 0 0.00 42 87392 4829 0 0
srv_make_money_saldo 708 708 0 0.00 1 16597 651 0 0
srv_recalc_idx_stat 4 4 0 0.00 209325 342200 267903 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 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 5009 5009 0 0.00 0 30422 196 0 0
t$perf-norm:sp_cancel_adding_invoice 378 378 0 0.00 1 4671 111 0 0
t$perf-norm:sp_cancel_client_order 1696 1696 0 0.00 0 5289 15 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 2265 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 6981 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 3968 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 5696 29 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 27421 1760 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 4298 17 0 0
t$perf-norm:sp_client_order 20489 20489 0 0.00 0 16202 25 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 8516 15 0 0
t$perf-norm:sp_pay_from_customer 6976 6976 0 0.00 0 12234 12 0 0
t$perf-norm:sp_pay_to_supplier 5734 5734 0 0.00 0 12231 13 0 0
t$perf-norm:sp_reserve_write_off 9748 9748 0 0.00 0 10761 16 0 0
t$perf-norm:sp_supplier_invoice 5448 5448 0 0.00 0 9089 19 0 0
t$perf-norm:sp_supplier_order 5444 5444 0 0.00 0 5066 21 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 737 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 57 2 0 0
t$perf-norm:srv_make_invnt_saldo 410 410 0 0.00 0 4379 28 0 0
t$perf-norm:srv_make_money_saldo 708 708 0 0.00 0 2726 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 277 71 0 0
v_add_invoice_to_stock 10232 9994 238 2.32 0 5443 19 0 238
v_all_customers 37272 37272 0 0.00 0 29 1 0 0
v_all_suppliers 29724 29724 0 0.00 0 18 1 0 0
v_all_wares 40974 40974 0 0.00 0 78 1 0 0
v_cancel_adding_invoice 756 756 0 0.00 0 186 6 0 0
v_cancel_client_order 3392 3392 0 0.00 0 3179 7 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 3936 12 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2585 8 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1270 6 0 0
v_cancel_supplier_invoice 878 860 18 2.05 0 5522 20 0 18
v_cancel_supplier_order 850 850 0 0.00 0 2995 13 0 0
v_cancel_write_off 1878 1878 0 0.00 0 6064 20 0 0
v_max_id_clo_ord 5438 5438 0 0.00 0 3026 4 0 0
v_max_id_clo_res 998 998 0 0.00 0 1482 10 0 0
v_max_id_ord_sup 5439 5439 0 0.00 0 5244 6 0 0
v_max_non_paid_invoice 1749 1749 0 0.00 0 1935 3 0 0
v_max_non_paid_realizn 3661 3661 0 0.00 0 1934 4 0 0
v_min_id_clo_ord 5438 5438 0 0.00 0 16507 14 0 0
v_min_id_clo_res 998 998 0 0.00 0 4648 12 0 0
v_min_id_ord_sup 5439 5439 0 0.00 0 7890 10 0 0
v_min_non_paid_invoice 5734 5734 0 0.00 0 11476 32 0 0
v_min_non_paid_realizn 6976 6976 0 0.00 0 11255 29 0 0
v_our_firm 10332 10332 0 0.00 0 130 1 0 0
v_reserve_write_off 19496 19496 0 0.00 0 7871 9 0 0
x_lock_depdocs_on_canc_invoice 11897 11897 0 0.00 0 14613 130 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 8030 387 0 0
x_make_qstorno_1000_1200 5444 5444 0 0.00 2 35936 1521 0 0
x_make_qstorno_1200_2000 5448 5448 0 0.00 3 111614 3950 0 0
x_make_qstorno_2100_3300 458632 458632 0 0.00 0 57904 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-08-2021 10:38:01 to 16-08-2021 10:38:05.

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
08:07:09 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 14813 8192000000 9712136000 10005983232 790646640 8.801 100 88 176 0 819600672 7559776 21394256
08:09:29 18778 8192000000 9752232048 10057912320 828385632 9.183 100 84 167 2 860411424 11696240 20329552
08:10:55 21996 8192000000 9788215088 10102272000 857736080 9.478 100 84 166 1 894322000 17601856 18984064
08:12:20 16307 8192000000 9852391744 10180206592 903951520 9.937 100 82 164 1 959020448 37461696 17607232
08:13:40 13550 8192000000 9906610784 10249740288 955244096 10.442 100 89 178 0 1024361616 50580720 18536800
08:14:57 5282 8192000000 9947918416 10297962496 989938224 10.781 100 80 159 1 1058493424 52469280 16085920
08:16:05 4480 8192000000 10007465920 10368421888 1020287520 11.075 100 82 162 0 1116394432 80216912 15890000
08:17:12 1995 8192000000 10025040848 10390896640 1040257200 11.267 100 95 188 1 1138941200 79708688 18975312
08:18:15 1340 8192000000 10041857360 10408992768 1059869296 11.455 100 97 194 0 1150142800 69993296 20280208
08:19:18 6177 8192000000 10028020448 10402430976 1065975296 11.514 100 83 166 2 1142774368 59452800 17346272
08:20:25 9276 8192000000 9971685440 10372321280 1068698192 11.540 100 21 42 2 1103633504 29943024 4992288
08:21:36 3042 8192000000 10006763360 10400927744 1082301712 11.669 100 48 97 0 1122577216 29220128 11055376
08:22:41 6862 8192000000 9987005280 10398629888 1084095888 11.686 100 23 46 2 1119501776 30134448 5271440
08:23:55 47004 8192000000 10009734864 10404155392 1088775408 11.731 100 42 84 2 1112401136 14608912 9016816
08:25:46 6459 8192000000 10021200784 10418921472 1079483200 11.643 99 41 82 1 1116204816 27089520 9632096
08:26:56 5512 8192000000 10000673936 10392784896 1084952352 11.695 99 43 85 1 1111324528 17037952 9334224
08:28:02 8342 8192000000 9970656176 10368266240 1040752576 11.272 97 27 54 1 1075512016 28886848 5872592
08:29:12 23024 8192000000 9983927424 10388213760 1040747952 11.272 97 31 62 0 1070186976 21808880 7630144
08:30:35 5775 8192000000 9968075136 10358145024 1019162032 11.064 97 40 79 4 1059604912 32045120 8397760
08:31:42 6501 8192000000 9963660832 10351407104 1030508912 11.173 99 45 90 2 1071076304 29868064 10699328
08:32:49 70600 8192000000 9949798192 10355703808 1026384288 11.134 98 27 54 0 1048053520 15913840 5755392
08:35:01 4442 8192000000 9975879312 10363150336 1059179936 11.449 100 64 124 1 1083864272 10492880 14191456
08:36:08 6292 8192000000 9988036896 10384965632 1072696192 11.578 100 25 47 1 1101592464 23052192 5844080
08:37:17 27164 8192000000 10017775504 10413015040 1096973472 11.809 100 35 70 0 1128663728 24410672 7279584
08:38:57 140472 8192000000 10068898240 10510049280 1123935536 12.064 100 30 59 2 1202611728 72108080 6568112
08:42:35 6177 8192000000 10124955376 10530091008 1133790032 12.157 100 50 99 0 1227421392 82613344 11018016
08:43:47 17172 8192000000 10151309792 10563293184 1142952192 12.243 100 48 96 2 1255984704 102619824 10412688
08:45:13 20433 8192000000 10189844880 10607448064 1151561584 12.324 100 53 106 1 1282468496 118338480 12568432
08:46:39 5439 8192000000 10185352144 10596335616 1163382496 12.435 100 66 130 1 1291665120 113803312 14479312
08:47:48 6188 8192000000 10220670528 10632609792 1161225808 12.415 99 70 137 0 1319739904 143874992 14639104
08:49:04 5666 8192000000 10258614160 10663587840 1171357936 12.510 100 73 146 0 1361105024 173370896 16376192
08:50:14 5014 8192000000 10262917088 10669973504 1161905120 12.421 100 73 146 1 1363469600 186219392 15345088
08:51:25 2550 8192000000 10281783616 10683486208 1159246032 12.396 100 94 188 0 1378164928 197698816 21220080
08:52:33 1957 8192000000 10304987472 10710040576 1154384896 12.351 100 74 148 0 1399361056 227573952 17402208
08:53:40 6985 8192000000 10308856624 10742628352 1156708656 12.372 100 71 142 1 1409233824 235571264 16953904
08:54:50 2016 8192000000 10295078384 10704277504 1148501088 12.295 99 72 143 1 1387698656 222095664 17101904
08:55:57 3765 8192000000 10354195584 10756800512 1163062416 12.432 100 81 154 3 1446893664 265895504 17935744
08:57:10 3906 8192000000 10347517552 10792099840 1147390096 12.285 100 75 151 0 1442693680 276852160 18451424
08:58:18 6444 8192000000 10326436800 10750468096 1162841840 12.430 100 83 165 0 1415971664 234086128 19043696
08:59:28 13024 8192000000 10296543664 10731851776 1160790640 12.411 100 29 57 0 1403097104 234998848 7307616
09:00:45 1552 8192000000 10350675856 10781536256 1154786320 12.354 100 88 173 0 1434117488 258722496 20608672
09:01:51 6527 8192000000 10309840240 10747871232 1139481952 12.211 100 33 65 3 1417776960 269786928 8508080
09:03:04 14907 8192000000 10282099824 10738593792 1121663792 12.043 99 26 52 2 1402286768 273717632 6905344
09:04:33 15359 8192000000 10300435440 10753359872 1135305920 12.171 100 27 54 0 1413635472 271466912 6862640
09:05:52 33390 8192000000 10218207504 10659577856 1127012672 12.093 99 24 48 0 1318126800 185287872 5826256
09:07:40 21979 8192000000 10207920656 10690240512 1146909792 12.280 100 17 34 2 1318489216 167708176 3871248
09:09:05 49353 8192000000 10209591968 10676301824 1152531952 12.333 100 13 26 0 1326589472 171127520 2930000
09:10:56 57129 8192000000 10085777696 10586001408 1104333760 11.879 97 10 21 2 1152121328 46018624 1768944
09:12:55 46187 8192000000 10097719728 10560352256 1118005056 12.008 98 23 46 0 1168374544 45413520 4955968
09:14:55 70270 8192000000 10082021536 10506375168 1118253888 12.010 98 11 21 1 1149048752 29186016 1608848
09:17:06 37989 8192000000 10118588000 10588581888 1114388416 11.974 98 19 40 0 1193998496 74662976 4947104
09:18:45 35294 8192000000 10098716080 10524340224 1137171456 12.189 99 33 65 1 1168144704 23197824 7775424
09:20:32 80933 8192000000 10117059744 10558386176 1125032912 12.075 98 42 82 1 1194207472 60466336 8708224
09:22:54 95909 8192000000 10097120912 10546257920 1129870304 12.120 98 27 52 1 1188250832 52668704 5711824
09:25:31 19236 8192000000 10128070624 10567421952 1146059824 12.272 100 31 63 0 1219832656 66504272 7268560
09:27:01 30276 8192000000 10164949248 10596945920 1133810976 12.157 98 36 72 1 1240091616 98332672 7947968
09:28:39 37563 8192000000 10171982192 10654777344 1151464048 12.323 100 33 66 0 1269867648 110958432 7445168
09:30:21 27912 8192000000 10146823728 10649767936 1128420032 12.106 99 17 34 2 1237215456 105065312 3730112
09:31:51 37579 8192000000 10062052144 10515660800 1103478512 11.871 97 19 38 0 1140065312 32888032 3698768
09:34:56 97938 8192000000 10115556192 10615087104 1146617232 12.278 100 27 54 0 1235236416 82936960 5682224
09:37:38 23491 8192000000 10071723184 10517053440 1151400848 12.323 100 23 46 1 1177163152 21556064 4206240
09:39:10 53872 8192000000 10073537360 10576818176 1136192736 12.180 99 15 30 0 1218650544 79194160 3263648
09:41:04 59471 8192000000 10094376592 10579918848 1142257600 12.237 99 10 20 0 1188982320 44233504 2491216
09:43:25 48567 8192000000 10094040864 10579243008 1133621984 12.155 99 12 24 0 1212527216 76292000 2613232
09:45:26 31478 8192000000 10125402160 10564362240 1091847248 11.760 94 25 50 2 1171577568 74440048 5290272
09:46:58 40841 8192000000 10130968576 10618494976 1122028368 12.046 99 19 38 4 1249620256 122896960 4694928
09:48:39 35032 8192000000 10099867072 10579783680 1127005968 12.093 99 21 42 0 1209273504 77537888 4729648
09:50:20 68243 8192000000 10084430192 10553724928 1108182400 11.915 98 18 36 3 1161072208 48933808 3956000
09:52:52 16766 8192000000 10057523536 10480238592 1097508608 11.814 99 53 105 1 1137628272 29152976 10966688
09:54:11 130598 8192000000 10057820864 10557378560 1102652336 11.863 98 34 68 1 1181456992 71586784 7217872
09:57:31 50387 8192000000 10063681408 10524884992 1117341120 12.002 100 43 86 1 1186117296 58311824 10464352
09:59:34 56476 8192000000 10058539088 10517655552 1111610336 11.948 99 17 34 1 1156663984 41331520 3722128
10:01:38 53332 8192000000 10101853648 10558205952 1122737776 12.053 100 18 36 0 1212097024 85283520 4075728
10:03:59 83060 8192000000 10115965312 10593169408 1116116976 11.990 98 23 46 0 1195680176 74022176 5541024
10:06:27 69450 8192000000 10190230592 10635583488 1151948416 12.328 100 18 36 1 1281883120 125509008 4425696
10:08:37 69984 8192000000 10071076800 10517393408 1108730544 11.920 97 17 33 2 1211856704 99568272 3557888
10:10:51 43772 8192000000 10125132528 10576470016 1138309872 12.200 100 26 52 2 1202674112 59043376 5320864
10:12:35 24975 8192000000 10076963312 10535407616 1120989232 12.036 99 30 58 1 1182319696 55114832 6215632
10:14:00 33691 8192000000 10091982096 10576793600 1137962384 12.196 100 34 68 2 1195579968 51184016 6433568
10:15:35 21053 8192000000 10108207888 10565115904 1146460304 12.276 100 25 50 0 1205270208 53567600 5242304
10:16:58 36585 8192000000 10133513872 10686144512 1132145408 12.142 99 8 16 0 1208409504 74434208 1829888
10:18:39 62700 8192000000 10069854208 10561986560 1136345568 12.181 100 14 28 1 1180891840 41922496 2623776
10:20:43 39068 8192000000 10094970448 10554462208 1113728640 11.968 98 41 82 0 1159658096 36699632 9229824
10:22:32 48030 8192000000 10077763248 10528346112 1123813840 12.063 99 13 26 0 1163544864 36770896 2960128
10:24:26 86693 8192000000 10103502096 10573344768 1132423472 12.144 99 12 24 0 1208166656 72864960 2878224
10:26:52 78162 8192000000 10056014368 10539151360 1116851344 11.997 98 17 34 0 1171068208 50546064 3670800
10:29:11 50098 8192000000 10093646656 10527563776 1134991856 12.168 99 26 52 0 1173981488 33744976 5244656
10:31:04 101652 8192000000 10085301472 10587418624 1088228320 11.726 95 16 31 1 1158908240 67031920 3648000
10:33:46 41324 8192000000 10108788496 10554277888 1098482240 11.823 97 27 53 1 1182435728 78201936 5751552
10:35:33 67776 8192000000 10111162176 10582822912 1135629920 12.174 100 12 24 0 1214717936 76688096 2399920

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-08-2021 10:38:07 to 16-08-2021 10:38:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 16-08-2021 10:38:23 to 16-08-2021 10:38:25.

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          232631
MON$OLDEST_ACTIVE               234069
MON$OLDEST_SNAPSHOT             234069
MON$NEXT_TRANSACTION            234069
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                       2090432
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:95: 
:96: mscs-test-161.budzdorov.ru    Mon Aug 16 10:37:07 2021
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
:99: 
:100: mscs-test-161.budzdorov.ru    Mon Aug 16 10:37:08 2021
:101:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:102: 
:103: 
:104: mscs-test-161.budzdorov.ru    Mon Aug 16 10:37:09 2021
:105:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:106: 
--- end of diff output ---

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

16.08.21 10:38:26. 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-08-16 10:38:29 No severe PSQL-related problems occured
16.08.21 10:38:30. End of report.