Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.12.2021 13: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 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 16352108 kB
MemAvailable 31381700 kB
Buffers 0 kB
Cached 15310168 kB
SwapCached 444 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-23 13:37:11
DTS_END                         2021-12-23 13:37:11.2390
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/60604
TRN_ID                          241016
ATT_ID                          697
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: 9306.18 21838 1116742
customer order: creation 175.88 391 21106
customer order: refuse 15.00 323 1800
order to supplier: creation 46.73 1444 5608
order to supplier: removal 3.58 110682 429
invoice (draft): creation 46.94 3686 5633
invoice (draft): removal 53.49 1015 6419
invoice accept: apply 41.83 18098 5020
invoice accept: cancel 48.11 919 5773
customer reserve: creation 3852.60 179 462312
customer reserve: removal 4723.76 66 566851
realization accept: apply 84.13 196 10096
realization accept: cancel 81.22 133 9747
payment from customer: creation 60.68 116 7282
payment from customer: removal 6.04 97 725
payment to supplier: creation 50.62 114 6074
payment to supplier: removal 3.88 60 466
service: total inventory turnovers 4.27 3146 512
service: total monetary turnovers 6.41 528 769
service: aggregate perf. data 0.97 12140 116
service: refresh index statistics 0.03 283423 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-12-2021 13:37:56 to 23-12-2021 13:37:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2151
TEST_TIME 2 2484
TEST_TIME 3 3322
TEST_TIME 4 4211
TEST_TIME 5 4687
TEST_TIME 6 4693
TEST_TIME 7 4897
TEST_TIME 8 4918
TEST_TIME 9 5075
TEST_TIME 10 5625
TEST_TIME 11 5857
TEST_TIME 12 6296
TEST_TIME 13 6439
TEST_TIME 14 6504
TEST_TIME 15 6521
TEST_TIME 16 6630
TEST_TIME 17 7107
TEST_TIME 18 7352
TEST_TIME 19 7554
TEST_TIME 20 7806
TEST_TIME 21 7683
TEST_TIME 22 7865
TEST_TIME 23 8743
TEST_TIME 24 8985
TEST_TIME 25 9588
TEST_TIME 26 10736
TEST_TIME 27 10974
TEST_TIME 28 10880
TEST_TIME 29 10822
TEST_TIME 30 10692
TEST_TIME 31 10743
TEST_TIME 32 10664
TEST_TIME 33 10743
TEST_TIME 34 10757
TEST_TIME 35 10609
TEST_TIME 36 10477
TEST_TIME 37 10247
TEST_TIME 38 10238
TEST_TIME 39 10208
TEST_TIME 40 10092
TEST_TIME 41 10000
TEST_TIME 42 10087
TEST_TIME 43 10016
TEST_TIME 44 10195
TEST_TIME 45 10097
TEST_TIME 46 10016
TEST_TIME 47 9902
TEST_TIME 48 9799
TEST_TIME 49 9715
TEST_TIME 50 9664
TEST_TIME 51 9629
TEST_TIME 52 9650
TEST_TIME 53 9642
TEST_TIME 54 9725
TEST_TIME 55 9783
TEST_TIME 56 9726
TEST_TIME 57 9681
TEST_TIME 58 9733
TEST_TIME 59 9817
TEST_TIME 60 9758
TEST_TIME 61 9728
TEST_TIME 62 9992
TEST_TIME 63 10012
TEST_TIME 64 9905
TEST_TIME 65 9808
TEST_TIME 66 9783
TEST_TIME 67 9689
TEST_TIME 68 9641
TEST_TIME 69 9573
TEST_TIME 70 9516
TEST_TIME 71 9504
TEST_TIME 72 9466
TEST_TIME 73 9440
TEST_TIME 74 9386
TEST_TIME 75 9345
TEST_TIME 76 9431
TEST_TIME 77 9367
TEST_TIME 78 9371
TEST_TIME 79 9457
TEST_TIME 80 9496
TEST_TIME 81 9515
TEST_TIME 82 9656
TEST_TIME 83 9709
TEST_TIME 84 9637
TEST_TIME 85 9586
TEST_TIME 86 9498
TEST_TIME 87 9462
TEST_TIME 88 9462
TEST_TIME 89 9448
TEST_TIME 90 9449
TEST_TIME 91 9477
TEST_TIME 92 9401
TEST_TIME 93 9422
TEST_TIME 94 9421
TEST_TIME 95 9411
TEST_TIME 96 9393
TEST_TIME 97 9513
TEST_TIME 98 9472
TEST_TIME 99 9466
TEST_TIME 100 9488
TEST_TIME 101 9585
TEST_TIME 102 9556
TEST_TIME 103 9539
TEST_TIME 104 9539
TEST_TIME 105 9515
TEST_TIME 106 9465
TEST_TIME 107 9408
TEST_TIME 108 9390
TEST_TIME 109 9359
TEST_TIME 110 9313
TEST_TIME 111 9305
TEST_TIME 112 9292
TEST_TIME 113 9280
TEST_TIME 114 9272
TEST_TIME 115 9268
TEST_TIME 116 9251
TEST_TIME 117 9224
TEST_TIME 118 9275
TEST_TIME 119 9364
TEST_TIME 120 9306

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-12-2021 13:38:00 to 23-12-2021 13:38:03.

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 1545550 1545550 0 0.00 0 27024 7 0 0
doc_list_biud 1545550 1545550 0 0.00 0 29245 31 0 0
sp_add_invoice_to_stock 5161 5020 141 2.73 22 195728 18098 0 141
sp_cancel_adding_invoice 5773 5773 0 0.00 6 77063 920 0 0
sp_cancel_client_order 1800 1800 0 0.00 5 14565 323 0 0
sp_cancel_customer_reserve 566851 566851 0 0.00 1 29248 66 0 0
sp_cancel_pay_from_customer 725 725 0 0.00 3 8296 98 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 1004 61 0 0
sp_cancel_supplier_invoice 6438 6419 19 0.29 2 25837 1015 0 19
sp_cancel_supplier_order 429 429 0 0.00 10 1286503 110683 0 0
sp_cancel_write_off 9747 9747 0 0.00 1 23562 133 0 0
sp_client_order 21106 21106 0 0.00 4 57601 392 0 0
sp_customer_reserve 462312 462312 0 0.00 0 49995 179 0 0
sp_fill_shopping_cart 32333 32333 0 0.00 1 13291 42 0 0
sp_fill_shopping_cart_clo_res 462312 462312 0 0.00 0 15760 18 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 22949 368 0 0
sp_kill_cost_storno 16711 16711 0 0.00 0 8290 57 0 0
sp_kill_qstorno_ret_qs2qd 1150297 1150297 0 0.00 0 29244 37 0 0
sp_kill_qty_storno 606129 606129 0 0.00 0 29245 79 0 0
sp_lock_selected_doc 21082 21082 0 0.00 0 7289 5 0 0
sp_make_cost_storno 28466 28466 0 0.00 0 12716 56 0 0
sp_multiply_rows_for_pdistr 28466 28466 0 0.00 0 8722 30 0 0
sp_multiply_rows_for_qdistr 21106 21106 0 0.00 0 57581 199 0 0
sp_pay_from_customer 7282 7282 0 0.00 4 12723 116 0 0
sp_pay_to_supplier 6074 6074 0 0.00 5 13315 115 0 0
sp_payment_common 13356 13356 0 0.00 1 3924 18 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 14554 286 0 0
sp_qd_handle_on_invoice_upd_sts 10787 10787 0 0.00 0 23987 356 0 0
sp_qd_handle_on_reserve_upd_sts 10096 10096 0 0.00 0 21635 67 0 0
sp_reserve_write_off 10096 10096 0 0.00 7 21668 196 0 0
sp_supplier_invoice 5633 5633 0 0.00 7 79532 3687 0 0
sp_supplier_order 5608 5608 0 0.00 5 30642 1444 0 0
srv_aggregate_perf_data 116 116 0 0.00 3158 109985 12140 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 862 125326 44032 0 0
srv_find_qd_qs_mism 1068573 1068573 0 0.00 0 27022 6 0 0
srv_make_invnt_saldo 512 512 0 0.00 62 107853 3147 0 0
srv_make_money_saldo 769 769 0 0.00 1 15024 528 0 0
srv_recalc_idx_stat 4 4 0 0.00 243886 315910 283423 0 0
t$perf-abend:sp_add_invoice_to_stock 141 141 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5020 5020 0 0.00 0 8738 150 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 0 8531 102 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 3240 12 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 1643 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 725 725 0 0.00 0 2537 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 1166 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 7808 42 0 0
t$perf-norm:sp_cancel_supplier_order 429 429 0 0.00 0 18599 1610 0 0
t$perf-norm:sp_cancel_write_off 1034 1034 0 0.00 0 2255 5 0 0
t$perf-norm:sp_client_order 21106 21106 0 0.00 0 14067 17 0 0
t$perf-norm:sp_customer_reserve 1062 1062 0 0.00 0 9699 19 0 0
t$perf-norm:sp_pay_from_customer 7282 7282 0 0.00 0 4872 11 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 6235 11 0 0
t$perf-norm:sp_reserve_write_off 10096 10096 0 0.00 0 7745 11 0 0
t$perf-norm:sp_supplier_invoice 5633 5633 0 0.00 0 20260 25 0 0
t$perf-norm:sp_supplier_order 5608 5608 0 0.00 0 7214 15 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 384 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 23 2 0 0
t$perf-norm:srv_make_invnt_saldo 512 512 0 0.00 0 940 6 0 0
t$perf-norm:srv_make_money_saldo 769 769 0 0.00 0 4990 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10310 10028 282 2.73 0 6429 15 0 282
v_all_customers 38628 38628 0 0.00 0 20 1 0 0
v_all_suppliers 31438 31438 0 0.00 0 19 1 0 0
v_all_wares 42208 42208 0 0.00 0 155 1 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 1214 7 0 0
v_cancel_client_order 3600 3600 0 0.00 0 11128 9 0 0
v_cancel_customer_prepayment 1450 1450 0 0.00 0 1943 9 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 3129 6 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 870 6 0 0
v_cancel_supplier_invoice 766 728 38 4.96 0 2359 17 0 38
v_cancel_supplier_order 858 858 0 0.00 0 1283 5 0 0
v_cancel_write_off 2068 2068 0 0.00 0 5369 16 0 0
v_max_id_clo_ord 5608 5608 0 0.00 0 1048 2 0 0
v_max_id_clo_res 1062 1062 0 0.00 0 6464 24 0 0
v_max_id_ord_sup 5621 5621 0 0.00 0 7051 8 0 0
v_max_non_paid_invoice 1584 1584 0 0.00 0 189 2 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 2110 3 0 0
v_min_id_clo_ord 5608 5608 0 0.00 0 1209 5 0 0
v_min_id_clo_res 1062 1062 0 0.00 0 8438 35 0 0
v_min_id_ord_sup 5621 5621 0 0.00 0 6559 8 0 0
v_min_non_paid_invoice 6074 6074 0 0.00 0 3479 10 0 0
v_min_non_paid_realizn 7282 7282 0 0.00 0 3919 13 0 0
v_our_firm 10484 10484 0 0.00 0 24 1 0 0
v_reserve_write_off 20192 20192 0 0.00 0 11434 7 0 0
x_lock_depdocs_on_canc_invoice 11828 11828 0 0.00 0 17781 110 0 0
x_lock_depdocs_on_canc_sup_ord 429 429 0 0.00 0 8912 319 0 0
x_make_qstorno_1000_1200 5608 5608 0 0.00 2 30360 1361 0 0
x_make_qstorno_1200_2000 5633 5633 0 0.00 4 72105 3529 0 0
x_make_qstorno_2100_3300 462312 462312 0 0.00 0 49993 159 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 23-12-2021 13:38:04 to 23-12-2021 13:38:09.

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
11:07:14 2 8192000000 8798580928 8809799680 4478816 0.054 2 1 2 0 4578208 63616 35776
11:08:15 16584 8192000000 9707650432 9999081472 785466864 8.749 100 93 186 0 817266656 8575296 23224496
11:09:42 24526 8192000000 9761038784 10069368832 835250272 9.252 100 94 188 0 869349024 11433440 22665312
11:11:19 18792 8192000000 9816545888 10136977408 873650624 9.636 100 80 160 0 919321072 27339792 18330656
11:12:44 12253 8192000000 9875662960 10211553280 932117520 10.215 100 79 158 1 989581872 40269456 17194896
11:13:59 6109 8192000000 9923233152 10273845248 965097376 10.539 100 75 150 1 1036566480 55589808 15879296
11:15:08 2394 8192000000 9968037440 10325872640 1003908496 10.916 100 86 168 3 1081469776 60471600 17089680
11:16:12 2776 8192000000 10028955888 10394890240 1034243280 11.209 100 78 155 0 1137150880 88220976 14686624
11:17:18 2021 8192000000 10046235680 10415493120 1059008240 11.447 100 93 186 0 1149487520 70866720 19612560
11:18:21 23086 8192000000 9976673520 10364633088 1064224784 11.497 100 31 62 1 1103242944 32241584 6776576
11:19:47 4033 8192000000 9981531552 10373349376 1076781248 11.617 100 54 106 1 1098130720 9519152 11830320
11:20:54 5818 8192000000 10006838256 10398732288 1080522432 11.652 100 42 84 1 1118921184 29753728 8645024
11:22:01 52344 8192000000 9993592544 10405826560 1084653824 11.692 100 43 85 0 1110113776 16645040 8814912
11:23:54 4790 8192000000 10015685488 10412163072 1087503008 11.719 100 44 87 3 1118717600 22525664 8688928
11:24:59 10880 8192000000 10012698000 10421743616 1092099248 11.763 100 18 36 0 1110408368 14082976 4226144
11:26:11 7354 8192000000 10024966096 10425073664 1097636448 11.815 100 36 72 1 1136621760 31152768 7832544
11:27:19 50741 8192000000 9977724544 10373132288 1040967696 11.274 95 25 49 0 1072900624 27041296 4891632
11:29:10 3653 8192000000 10003166976 10382884864 1059410688 11.451 100 55 111 1 1098717184 27185680 12120816
11:30:15 3471 8192000000 9928436256 10301472768 983078816 10.714 96 48 100 2 1010342368 15934240 11329312
11:31:19 5771 8192000000 9938529840 10322915328 1011301024 10.988 99 56 109 2 1038609984 14019216 13289744
11:32:26 30897 8192000000 9936823488 10316128256 1010035584 10.976 98 36 73 0 1035496768 17613232 7847952
11:33:57 3836 8192000000 9980742144 10356666368 1045026304 11.313 100 62 120 2 1080253984 21429888 13797792
11:35:02 5306 8192000000 9969337344 10362662912 1057281904 11.430 100 47 94 1 1084222512 16716368 10224240
11:36:10 5334 8192000000 9986332848 10383687680 1068443392 11.537 100 49 99 0 1096387488 17558352 10385744
11:37:17 98130 8192000000 10005198944 10405081088 1084186400 11.687 100 30 60 0 1104747760 14536864 6024496
11:39:56 37270 8192000000 10074823120 10482053120 1105440656 11.889 100 39 78 0 1189347584 76012432 7894496
11:41:37 1587 8192000000 10088797024 10485391360 1126161552 12.085 100 59 113 1 1198409216 60217008 12030656
11:42:45 9913 8192000000 10148242544 10547109888 1138869056 12.205 100 57 114 0 1259611040 108625696 12116288
11:43:57 3525 8192000000 10183945840 10586140672 1147826320 12.289 100 61 122 0 1286791584 125874448 13090816
11:45:06 24323 8192000000 10197152896 10616373248 1154932368 12.356 100 41 82 2 1301803040 137626384 9244288
11:46:33 6001 8192000000 10244783312 10663567360 1165249616 12.452 100 57 114 3 1357338368 178693280 13395472
11:47:42 26161 8192000000 10242733632 10661326848 1172732112 12.522 100 44 88 0 1359190368 175801392 10656864
11:49:14 3768 8192000000 10279565456 10683813888 1178448816 12.576 100 79 158 2 1378663568 182245216 17969536
11:50:25 2091 8192000000 10299052912 10704289792 1168230448 12.480 99 84 168 1 1389616416 202116720 19269248
11:51:33 2613 8192000000 10289645616 10705178624 1169335376 12.491 100 63 126 0 1398755680 214068944 15351360
11:52:40 2660 8192000000 10347887760 10755272704 1171391376 12.510 100 74 148 0 1449908592 261366272 17150944
11:53:48 3181 8192000000 10368258144 10792153088 1168887904 12.486 100 88 174 0 1465452496 276693424 19871168
11:54:57 856 8192000000 10397019152 10791936000 1171186384 12.508 100 93 180 1 1496715712 304602848 20926480
11:56:03 12682 8192000000 10391431488 10809520128 1151284864 12.322 98 49 97 1 1476690416 313244256 12161296
11:57:21 1139 8192000000 10460189536 10858573824 1166063104 12.460 100 86 171 3 1549719568 363517664 20138800
11:58:24 6499 8192000000 10475406384 10897960960 1158042208 12.385 100 43 86 0 1574488976 405005904 11440864
11:59:33 13450 8192000000 10505331264 10931146752 1133051776 12.150 100 49 98 0 1590740048 444582192 13106080
12:00:57 16514 8192000000 10353346208 10817134592 1138522784 12.202 100 33 66 0 1498507632 351476000 8508848
12:02:23 15281 8192000000 10306919952 10827943936 1125419472 12.078 100 25 50 2 1451477120 319094144 6963504
12:03:43 23817 8192000000 10062333696 10581135360 1078230544 11.631 100 13 25 1 1233263808 151492272 3540992
12:05:07 21141 8192000000 10036210480 10456068096 1096181440 11.801 100 44 88 2 1134655600 28857376 9616784
12:06:54 34743 8192000000 10057092576 10478305280 1109941088 11.932 100 32 63 2 1162666496 45382416 7342992
12:08:30 64829 8192000000 10072718976 10527084544 1128100912 12.103 100 23 46 0 1199600112 67043104 4456096
12:10:38 80399 8192000000 10057516304 10510737408 1064683216 11.501 94 11 22 0 1123285936 56244176 2358544
12:13:01 97979 8192000000 10118629440 10555564032 1132440704 12.144 100 35 70 2 1191668688 50654352 8573632
12:15:48 93595 8192000000 10099445440 10538487808 1143892080 12.252 100 18 36 0 1238543040 90789152 3861808
12:18:23 43935 8192000000 10144544128 10601877504 1148595536 12.296 100 32 64 1 1250689904 94787056 7307312
12:20:12 45607 8192000000 10156797840 10600095744 1139969696 12.215 99 39 78 0 1235897488 87559136 8368656
12:22:04 26908 8192000000 10069051072 10512506880 1143706400 12.250 100 13 26 0 1163141520 17033152 2401968
12:23:31 103007 8192000000 10087191376 10541715456 1135593024 12.174 99 13 26 0 1178901472 40373456 2934992
12:26:15 17980 8192000000 10083485216 10548592640 1150261040 12.312 100 35 70 0 1191056928 33174000 7621888
12:27:33 72980 8192000000 10096890384 10554413056 1156249328 12.368 100 13 26 1 1207294880 47999760 3045792
12:29:47 51990 8192000000 10154671616 10603958272 1155853984 12.364 99 25 50 1 1224335088 63698144 4782960
12:31:47 52238 8192000000 10115378256 10610716672 1143549792 12.249 99 18 36 1 1190746672 43767728 3429152
12:33:40 33573 8192000000 10102329552 10535309312 1107796176 11.912 96 37 74 1 1149269824 33676928 7796720
12:35:14 39351 8192000000 10127201888 10579808256 1111859008 11.950 98 33 66 1 1212393440 93260960 7273472
12:36:58 18615 8192000000 10062577984 10484756480 1085735536 11.702 95 39 79 2 1119682112 26775408 7171168
12:38:19 31970 8192000000 10173910752 10614616064 1114630352 11.976 99 29 57 1 1244569264 123972432 5966480
12:40:18 125325 8192000000 10067373840 10504306688 1055862848 11.417 93 20 40 1 1099517216 39380928 4273440
12:43:26 38819 8192000000 10056048432 10523824128 1109126800 11.924 98 15 30 2 1158122656 46030816 2965040
12:45:04 59491 8192000000 10055924816 10531786752 1103837728 11.874 98 7 14 0 1131279168 26074480 1366960
12:47:15 63240 8192000000 10073178640 10540519424 1109708896 11.930 98 12 24 0 1163883520 51546224 2628400
12:49:18 43542 8192000000 10070825920 10547945472 1111885840 11.950 99 22 44 0 1168300608 51244480 5170288
12:51:13 37669 8192000000 10121572112 10561568768 1127229200 12.095 99 29 57 0 1201143920 68081808 5832912
12:52:55 80427 8192000000 10101496208 10571472896 1110519568 11.937 99 23 46 0 1184062848 68675808 4867472
12:55:21 60654 8192000000 10132396512 10604621824 1110652880 11.939 98 26 52 0 1190216528 74305248 5258400
12:57:42 21536 8192000000 10096590320 10537996288 1125661552 12.080 99 51 101 1 1185059200 48967776 10429872
12:59:05 65162 8192000000 10149914352 10687684608 1143372688 12.247 100 17 34 0 1254857328 107943664 3540976
13:01:10 38213 8192000000 10064898304 10500947968 1101133472 11.848 97 36 71 2 1129313584 20336288 7843824
13:02:49 73718 8192000000 10105960544 10542534656 1138771216 12.204 100 28 56 1 1205708080 60630112 6306752
13:05:03 74735 8192000000 10126682864 10622103552 1121107888 12.037 98 10 20 0 1203336016 79929664 2298464
13:08:19 76436 8192000000 10089202352 10578509824 1121177056 12.038 98 15 30 1 1186607968 62327456 3103456
13:10:49 33221 8192000000 10081766480 10541223936 1145676048 12.269 100 12 24 0 1186927984 38744656 2507280
13:12:23 34634 8192000000 10103438224 10540863488 1121718272 12.043 98 35 70 0 1160104144 30910752 7475120
13:14:03 70213 8192000000 10090660624 10605268992 1123742064 12.062 98 23 46 0 1241352976 112712240 4898672
13:16:13 38917 8192000000 10115329248 10568343552 1132028832 12.140 98 25 50 0 1190061104 52693808 5338464
13:18:11 73217 8192000000 10129820448 10660421632 1154931056 12.356 100 23 46 1 1255017744 95351920 4734768
13:20:34 28433 8192000000 10094076656 10515558400 1119487680 12.022 98 50 100 0 1152835888 22142768 11205440
13:22:06 123789 8192000000 10051099648 10501849088 1073907424 11.589 94 40 80 0 1118734000 36634112 8192464
13:25:11 67927 8192000000 10078207024 10548277248 1118547024 12.013 98 17 34 2 1170636192 47655648 4433520
13:27:33 56944 8192000000 10064288576 10549735424 1129673280 12.118 100 4 8 0 1187745936 57196352 876304
13:29:37 62712 8192000000 10047032208 10500390912 1087762176 11.721 97 11 22 0 1123753584 33914896 2076512
13:31:41 71745 8192000000 10102634272 10553700352 1120749024 12.034 99 25 50 0 1181073216 54891232 5432960
13:33:53 62717 8192000000 10136848080 10569535488 1083837680 11.684 95 25 50 0 1178101904 89146144 5118080
13:35:56 80254 8192000000 9279322384 9620361216 390683360 4.551 36 7 14 3 437739888 45810000 1246528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 23-12-2021 13:38:10 to 23-12-2021 13:38:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 141 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-12-2021 13:38:26 to 23-12-2021 13:38:28.

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          240794
MON$OLDEST_ACTIVE               241256
MON$OLDEST_SNAPSHOT             241256
MON$NEXT_TRANSACTION            241256
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                       2059616
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2696 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2696 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2696 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2696 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Thu Dec 23 13:37:17 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
--- end of diff output ---

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

23.12.21 13:38:29. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-23 13:38:32 No severe PSQL-related problems occured
23.12.21 13:38:33. End of report.