Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.04.2022 07:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16274380 kB
MemAvailable 31582992 kB
Buffers 0 kB
Cached 15464564 kB
SwapCached 2720 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.2
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 2022-04-21 07:37:15
DTS_END                         2022-04-21 07:37:15.8110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/55434
TRN_ID                          211602
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: 8389.62 30590 1006754
customer order: creation 157.14 519 18857
customer order: refuse 14.29 509 1715
order to supplier: creation 42.13 1631 5055
order to supplier: removal 3.19 199757 383
invoice (draft): creation 42.66 4019 5119
invoice (draft): removal 46.93 1177 5631
invoice accept: apply 38.38 29029 4605
invoice accept: cancel 41.98 1548 5037
customer reserve: creation 3593.83 270 431259
customer reserve: removal 4138.23 121 496587
realization accept: apply 78.08 317 9369
realization accept: cancel 72.72 208 8727
payment from customer: creation 54.85 215 6582
payment from customer: removal 4.97 146 597
payment to supplier: creation 45.31 213 5437
payment to supplier: removal 3.30 160 396
service: total inventory turnovers 3.53 5389 423
service: total monetary turnovers 7.23 481 868
service: aggregate perf. data 0.85 19829 102
service: refresh index statistics 0.04 346258 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-04-2022 07:39:30 to 21-04-2022 07:39:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6848
TEST_TIME 2 5283
TEST_TIME 3 4563
TEST_TIME 4 4893
TEST_TIME 5 5859
TEST_TIME 6 6162
TEST_TIME 7 6363
TEST_TIME 8 6480
TEST_TIME 9 6449
TEST_TIME 10 6883
TEST_TIME 11 6778
TEST_TIME 12 6775
TEST_TIME 13 6687
TEST_TIME 14 6684
TEST_TIME 15 6977
TEST_TIME 16 7288
TEST_TIME 17 7374
TEST_TIME 18 7566
TEST_TIME 19 7753
TEST_TIME 20 8029
TEST_TIME 21 8080
TEST_TIME 22 7983
TEST_TIME 23 7913
TEST_TIME 24 8127
TEST_TIME 25 8301
TEST_TIME 26 8239
TEST_TIME 27 8387
TEST_TIME 28 8348
TEST_TIME 29 8432
TEST_TIME 30 8441
TEST_TIME 31 8332
TEST_TIME 32 8252
TEST_TIME 33 8762
TEST_TIME 34 8899
TEST_TIME 35 9388
TEST_TIME 36 9652
TEST_TIME 37 9764
TEST_TIME 38 9863
TEST_TIME 39 10296
TEST_TIME 40 10173
TEST_TIME 41 10146
TEST_TIME 42 9943
TEST_TIME 43 9891
TEST_TIME 44 9998
TEST_TIME 45 9937
TEST_TIME 46 9859
TEST_TIME 47 9745
TEST_TIME 48 9607
TEST_TIME 49 9500
TEST_TIME 50 9425
TEST_TIME 51 9459
TEST_TIME 52 9481
TEST_TIME 53 9335
TEST_TIME 54 9261
TEST_TIME 55 9249
TEST_TIME 56 9214
TEST_TIME 57 9072
TEST_TIME 58 8993
TEST_TIME 59 8898
TEST_TIME 60 8884
TEST_TIME 61 9002
TEST_TIME 62 8918
TEST_TIME 63 8852
TEST_TIME 64 8790
TEST_TIME 65 8725
TEST_TIME 66 8655
TEST_TIME 67 8608
TEST_TIME 68 8517
TEST_TIME 69 8587
TEST_TIME 70 8592
TEST_TIME 71 8587
TEST_TIME 72 8567
TEST_TIME 73 8604
TEST_TIME 74 8565
TEST_TIME 75 8609
TEST_TIME 76 8606
TEST_TIME 77 8568
TEST_TIME 78 8601
TEST_TIME 79 8696
TEST_TIME 80 8672
TEST_TIME 81 8729
TEST_TIME 82 8735
TEST_TIME 83 8693
TEST_TIME 84 8760
TEST_TIME 85 8750
TEST_TIME 86 8684
TEST_TIME 87 8658
TEST_TIME 88 8652
TEST_TIME 89 8593
TEST_TIME 90 8584
TEST_TIME 91 8544
TEST_TIME 92 8528
TEST_TIME 93 8495
TEST_TIME 94 8429
TEST_TIME 95 8391
TEST_TIME 96 8364
TEST_TIME 97 8375
TEST_TIME 98 8411
TEST_TIME 99 8469
TEST_TIME 100 8497
TEST_TIME 101 8512
TEST_TIME 102 8483
TEST_TIME 103 8490
TEST_TIME 104 8505
TEST_TIME 105 8706
TEST_TIME 106 8677
TEST_TIME 107 8669
TEST_TIME 108 8746
TEST_TIME 109 8706
TEST_TIME 110 8636
TEST_TIME 111 8598
TEST_TIME 112 8604
TEST_TIME 113 8598
TEST_TIME 114 8601
TEST_TIME 115 8564
TEST_TIME 116 8545
TEST_TIME 117 8538
TEST_TIME 118 8522
TEST_TIME 119 8479
TEST_TIME 120 8420

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-04-2022 07:39:33 to 21-04-2022 07:39:37.

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 1404076 1404076 0 0.00 0 29285 15 0 0
doc_list_biud 1404062 1404062 0 0.00 0 28881 50 0 0
sp_add_invoice_to_stock 4785 4605 180 3.76 48 193842 29030 0 180
sp_cancel_adding_invoice 5037 5037 0 0.00 6 82400 1548 0 0
sp_cancel_client_order 1715 1715 0 0.00 7 8790 510 0 0
sp_cancel_customer_reserve 496587 496587 0 0.00 1 28818 122 0 0
sp_cancel_pay_from_customer 597 597 0 0.00 4 3248 147 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 4 4711 160 0 0
sp_cancel_supplier_invoice 5644 5631 13 0.23 4 21136 1177 0 13
sp_cancel_supplier_order 383 383 0 0.00 15 1364105 199758 0 0
sp_cancel_write_off 8727 8727 0 0.00 2 21564 208 0 0
sp_client_order 18857 18857 0 0.00 6 32401 519 0 0
sp_customer_reserve 431259 431259 0 0.00 1 47023 271 0 0
sp_fill_shopping_cart 29014 29014 0 0.00 1 55754 101 0 0
sp_fill_shopping_cart_clo_res 431228 431228 0 0.00 0 23743 20 0 0
sp_get_clo_for_invoice 4566 4566 0 0.00 0 31276 359 0 0
sp_kill_cost_storno 14757 14757 0 0.00 0 21558 95 0 0
sp_kill_qstorno_ret_qs2qd 1007915 1007915 0 0.00 0 28643 53 0 0
sp_kill_qty_storno 532012 532012 0 0.00 0 28881 122 0 0
sp_lock_selected_doc 19308 19308 0 0.00 0 7338 8 0 0
sp_make_cost_storno 25954 25954 0 0.00 0 29210 100 0 0
sp_multiply_rows_for_pdistr 25951 25951 0 0.00 0 6481 43 0 0
sp_multiply_rows_for_qdistr 18857 18857 0 0.00 0 28611 175 0 0
sp_pay_from_customer 6582 6582 0 0.00 6 21610 215 0 0
sp_pay_to_supplier 5437 5437 0 0.00 7 18859 213 0 0
sp_payment_common 12018 12018 0 0.00 2 8312 51 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 8618 420 0 0
sp_qd_handle_on_invoice_upd_sts 9603 9603 0 0.00 0 28880 664 0 0
sp_qd_handle_on_reserve_upd_sts 9365 9365 0 0.00 0 19726 77 0 0
sp_reserve_write_off 9369 9369 0 0.00 8 29416 318 0 0
sp_supplier_invoice 5119 5119 0 0.00 7 86915 4020 0 0
sp_supplier_order 5055 5055 0 0.00 8 50557 1631 0 0
srv_aggregate_perf_data 102 102 0 0.00 2216 157151 19829 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 782 114524 35941 0 0
srv_find_qd_qs_mism 961548 961548 0 0.00 0 19996 11 0 0
srv_make_invnt_saldo 423 423 0 0.00 53 81222 5389 0 0
srv_make_money_saldo 868 868 0 0.00 2 14753 481 0 0
srv_recalc_idx_stat 5 5 0 0.00 203726 566669 346259 0 0
t$perf-abend:sp_add_invoice_to_stock 180 180 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4615 4615 0 0.00 0 10879 1160 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 15736 672 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 2046 30 0 0
t$perf-norm:sp_cancel_customer_reserve 623 623 0 0.00 0 878 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 597 597 0 0.00 0 1308 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 1494 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 545 30 0 0
t$perf-norm:sp_cancel_supplier_order 383 383 0 0.00 0 112546 9316 0 0
t$perf-norm:sp_cancel_write_off 906 906 0 0.00 0 8329 30 0 0
t$perf-norm:sp_client_order 18858 18858 0 0.00 0 13135 42 0 0
t$perf-norm:sp_customer_reserve 924 924 0 0.00 0 1164 15 0 0
t$perf-norm:sp_pay_from_customer 6582 6582 0 0.00 0 11778 23 0 0
t$perf-norm:sp_pay_to_supplier 5437 5437 0 0.00 0 12021 23 0 0
t$perf-norm:sp_reserve_write_off 9369 9369 0 0.00 0 13350 30 0 0
t$perf-norm:sp_supplier_invoice 5119 5119 0 0.00 0 12782 40 0 0
t$perf-norm:sp_supplier_order 5055 5055 0 0.00 0 7351 39 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 826 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 54 5 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 979 17 0 0
t$perf-norm:srv_make_money_saldo 868 868 0 0.00 0 1393 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 184 59 0 0
v_add_invoice_to_stock 9486 9126 360 3.79 0 8285 32 0 360
v_all_customers 34994 34994 0 0.00 0 122 4 0 0
v_all_suppliers 28455 28455 0 0.00 0 133 5 0 0
v_all_wares 37684 37684 0 0.00 0 59 4 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 1379 19 0 0
v_cancel_client_order 3430 3430 0 0.00 0 4261 14 0 0
v_cancel_customer_prepayment 1194 1194 0 0.00 0 2154 15 0 0
v_cancel_customer_reserve 1246 1246 0 0.00 0 535 12 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 840 15 0 0
v_cancel_supplier_invoice 782 756 26 3.32 0 1148 27 0 26
v_cancel_supplier_order 766 766 0 0.00 0 1248 18 0 0
v_cancel_write_off 1812 1812 0 0.00 0 6479 32 0 0
v_max_id_clo_ord 5052 5052 0 0.00 0 462 5 0 0
v_max_id_clo_res 923 923 0 0.00 0 1375 14 0 0
v_max_id_ord_sup 5110 5110 0 0.00 0 6151 9 0 0
v_max_non_paid_invoice 1371 1371 0 0.00 0 3407 11 0 0
v_max_non_paid_realizn 3298 3298 0 0.00 0 4655 12 0 0
v_min_id_clo_ord 5052 5052 0 0.00 0 3411 9 0 0
v_min_id_clo_res 923 923 0 0.00 0 2082 15 0 0
v_min_id_ord_sup 5110 5110 0 0.00 0 8387 11 0 0
v_min_non_paid_invoice 5437 5437 0 0.00 0 4619 16 0 0
v_min_non_paid_realizn 6581 6581 0 0.00 0 8309 19 0 0
v_our_firm 9254 9254 0 0.00 0 51 5 0 0
v_reserve_write_off 18726 18726 0 0.00 0 3180 11 0 0
x_lock_depdocs_on_canc_invoice 10290 10290 0 0.00 0 11939 143 0 0
x_lock_depdocs_on_canc_sup_ord 383 383 0 0.00 0 11724 484 0 0
x_make_qstorno_1000_1200 5055 5055 0 0.00 3 50525 1431 0 0
x_make_qstorno_1200_2000 5119 5119 0 0.00 3 74841 3730 0 0
x_make_qstorno_2100_3300 431258 431258 0 0.00 0 40324 236 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-04-2022 07:39:38 to 21-04-2022 07:39:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 4681 8192000000 9699438720 9993076736 784640928 8.740 100 83 166 0 812712208 7604336 20466944
05:09:28 20383 8192000000 9738678640 10041769984 815601888 9.054 100 85 170 0 847362816 10675072 21085856
05:10:53 21499 8192000000 9785451536 10099994624 856516464 9.465 100 90 180 0 895417008 17679104 21221440
05:12:25 12379 8192000000 9857521648 10188259328 900298304 9.901 100 78 154 1 958676624 41466752 16911568
05:13:49 9233 8192000000 9902528096 10243469312 948874592 10.380 100 84 168 0 1016010992 49651248 17485152
05:15:02 3733 8192000000 9971288640 10317680640 982615328 10.710 100 93 185 2 1083420336 82197184 18607824
05:16:08 1304 8192000000 9994926272 10354716672 1009672032 10.972 100 93 176 0 1110185312 82945232 17568048
05:17:12 1489 8192000000 10012652112 10381815808 1036417152 11.230 100 89 174 0 1127932480 74405056 17110272
05:18:15 948 8192000000 10014828000 10386874368 1057834880 11.436 100 93 183 0 1141365344 64065200 19465264
05:19:17 1065 8192000000 10048083248 10426568704 1070221616 11.554 100 97 190 1 1165751376 77919424 17610336
05:20:18 1041 8192000000 10052811856 10432442368 1078171472 11.630 100 95 189 2 1171690160 74940640 18578048
05:21:21 1148 8192000000 10060932176 10446475264 1078051568 11.629 100 90 177 1 1186423696 90300736 18071392
05:22:23 8405 8192000000 10030448448 10418597888 1083413408 11.680 100 72 135 1 1157425568 60865232 13146928
05:23:32 1095 8192000000 10073394384 10461798400 1089217808 11.735 100 92 180 0 1196216864 87614832 19384224
05:24:34 1077 8192000000 10037894128 10428882944 1087816112 11.722 100 89 167 3 1153495728 48927392 16752224
05:25:36 1104 8192000000 10070166992 10459283456 1093207280 11.773 100 92 169 1 1193625168 84053728 16364160
05:26:38 761 8192000000 10068428784 10460151808 1099530288 11.833 100 94 178 0 1185319008 68084832 17703888
05:27:40 1248 8192000000 10037121232 10426019840 1089537904 11.738 100 90 168 0 1160979760 53348880 18092976
05:28:43 1133 8192000000 10026353408 10407985152 1083729136 11.683 100 95 184 6 1144046864 42242144 18075584
05:29:45 1926 8192000000 10028909920 10413047808 1083850976 11.684 100 90 168 1 1145347056 44665824 16830256
05:30:49 3878 8192000000 10029407472 10413268992 1077691104 11.625 100 82 154 2 1155290368 62331376 15267888
05:31:54 929 8192000000 10031771264 10408583168 1066189168 11.516 100 98 185 1 1154587840 68216592 20182080
05:32:56 1608 8192000000 10020656608 10401492992 1064761392 11.502 100 86 168 2 1146172544 65921568 15489584
05:33:58 1125 8192000000 10005988192 10380701696 1046612512 11.328 100 88 171 2 1129320736 65852016 16856208
05:35:01 1193 8192000000 9967435808 10338910208 1034855360 11.215 100 88 160 1 1090380464 40224240 15300864
05:36:03 1141 8192000000 10034774352 10409996288 1045345856 11.316 100 96 182 0 1160599216 96355168 18898192
05:37:05 1028 8192000000 9991575696 10359656448 1036246240 11.229 100 92 180 0 1114017088 57760448 20010400
05:38:13 3346 8192000000 10018273568 10393133056 1062959840 11.485 100 75 145 2 1137230816 59993840 14277136
05:39:22 10209 8192000000 10044146800 10428596224 1065402992 11.508 99 70 134 1 1162968928 83301120 14264816
05:40:39 1505 8192000000 10100203056 10478370816 1087420016 11.718 100 91 173 0 1218630016 112993600 18216400
05:41:45 13614 8192000000 10111189552 10497945600 1077596416 11.625 99 70 130 2 1225933792 134710112 13627264
05:43:01 3313 8192000000 10139294592 10537201664 1107498688 11.909 100 73 145 1 1259324416 136413712 15412016
05:44:09 12566 8192000000 10128381888 10522955776 1118180960 12.010 100 74 135 1 1245069440 111692704 15195776
05:45:28 1686 8192000000 10150116224 10536652800 1124778592 12.072 100 87 170 0 1262617984 120189568 17649824
05:46:33 2843 8192000000 10192608304 10588200960 1130223072 12.123 100 90 174 1 1316078464 166633904 19221488
05:47:42 1065 8192000000 10222705712 10624688128 1154270336 12.350 100 97 189 0 1335992048 161495168 20226544
05:48:46 10607 8192000000 10263755520 10670178304 1156216336 12.368 100 74 141 1 1372426064 200934992 15274736
05:50:04 7141 8192000000 10253725824 10659663872 1167160640 12.470 100 70 128 0 1366572016 185499872 13911504
05:51:15 901 8192000000 10284935024 10700079104 1179147744 12.582 100 92 180 0 1395371968 196238704 19985520
05:52:18 797 8192000000 10331967568 10751594496 1176539664 12.558 100 94 185 0 1441840048 244567552 20732832
05:53:21 784 8192000000 10332097680 10752286720 1183929200 12.627 100 95 190 0 1440545584 235051152 21565232
05:54:28 2184 8192000000 10334530992 10748170240 1190321072 12.686 100 84 163 0 1440200096 231172688 18706336
05:55:34 949 8192000000 10320752976 10747887616 1175721744 12.550 100 94 177 1 1427444880 231199824 20523312
05:56:38 930 8192000000 10373489008 10805944320 1192573264 12.707 100 92 182 0 1482778528 268124992 22080272
05:57:41 779 8192000000 10374371360 10789138432 1200577840 12.782 100 97 188 0 1481904800 260897680 20429280
05:58:45 1588 8192000000 10387125120 10803200000 1191325024 12.696 100 95 184 0 1500598896 287254976 22018896
05:59:51 873 8192000000 10438888144 10865475584 1212171312 12.889 100 91 177 2 1543658160 310655088 20831760
06:00:56 1778 8192000000 10469925040 10902077440 1195559520 12.735 100 93 181 1 1571015296 353924480 21531296
06:02:02 1306 8192000000 10485632944 10916233216 1203994384 12.813 100 94 185 1 1588800320 362641488 22164448
06:03:06 1014 8192000000 10513632864 10926342144 1207973568 12.850 100 96 192 1 1614183440 382638288 23571584
06:04:10 9719 8192000000 10474273920 10898767872 1187098272 12.656 100 86 162 0 1581694896 375824064 18772560
06:05:29 881 8192000000 10520613984 10928996352 1198614896 12.763 100 93 185 0 1622876720 401690704 22571120
06:06:39 1009 8192000000 10546690352 10991218688 1202947584 12.804 100 91 179 2 1648725984 424506256 21272144
06:07:44 2663 8192000000 10573968400 11002458112 1203614560 12.810 100 68 132 0 1676938096 456864752 16458784
06:08:56 5451 8192000000 10579821104 11020300288 1183143648 12.620 100 54 104 0 1679556784 482788912 13624224
06:10:12 15048 8192000000 10569393616 11054690304 1187246512 12.658 100 47 94 0 1679771136 480010624 12514000
06:12:00 7679 8192000000 10435437104 10926751744 1163736832 12.438 100 38 75 0 1552974880 379046512 10191536
06:13:22 18119 8192000000 10303605920 10792968192 1163171232 12.433 100 34 68 1 1411691008 240685776 7834000
06:14:44 16519 8192000000 10287630016 10752536576 1163612912 12.437 100 24 47 0 1381703136 212345728 5744496
06:16:19 47758 8192000000 10138185696 10602176512 1112235808 11.954 96 21 43 1 1211983504 94147216 5600480
06:18:07 85317 8192000000 10176922432 10619146240 1149273712 12.303 99 35 68 3 1302846064 145732656 7839696
06:20:57 57907 8192000000 10118768400 10611482624 1121775376 12.044 98 23 45 2 1275709248 148799248 5134624
06:22:55 86999 8192000000 10099404704 10575314944 1124210880 12.067 99 28 54 1 1212586864 82757680 5618304
06:25:40 50289 8192000000 10117939616 10561323008 1099475568 11.833 96 36 70 2 1180690832 72956912 8258352
06:27:31 104054 8192000000 10076493184 10530107392 1075788624 11.607 95 14 29 0 1138348304 59514288 3045392
06:30:16 66354 8192000000 10078251952 10511077376 1100438416 11.842 98 39 70 0 1132758096 25010000 7309680
06:32:25 100440 8192000000 10076973952 10567815168 1138097040 12.198 100 14 28 1 1195699376 54325344 3276992
06:35:31 1205 8192000000 10071030000 10487685120 1114160160 11.972 98 71 143 0 1174383344 44719328 15503856
06:36:34 28028 8192000000 10142450256 10570117120 1141304032 12.228 100 42 84 0 1237429120 87204560 8920528
06:38:03 42766 8192000000 10056351664 10567114752 1119382496 12.021 99 16 32 1 1217164240 94231872 3549872
06:39:49 89156 8192000000 10082635040 10580107264 1123569648 12.061 99 10 20 0 1187030320 61272768 2187904
06:42:19 83248 8192000000 10076318816 10549903360 1124979008 12.074 99 13 26 1 1175123328 47506784 2637536
06:44:42 86976 8192000000 10121136448 10586939392 1142709536 12.241 100 18 35 2 1229713136 82909552 4094048
06:47:10 80787 8192000000 10077646688 10528174080 1115782016 11.987 98 15 30 0 1175169712 56684368 2703328
06:49:32 78992 8192000000 10060260352 10538266624 1097674320 11.816 96 25 47 1 1139799552 37324128 4801104
06:51:52 51277 8192000000 10085003824 10556452864 1122737376 12.053 98 26 52 1 1202522800 74479216 5306208
06:53:45 45983 8192000000 10087896304 10512818176 1112776848 11.959 97 36 62 2 1162878368 44132080 5969440
06:55:32 85245 8192000000 10083522064 10565611520 1114372832 11.974 98 15 29 1 1189961120 72392896 3195392
06:57:59 77993 8192000000 10092154896 10603483136 1115455008 11.984 97 30 60 1 1186870928 65631632 5784288
07:00:18 69115 8192000000 10126539040 10587639808 1133275216 12.152 99 46 90 0 1219245920 77002000 8968704
07:02:30 13803 8192000000 10107231664 10527981568 1138477648 12.201 100 57 111 3 1206449968 56983808 10988512
07:03:44 114523 8192000000 10084679552 10549153792 1118914992 12.017 98 41 83 0 1205360496 77072464 9373040
07:06:46 5754 8192000000 10077051616 10509271040 1137367264 12.191 100 42 76 3 1185127664 39563296 8197104
07:07:53 56842 8192000000 10080309808 10538344448 1109061776 11.924 98 12 24 1 1165133040 53754592 2316672
07:09:53 95421 8192000000 10094128192 10544041984 1087963568 11.723 96 13 27 0 1159571072 68363280 3244224
07:13:06 85826 8192000000 10123489728 10557550592 1075728560 11.607 95 15 30 0 1207504592 128125920 3650112
07:15:32 68206 8192000000 10115744592 10612518912 1121889792 12.045 99 21 42 1 1242187376 115198096 5099488
07:18:11 14019 8192000000 10110628528 10531454976 1127527792 12.098 100 55 108 0 1207386112 68691536 11166784
07:19:27 15121 8192000000 10147806560 10576744448 1103749920 11.873 97 44 88 2 1233279104 119703552 9825632
07:20:47 37333 8192000000 10171816400 10628919296 1116252144 11.992 98 23 46 2 1265327696 143949312 5126240
07:22:25 63390 8192000000 10066184384 10484543488 1088138256 11.725 96 35 66 2 1155648720 59678416 7832048
07:24:29 20515 8192000000 10126843360 10552082432 1138342016 12.200 100 49 89 2 1224819024 77932720 8544288
07:25:52 94225 8192000000 10056656528 10495688704 1073366224 11.584 94 22 44 1 1101206064 23882048 3957792
07:29:21 60055 8192000000 10076932336 10541830144 1134193104 12.161 100 19 38 0 1200347216 62375264 3778848
07:31:23 39698 8192000000 10061786112 10526601216 1122828992 12.054 99 23 44 0 1181146048 53892624 4424432
07:33:06 90644 8192000000 10072360128 10536202240 1126644544 12.090 99 12 24 0 1181247664 52101648 2501472
07:35:37 61478 8192000000 10071113024 10535448576 1133799424 12.157 99 14 28 2 1167374832 30597216 2978192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-04-2022 07:39:44 to 21-04-2022 07:40:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 180 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-04-2022 07:40:01 to 21-04-2022 07:40:03.

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          210637
MON$OLDEST_ACTIVE               211719
MON$OLDEST_SNAPSHOT             211719
MON$NEXT_TRANSACTION            211719
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                       2083232
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:442: 
:443: mscs-test-161.budzdorov.ru    Thu Apr 21 07:37:22 2022
:444:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:445: 
--- end of diff output ---

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

21.04.22 07:40:04. 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
2022-04-21 07:40:06 No severe PSQL-related problems occured
21.04.22 07:40:07. End of report.