Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.03.2021 04:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16947356 kB
MemAvailable 31511348 kB
Buffers 20 kB
Cached 14633380 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.0
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-03-15 04:26:57
DTS_END                         2021-03-15 04:26:57.8480
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/51862
TRN_ID                          223505
ATT_ID                          627
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: 8618.95 28448 1034274
customer order: creation 156.41 489 18769
customer order: refuse 13.35 318 1602
order to supplier: creation 41.98 1462 5038
order to supplier: removal 3.47 123843 416
invoice (draft): creation 42.83 3519 5139
invoice (draft): removal 51.34 901 6161
invoice accept: apply 37.92 18899 4550
invoice accept: cancel 45.72 867 5486
customer reserve: creation 3487.68 185 418522
customer reserve: removal 4463.83 77 535659
realization accept: apply 76.47 189 9177
realization accept: cancel 80.03 116 9603
payment from customer: creation 54.08 135 6489
payment from customer: removal 5.21 81 625
payment to supplier: creation 45.25 141 5430
payment to supplier: removal 3.39 134 407
service: total inventory turnovers 3.48 3366 418
service: total monetary turnovers 5.52 582 662
service: aggregate perf. data 0.98 12401 117
service: refresh index statistics 0.03 401250 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-03-2021 04:27:50 to 15-03-2021 04:27:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4751
TEST_TIME 2 4212
TEST_TIME 3 3593
TEST_TIME 4 4244
TEST_TIME 5 4135
TEST_TIME 6 4240
TEST_TIME 7 4634
TEST_TIME 8 4874
TEST_TIME 9 4784
TEST_TIME 10 4996
TEST_TIME 11 5170
TEST_TIME 12 5307
TEST_TIME 13 5246
TEST_TIME 14 5897
TEST_TIME 15 6033
TEST_TIME 16 6108
TEST_TIME 17 6595
TEST_TIME 18 7019
TEST_TIME 19 7429
TEST_TIME 20 7617
TEST_TIME 21 7739
TEST_TIME 22 8222
TEST_TIME 23 8656
TEST_TIME 24 9354
TEST_TIME 25 9510
TEST_TIME 26 10593
TEST_TIME 27 10386
TEST_TIME 28 10141
TEST_TIME 29 10041
TEST_TIME 30 9730
TEST_TIME 31 9542
TEST_TIME 32 9748
TEST_TIME 33 9776
TEST_TIME 34 9688
TEST_TIME 35 9513
TEST_TIME 36 9623
TEST_TIME 37 9679
TEST_TIME 38 9539
TEST_TIME 39 9583
TEST_TIME 40 9514
TEST_TIME 41 9426
TEST_TIME 42 9346
TEST_TIME 43 9317
TEST_TIME 44 9420
TEST_TIME 45 9358
TEST_TIME 46 9368
TEST_TIME 47 9512
TEST_TIME 48 9483
TEST_TIME 49 9466
TEST_TIME 50 9530
TEST_TIME 51 9363
TEST_TIME 52 9200
TEST_TIME 53 9120
TEST_TIME 54 9067
TEST_TIME 55 8954
TEST_TIME 56 8987
TEST_TIME 57 8927
TEST_TIME 58 8913
TEST_TIME 59 8870
TEST_TIME 60 8935
TEST_TIME 61 8878
TEST_TIME 62 8821
TEST_TIME 63 8905
TEST_TIME 64 8870
TEST_TIME 65 8889
TEST_TIME 66 9095
TEST_TIME 67 9078
TEST_TIME 68 9062
TEST_TIME 69 9040
TEST_TIME 70 9006
TEST_TIME 71 9128
TEST_TIME 72 9141
TEST_TIME 73 9041
TEST_TIME 74 8969
TEST_TIME 75 8914
TEST_TIME 76 8841
TEST_TIME 77 8804
TEST_TIME 78 8730
TEST_TIME 79 8683
TEST_TIME 80 8653
TEST_TIME 81 8634
TEST_TIME 82 8601
TEST_TIME 83 8593
TEST_TIME 84 8639
TEST_TIME 85 8769
TEST_TIME 86 8830
TEST_TIME 87 8793
TEST_TIME 88 8907
TEST_TIME 89 8875
TEST_TIME 90 8930
TEST_TIME 91 8853
TEST_TIME 92 8846
TEST_TIME 93 8792
TEST_TIME 94 8748
TEST_TIME 95 8697
TEST_TIME 96 8689
TEST_TIME 97 8682
TEST_TIME 98 8698
TEST_TIME 99 8671
TEST_TIME 100 8685
TEST_TIME 101 8663
TEST_TIME 102 8693
TEST_TIME 103 8653
TEST_TIME 104 8648
TEST_TIME 105 8726
TEST_TIME 106 8795
TEST_TIME 107 8795
TEST_TIME 108 8798
TEST_TIME 109 8748
TEST_TIME 110 8682
TEST_TIME 111 8679
TEST_TIME 112 8632
TEST_TIME 113 8640
TEST_TIME 114 8635
TEST_TIME 115 8644
TEST_TIME 116 8618
TEST_TIME 117 8651
TEST_TIME 118 8647
TEST_TIME 119 8631
TEST_TIME 120 8627

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-03-2021 04:27:53 to 15-03-2021 04:27:57.

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 1424247 1424247 0 0.00 0 41269 7 0 0
doc_list_biud 1424246 1424246 0 0.00 0 71301 36 0 0
sp_add_invoice_to_stock 4709 4550 159 3.37 36 208777 18899 0 159
sp_cancel_adding_invoice 5486 5486 0 0.00 5 81197 867 0 0
sp_cancel_client_order 1602 1602 0 0.00 5 13029 318 0 0
sp_cancel_customer_reserve 535659 535659 0 0.00 1 71303 78 0 0
sp_cancel_pay_from_customer 625 625 0 0.00 3 8343 81 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 2 11564 135 0 0
sp_cancel_supplier_invoice 6173 6161 12 0.19 2 61744 902 0 12
sp_cancel_supplier_order 416 416 0 0.00 7 1227407 123844 0 0
sp_cancel_write_off 9603 9603 0 0.00 1 18158 116 0 0
sp_client_order 18769 18769 0 0.00 4 38965 490 0 0
sp_customer_reserve 418522 418522 0 0.00 0 130481 186 0 0
sp_fill_shopping_cart 28941 28941 0 0.00 1 11545 47 0 0
sp_fill_shopping_cart_clo_res 418514 418514 0 0.00 0 26316 22 0 0
sp_get_clo_for_invoice 4536 4536 0 0.00 0 47288 548 0 0
sp_kill_cost_storno 16121 16121 0 0.00 0 25991 66 0 0
sp_kill_qstorno_ret_qs2qd 1087498 1087498 0 0.00 0 71299 41 0 0
sp_kill_qty_storno 572640 572640 0 0.00 0 71301 88 0 0
sp_lock_selected_doc 19069 19069 0 0.00 0 8854 5 0 0
sp_make_cost_storno 25632 25632 0 0.00 0 20609 64 0 0
sp_multiply_rows_for_pdistr 25632 25632 0 0.00 0 19067 35 0 0
sp_multiply_rows_for_qdistr 18769 18769 0 0.00 0 15678 279 0 0
sp_pay_from_customer 6489 6489 0 0.00 4 26390 136 0 0
sp_pay_to_supplier 5430 5430 0 0.00 5 20135 141 0 0
sp_payment_common 11919 11919 0 0.00 1 19038 28 0 0
sp_qd_handle_on_cancel_clo 1602 1602 0 0.00 0 13023 290 0 0
sp_qd_handle_on_invoice_upd_sts 10022 10022 0 0.00 0 45610 383 0 0
sp_qd_handle_on_reserve_upd_sts 9177 9177 0 0.00 0 14614 56 0 0
sp_reserve_write_off 9177 9177 0 0.00 7 21193 189 0 0
sp_supplier_invoice 5139 5139 0 0.00 8 81586 3519 0 0
sp_supplier_order 5038 5038 0 0.00 6 57327 1463 0 0
srv_aggregate_perf_data 117 117 0 0.00 2524 106411 12402 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1548 147109 50601 0 0
srv_find_qd_qs_mism 991970 991970 0 0.00 0 16083 5 0 0
srv_make_invnt_saldo 418 418 0 0.00 46 117211 3367 0 0
srv_make_money_saldo 662 662 0 0.00 1 16732 582 0 0
srv_recalc_idx_stat 4 4 0 0.00 182999 559277 401250 0 0
t$perf-abend:sp_add_invoice_to_stock 159 159 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4550 4550 0 0.00 0 15194 152 0 0
t$perf-norm:sp_cancel_adding_invoice 346 346 0 0.00 1 2420 81 0 0
t$perf-norm:sp_cancel_client_order 1602 1602 0 0.00 0 5784 17 0 0
t$perf-norm:sp_cancel_customer_reserve 633 633 0 0.00 0 1421 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 625 625 0 0.00 0 2448 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 360 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 457 11 0 0
t$perf-norm:sp_cancel_supplier_order 416 416 0 0.00 0 26136 1439 0 0
t$perf-norm:sp_cancel_write_off 939 939 0 0.00 0 1090 6 0 0
t$perf-norm:sp_client_order 18769 18769 0 0.00 0 7180 15 0 0
t$perf-norm:sp_customer_reserve 897 897 0 0.00 0 10536 18 0 0
t$perf-norm:sp_pay_from_customer 6489 6489 0 0.00 0 6775 9 0 0
t$perf-norm:sp_pay_to_supplier 5430 5430 0 0.00 0 6556 10 0 0
t$perf-norm:sp_reserve_write_off 9177 9177 0 0.00 0 7041 10 0 0
t$perf-norm:sp_supplier_invoice 5139 5139 0 0.00 0 5642 15 0 0
t$perf-norm:sp_supplier_order 5038 5038 0 0.00 0 3391 11 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 745 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 55 2 0 0
t$perf-norm:srv_make_invnt_saldo 418 418 0 0.00 0 313 4 0 0
t$perf-norm:srv_make_money_saldo 662 662 0 0.00 0 953 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9388 9070 318 3.38 0 5822 19 0 318
v_all_customers 33928 33928 0 0.00 0 23 1 0 0
v_all_suppliers 28496 28496 0 0.00 0 16 1 0 0
v_all_wares 37536 37536 0 0.00 0 39 1 0 0
v_cancel_adding_invoice 692 692 0 0.00 0 2686 9 0 0
v_cancel_client_order 3204 3204 0 0.00 0 3586 7 0 0
v_cancel_customer_prepayment 1250 1250 0 0.00 0 2687 9 0 0
v_cancel_customer_reserve 1266 1266 0 0.00 0 121 3 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 4635 17 0 0
v_cancel_supplier_invoice 802 778 24 2.99 0 299 13 0 24
v_cancel_supplier_order 832 832 0 0.00 0 215 3 0 0
v_cancel_write_off 1878 1878 0 0.00 0 2960 13 0 0
v_max_id_clo_ord 5038 5038 0 0.00 0 1748 2 0 0
v_max_id_clo_res 897 897 0 0.00 0 4006 23 0 0
v_max_id_ord_sup 5135 5135 0 0.00 0 5559 6 0 0
v_max_non_paid_invoice 1355 1355 0 0.00 0 452 2 0 0
v_max_non_paid_realizn 3467 3467 0 0.00 0 584 2 0 0
v_min_id_clo_ord 5038 5038 0 0.00 0 6712 7 0 0
v_min_id_clo_res 896 896 0 0.00 0 7353 18 0 0
v_min_id_ord_sup 5135 5135 0 0.00 0 11454 15 0 0
v_min_non_paid_invoice 5430 5430 0 0.00 0 18923 25 0 0
v_min_non_paid_realizn 6489 6489 0 0.00 0 18051 21 0 0
v_our_firm 9652 9652 0 0.00 0 14 1 0 0
v_reserve_write_off 18354 18354 0 0.00 0 9451 6 0 0
x_lock_depdocs_on_canc_invoice 11258 11258 0 0.00 0 43483 113 0 0
x_lock_depdocs_on_canc_sup_ord 416 416 0 0.00 0 7054 311 0 0
x_make_qstorno_1000_1200 5038 5038 0 0.00 2 57313 1385 0 0
x_make_qstorno_1200_2000 5139 5139 0 0.00 4 81577 3337 0 0
x_make_qstorno_2100_3300 418522 418522 0 0.00 0 130473 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-03-2021 04:27:58 to 15-03-2021 04:28:02.

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
01:57:01 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:03 26512 8192000000 9714171536 10007273472 793570352 8.831 100 90 180 0 823702912 8105328 22027232
01:59:31 17674 8192000000 9758142656 10060337152 833920944 9.239 100 87 174 1 866292016 11685040 20686032
02:00:53 27874 8192000000 9795625456 10111954944 859447440 9.495 100 81 160 1 901417568 23441472 18528656
02:02:25 15785 8192000000 9856834176 10186858496 911923216 10.016 100 76 150 1 955861216 27697152 16240848
02:03:44 8824 8192000000 9926274368 10267734016 961655408 10.505 100 82 164 1 1031804368 53334528 16814432
02:04:55 6828 8192000000 9957612160 10320195584 991558928 10.797 100 68 136 0 1075797504 69935776 14302800
02:06:06 2870 8192000000 9999021040 10363686912 1025471456 11.125 100 77 154 1 1104527200 63607216 15448528
02:07:10 2186 8192000000 10016985728 10384273408 1050967536 11.370 100 91 182 1 1119284560 50357904 17959120
02:08:13 1772 8192000000 10034714912 10410356736 1062870320 11.484 100 81 164 0 1148201696 68744448 16586928
02:09:16 10353 8192000000 9986757728 10389528576 1063985008 11.495 100 42 84 2 1106427056 34236720 8205328
02:10:27 3693 8192000000 10015912176 10407448576 1069110480 11.544 100 37 74 0 1133105856 56437856 7557520
02:11:33 8318 8192000000 9981945728 10390597632 1082484320 11.671 100 30 60 0 1109838976 20669728 6684928
02:12:43 52092 8192000000 10007903968 10399551488 1088453648 11.728 100 39 78 2 1118714976 22350880 7910448
02:14:36 6114 8192000000 10014786480 10418958336 1097726672 11.816 100 49 96 1 1130069504 21463824 10879008
02:15:45 7251 8192000000 9995950560 10398035968 1094041392 11.781 100 41 80 1 1121868864 18114992 9712480
02:16:54 9953 8192000000 10016977184 10428284928 1100536544 11.843 100 34 68 2 1127604544 20034576 7033424
02:18:06 62790 8192000000 9978681264 10376269824 1011462848 10.990 93 26 50 4 1044847904 27993072 5391984
02:20:11 4983 8192000000 9935511200 10307354624 956044240 10.450 93 52 103 1 995598384 27457232 12096912
02:21:22 7727 8192000000 9924540288 10308059136 987321424 10.755 97 24 48 2 1012509488 20194016 4994048
02:22:30 12660 8192000000 9927941312 10317361152 998257040 10.862 98 27 54 0 1023135664 19336736 5541888
02:23:44 54057 8192000000 9950261344 10354470912 1034572608 11.212 100 28 56 2 1064637568 23862848 6202112
02:25:40 7302 8192000000 9964181040 10362601472 1045380336 11.316 100 40 80 1 1066296288 12000512 8915440
02:26:48 10400 8192000000 9979078624 10385195008 1054676848 11.406 100 26 52 0 1074467936 14623408 5167680
02:28:09 20290 8192000000 10027185536 10421383168 1088019936 11.724 100 46 92 1 1126217824 28147184 10050704
02:29:34 54689 8192000000 10088668000 10504970240 1105268016 11.888 100 41 82 2 1199116768 85814320 8034432
02:31:30 37136 8192000000 10098978016 10503815168 1118897296 12.017 100 44 88 1 1214235856 86434864 8903696
02:33:15 30782 8192000000 10165368496 10581442560 1135972096 12.178 100 53 106 0 1268241696 120850880 11418720
02:34:55 7577 8192000000 10189887488 10600464384 1156187712 12.368 100 46 87 2 1302328704 135694656 10446336
02:36:04 4300 8192000000 10274873200 10685657088 1167894976 12.477 100 58 114 0 1382816016 202520704 12400336
02:37:13 3842 8192000000 10293582464 10705616896 1174420640 12.538 100 63 124 0 1403544720 214942768 14181312
02:38:20 10710 8192000000 10348150640 10759630848 1177802336 12.570 100 65 127 1 1448837248 255825424 15209488
02:39:49 1547 8192000000 10382043712 10803060736 1193073824 12.712 100 82 162 2 1496372752 283286192 20012736
02:40:56 9034 8192000000 10378327936 10793340928 1196554304 12.744 100 68 136 1 1477761392 265183888 16023200
02:42:10 5624 8192000000 10410822944 10826330112 1184533104 12.632 100 54 108 1 1517298336 319246992 13518240
02:43:22 1851 8192000000 10440189504 10850045952 1158228624 12.387 98 85 165 0 1531893744 353744768 19920352
02:44:30 1658 8192000000 10446630096 10878291968 1174503952 12.539 100 75 148 1 1548264688 354937968 18822768
02:45:36 9306 8192000000 10430286480 10860498944 1155915520 12.365 99 46 93 3 1531385824 364055600 11414704
02:46:52 6135 8192000000 10439902640 10849918976 1163294352 12.434 100 72 144 0 1527151280 346969360 16887568
02:48:01 2920 8192000000 10430803008 10869596160 1146010640 12.272 100 51 102 1 1531346832 372423632 12912560
02:49:09 4259 8192000000 10402250352 10882056192 1146993168 12.281 100 40 79 3 1521449376 364406432 10049776
02:50:26 20614 8192000000 10311260592 10753146880 1131190112 12.133 99 19 38 1 1410984240 274878224 4915904
02:51:49 41164 8192000000 10071805472 10618855424 1110454160 11.937 99 14 28 0 1345828848 231684304 3690384
02:53:35 13796 8192000000 10112987232 10533531648 1143026480 12.244 100 49 96 1 1216748672 63632032 10090160
02:54:51 99365 8192000000 10197258016 10643537920 1107040128 11.904 97 34 68 0 1274717168 159284000 8393040
02:58:39 41553 8192000000 10110790208 10668519424 1097760592 11.816 100 8 16 1 1216756704 117530064 1466048
03:00:23 46193 8192000000 10139981728 10567061504 1115214432 11.982 99 45 87 1 1223070064 98559584 9296048
03:02:11 54031 8192000000 10123324352 10624983040 1117666352 12.005 100 20 40 0 1246112320 123795072 4650896
03:04:33 113589 8192000000 10048940272 10493149184 1111524944 11.947 99 13 24 1 1192947856 78441424 2981488
03:07:38 32180 8192000000 10071089792 10505789440 1125306304 12.077 100 24 48 0 1168708240 38726288 4675648
03:09:18 43550 8192000000 10105069104 10552250368 1127008800 12.093 99 34 68 1 1202984448 69223168 6752480
03:11:02 81204 8192000000 10104103680 10531205120 1104663584 11.882 97 14 28 0 1135193056 27744016 2785456
03:13:29 147109 8192000000 10079382368 10528362496 1104914240 11.884 97 13 26 1 1174599072 67170384 2514448
03:16:57 138250 8192000000 10103690112 10532691968 1144498576 12.258 100 24 48 1 1178464672 28896480 5069616
03:20:31 78710 8192000000 10114932320 10576011264 1131727072 12.138 98 9 19 0 1202322064 68428432 2166560
03:22:52 51998 8192000000 10074453520 10552225792 1141441088 12.229 100 10 20 0 1201153072 57513840 2198144
03:24:56 46041 8192000000 10103607488 10544906240 1146297744 12.275 100 17 34 1 1196141040 46485008 3358288
03:26:54 52990 8192000000 10090696176 10535985152 1143174816 12.245 99 15 30 0 1180097264 33621536 3300912
03:28:47 79957 8192000000 10123437648 10610253824 1163603632 12.437 100 8 16 1 1213023312 47858448 1561232
03:31:08 58373 8192000000 10154838752 10619002880 1129298864 12.115 97 16 32 0 1217518144 84729328 3489952
03:33:06 66118 8192000000 10061223824 10485334016 1107031136 11.904 97 18 36 3 1127465632 16167584 4266912
03:35:16 36614 8192000000 10061635136 10485760000 1090822032 11.750 96 49 99 0 1118134624 17083024 10229568
03:36:53 73264 8192000000 10098914784 10594402304 1129361856 12.115 99 33 66 1 1215465520 79401296 6702368
03:39:14 110331 8192000000 10069011296 10532040704 1138479840 12.201 100 30 60 1 1181284400 37021440 5783120
03:42:08 57613 8192000000 10073028560 10539094016 1139314960 12.209 100 8 16 1 1172068544 30752928 2000656
03:44:48 82193 8192000000 10113057568 10566991872 1107953856 11.913 97 16 32 1 1176160080 64577888 3628336
03:47:42 32373 8192000000 10171587536 10610622464 1136586736 12.183 100 29 58 0 1279630496 136754032 6289728
03:49:15 80196 8192000000 10185623712 10682490880 1131277792 12.133 99 22 44 1 1297108704 160394848 5436064
03:51:36 46531 8192000000 10116024048 10543378432 1108349632 11.917 98 26 50 0 1194849728 80666080 5834016
03:53:32 41946 8192000000 10152273136 10595573760 1129698384 12.119 99 36 72 0 1237814000 100906560 7209056
03:55:18 29398 8192000000 10085092160 10509787136 1118367808 12.012 99 26 52 0 1161207552 37720016 5119728
03:56:50 125237 8192000000 10068676608 10573873152 1123594208 12.061 100 24 48 2 1224728256 96576128 4557920
04:00:25 72729 8192000000 10043412688 10492960768 1093766432 11.778 97 6 11 2 1117309488 22455648 1087408
04:02:40 55264 8192000000 10057449264 10496401408 1099259760 11.831 98 17 33 1 1137043072 34851184 2932128
04:04:35 40699 8192000000 10076259296 10497568768 1122762576 12.053 99 31 62 0 1150012048 21138992 6110480
04:06:21 66014 8192000000 10091038608 10531315712 1135442240 12.173 100 37 74 2 1193354592 49953248 7959104
04:08:27 97552 8192000000 10135939120 10575695872 1132444160 12.144 98 23 45 1 1210046464 71888304 5714000
04:11:10 53595 8192000000 10099361104 10596208640 1136112272 12.179 99 15 30 1 1196002864 56979296 2911296
04:13:04 55444 8192000000 10099455952 10563420160 1107892896 11.912 96 7 14 1 1169794112 60463952 1437264
04:15:06 132600 8192000000 10138720512 10613006336 1142745200 12.241 100 23 46 0 1230044112 82065456 5233456
04:20:47 99504 8192000000 10099594112 10541703168 1131958096 12.140 98 19 38 0 1195376336 59417968 4000272
04:23:39 86802 8192000000 10063677808 10524061696 1112667840 11.958 97 24 48 2 1172275472 54343376 5264256
04:26:07 31141 8192000000 10074608608 10528911360 1098799200 11.826 97 16 32 0 1159696928 57737984 3159744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-03-2021 04:28:04 to 15-03-2021 04:28:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 159 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-03-2021 04:28:19 to 15-03-2021 04:28:21.

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          220626
MON$OLDEST_ACTIVE               223613
MON$OLDEST_SNAPSHOT             223613
MON$NEXT_TRANSACTION            223613
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                       2087200
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             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2387 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:89: 
:90: mscs-test-161.budzdorov.ru    Mon Mar 15 04:26:59 2021
:91:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:92: 
:93: 
:94: mscs-test-161.budzdorov.ru    Mon Mar 15 04:26:59 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
:97: 
:98: mscs-test-161.budzdorov.ru    Mon Mar 15 04:27:01 2021
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:100: 
--- end of diff output ---

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

15.03.21 04:28:22. 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-03-15 04:28:25 No severe PSQL-related problems occured
15.03.21 04:28:26. End of report.