Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.03.2021 10:28
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 16938356 kB
MemAvailable 31561816 kB
Buffers 0 kB
Cached 14754676 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-23 10:26:58
DTS_END                         2021-03-23 10:26:58.7050
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39334
TRN_ID                          227965
ATT_ID                          674
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: 9087.88 27663 1090546
customer order: creation 161.29 431 19355
customer order: refuse 13.72 318 1646
order to supplier: creation 43.24 1225 5189
order to supplier: removal 3.48 129234 417
invoice (draft): creation 43.94 3160 5273
invoice (draft): removal 53.46 976 6415
invoice accept: apply 40.13 18203 4815
invoice accept: cancel 48.12 883 5774
customer reserve: creation 3732.24 176 447869
customer reserve: removal 4664.99 78 559799
realization accept: apply 79.03 155 9483
realization accept: cancel 81.12 127 9734
payment from customer: creation 56.83 98 6819
payment from customer: removal 5.42 82 650
payment to supplier: creation 46.88 124 5625
payment to supplier: removal 3.06 67 367
service: total inventory turnovers 3.89 3314 467
service: total monetary turnovers 6.11 493 733
service: aggregate perf. data 0.93 14589 111
service: refresh index statistics 0.04 379520 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-03-2021 10:29:15 to 23-03-2021 10:29:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1236
TEST_TIME 2 1932
TEST_TIME 3 2718
TEST_TIME 4 2657
TEST_TIME 5 3029
TEST_TIME 6 3648
TEST_TIME 7 4614
TEST_TIME 8 4596
TEST_TIME 9 4677
TEST_TIME 10 4931
TEST_TIME 11 5050
TEST_TIME 12 5192
TEST_TIME 13 5425
TEST_TIME 14 5616
TEST_TIME 15 5941
TEST_TIME 16 6250
TEST_TIME 17 6241
TEST_TIME 18 6561
TEST_TIME 19 6749
TEST_TIME 20 7774
TEST_TIME 21 7860
TEST_TIME 22 8027
TEST_TIME 23 8375
TEST_TIME 24 8926
TEST_TIME 25 9405
TEST_TIME 26 11053
TEST_TIME 27 10810
TEST_TIME 28 10626
TEST_TIME 29 10269
TEST_TIME 30 10235
TEST_TIME 31 10090
TEST_TIME 32 10154
TEST_TIME 33 10059
TEST_TIME 34 9858
TEST_TIME 35 9707
TEST_TIME 36 9564
TEST_TIME 37 9433
TEST_TIME 38 9881
TEST_TIME 39 9912
TEST_TIME 40 10053
TEST_TIME 41 10057
TEST_TIME 42 9970
TEST_TIME 43 10068
TEST_TIME 44 10131
TEST_TIME 45 10065
TEST_TIME 46 10086
TEST_TIME 47 10161
TEST_TIME 48 10139
TEST_TIME 49 9999
TEST_TIME 50 9844
TEST_TIME 51 9762
TEST_TIME 52 9643
TEST_TIME 53 9572
TEST_TIME 54 9451
TEST_TIME 55 9362
TEST_TIME 56 9444
TEST_TIME 57 9382
TEST_TIME 58 9359
TEST_TIME 59 9326
TEST_TIME 60 9298
TEST_TIME 61 9385
TEST_TIME 62 9482
TEST_TIME 63 9522
TEST_TIME 64 9631
TEST_TIME 65 9675
TEST_TIME 66 9613
TEST_TIME 67 9477
TEST_TIME 68 9444
TEST_TIME 69 9409
TEST_TIME 70 9371
TEST_TIME 71 9353
TEST_TIME 72 9380
TEST_TIME 73 9336
TEST_TIME 74 9365
TEST_TIME 75 9302
TEST_TIME 76 9412
TEST_TIME 77 9406
TEST_TIME 78 9399
TEST_TIME 79 9395
TEST_TIME 80 9453
TEST_TIME 81 9491
TEST_TIME 82 9560
TEST_TIME 83 9544
TEST_TIME 84 9519
TEST_TIME 85 9422
TEST_TIME 86 9362
TEST_TIME 87 9316
TEST_TIME 88 9306
TEST_TIME 89 9244
TEST_TIME 90 9285
TEST_TIME 91 9263
TEST_TIME 92 9240
TEST_TIME 93 9219
TEST_TIME 94 9174
TEST_TIME 95 9228
TEST_TIME 96 9292
TEST_TIME 97 9307
TEST_TIME 98 9283
TEST_TIME 99 9363
TEST_TIME 100 9391
TEST_TIME 101 9303
TEST_TIME 102 9266
TEST_TIME 103 9237
TEST_TIME 104 9228
TEST_TIME 105 9194
TEST_TIME 106 9196
TEST_TIME 107 9208
TEST_TIME 108 9214
TEST_TIME 109 9192
TEST_TIME 110 9196
TEST_TIME 111 9234
TEST_TIME 112 9251
TEST_TIME 113 9237
TEST_TIME 114 9299
TEST_TIME 115 9296
TEST_TIME 116 9225
TEST_TIME 117 9179
TEST_TIME 118 9140
TEST_TIME 119 9096
TEST_TIME 120 9091

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-03-2021 10:29:19 to 23-03-2021 10:29:22.

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 1505741 1505741 0 0.00 0 17279 6 0 0
doc_list_biud 1505741 1505741 0 0.00 0 72590 35 0 0
sp_add_invoice_to_stock 4934 4815 119 2.41 41 169793 18203 0 119
sp_cancel_adding_invoice 5774 5774 0 0.00 4 57784 884 0 0
sp_cancel_client_order 1646 1646 0 0.00 5 9314 319 0 0
sp_cancel_customer_reserve 559799 559799 0 0.00 1 72593 78 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 8308 83 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 3813 68 0 0
sp_cancel_supplier_invoice 6419 6415 4 0.06 2 36912 977 0 4
sp_cancel_supplier_order 417 417 0 0.00 7 1333055 129234 0 0
sp_cancel_write_off 9734 9734 0 0.00 1 7073 128 0 0
sp_client_order 19355 19355 0 0.00 4 19773 431 0 0
sp_customer_reserve 447869 447869 0 0.00 0 111843 177 0 0
sp_fill_shopping_cart 29806 29806 0 0.00 1 23073 39 0 0
sp_fill_shopping_cart_clo_res 447855 447855 0 0.00 0 29846 21 0 0
sp_get_clo_for_invoice 4801 4801 0 0.00 0 35906 399 0 0
sp_kill_cost_storno 16525 16525 0 0.00 0 7030 58 0 0
sp_kill_qstorno_ret_qs2qd 1136164 1136164 0 0.00 0 72588 42 0 0
sp_kill_qty_storno 598069 598069 0 0.00 0 72590 88 0 0
sp_lock_selected_doc 19753 19753 0 0.00 0 6743 6 0 0
sp_make_cost_storno 26728 26728 0 0.00 0 15236 53 0 0
sp_multiply_rows_for_pdistr 26728 26728 0 0.00 0 7666 29 0 0
sp_multiply_rows_for_qdistr 19355 19355 0 0.00 0 19760 260 0 0
sp_pay_from_customer 6819 6819 0 0.00 4 11944 99 0 0
sp_pay_to_supplier 5625 5625 0 0.00 5 15224 125 0 0
sp_payment_common 12444 12444 0 0.00 1 6825 18 0 0
sp_qd_handle_on_cancel_clo 1646 1646 0 0.00 0 7932 283 0 0
sp_qd_handle_on_invoice_upd_sts 10575 10575 0 0.00 0 37005 380 0 0
sp_qd_handle_on_reserve_upd_sts 9483 9483 0 0.00 0 8309 45 0 0
sp_reserve_write_off 9483 9483 0 0.00 7 15267 155 0 0
sp_supplier_invoice 5273 5273 0 0.00 6 106789 3161 0 0
sp_supplier_order 5189 5189 0 0.00 5 104872 1226 0 0
srv_aggregate_perf_data 111 111 0 0.00 2608 122908 14590 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 2329 163387 51549 0 0
srv_find_qd_qs_mism 1045187 1045187 0 0.00 0 17279 5 0 0
srv_make_invnt_saldo 467 467 0 0.00 56 89302 3315 0 0
srv_make_money_saldo 733 733 0 0.00 1 15366 493 0 0
srv_recalc_idx_stat 5 5 0 0.00 257701 455703 379520 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4815 4815 0 0.00 0 14821 178 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 6874 114 0 0
t$perf-norm:sp_cancel_client_order 1646 1646 0 0.00 0 3823 15 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 3772 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 8410 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 333 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 5702 24 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 14010 1433 0 0
t$perf-norm:sp_cancel_write_off 917 917 0 0.00 0 915 5 0 0
t$perf-norm:sp_client_order 19355 19355 0 0.00 0 8588 19 0 0
t$perf-norm:sp_customer_reserve 935 935 0 0.00 0 2186 11 0 0
t$perf-norm:sp_pay_from_customer 6820 6820 0 0.00 0 10129 11 0 0
t$perf-norm:sp_pay_to_supplier 5625 5625 0 0.00 0 8542 7 0 0
t$perf-norm:sp_reserve_write_off 9483 9483 0 0.00 0 8175 10 0 0
t$perf-norm:sp_supplier_invoice 5273 5273 0 0.00 0 5465 20 0 0
t$perf-norm:sp_supplier_order 5189 5189 0 0.00 0 6401 13 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 149 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 569 19 0 0
t$perf-norm:srv_make_invnt_saldo 467 467 0 0.00 0 2774 13 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 1547 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 23 7 0 0
v_add_invoice_to_stock 9840 9602 238 2.41 0 8756 18 0 238
v_all_customers 36000 36000 0 0.00 0 36 1 0 0
v_all_suppliers 29218 29218 0 0.00 0 36 1 0 0
v_all_wares 38700 38700 0 0.00 0 80 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 2215 12 0 0
v_cancel_client_order 3292 3292 0 0.00 0 6907 10 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 992 5 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 4992 8 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 2268 6 0 0
v_cancel_supplier_invoice 784 776 8 1.02 0 4729 17 0 8
v_cancel_supplier_order 834 834 0 0.00 0 637 4 0 0
v_cancel_write_off 1834 1834 0 0.00 0 1079 12 0 0
v_max_id_clo_ord 5189 5189 0 0.00 0 302 1 0 0
v_max_id_clo_res 935 935 0 0.00 0 1940 15 0 0
v_max_id_ord_sup 5267 5267 0 0.00 0 2624 3 0 0
v_max_non_paid_invoice 1472 1472 0 0.00 0 366 2 0 0
v_max_non_paid_realizn 3312 3312 0 0.00 0 2938 4 0 0
v_min_id_clo_ord 5189 5189 0 0.00 0 3548 4 0 0
v_min_id_clo_res 935 935 0 0.00 0 3540 12 0 0
v_min_id_ord_sup 5267 5267 0 0.00 0 6024 9 0 0
v_min_non_paid_invoice 5625 5625 0 0.00 0 4680 11 0 0
v_min_non_paid_realizn 6819 6819 0 0.00 0 3637 12 0 0
v_our_firm 9714 9714 0 0.00 0 14 1 0 0
v_reserve_write_off 18966 18966 0 0.00 0 2409 4 0 0
x_lock_depdocs_on_canc_invoice 11801 11801 0 0.00 0 21927 111 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 11261 351 0 0
x_make_qstorno_1000_1200 5189 5189 0 0.00 2 104857 1160 0 0
x_make_qstorno_1200_2000 5273 5273 0 0.00 3 106762 3012 0 0
x_make_qstorno_2100_3300 447869 447869 0 0.00 0 111828 153 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-03-2021 10:29:23 to 23-03-2021 10:29:27.

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
07:57:02 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:04 39053 8192000000 9731094448 10030231552 807451376 8.972 100 89 178 0 837047696 7972976 21623344
07:59:46 18903 8192000000 9776457680 10089021440 851346192 9.414 100 87 174 0 885646528 14155488 20144848
08:01:13 14557 8192000000 9823511536 10148585472 889319424 9.792 100 80 160 1 934152912 27087424 17746064
08:02:35 6897 8192000000 9884509936 10218782720 931862848 10.213 100 80 160 0 1000215248 51200736 17151664
08:03:45 10784 8192000000 9933205248 10274009088 966211456 10.550 100 88 176 0 1047602592 62693456 18697680
08:04:59 5254 8192000000 9972186368 10327990272 995291952 10.833 100 84 168 1 1081500560 69306912 16901696
08:06:05 2972 8192000000 9984632400 10347753472 1021463264 11.086 100 86 168 3 1105822560 67399648 16959648
08:07:09 1518 8192000000 10016523968 10384433152 1050324672 11.364 100 96 190 0 1131035088 61039456 19670960
08:08:13 13378 8192000000 10036529008 10434007040 1068345792 11.536 100 73 146 2 1163878448 80838688 14693968
08:09:43 5139 8192000000 10010683760 10398855168 1079337904 11.641 100 42 84 0 1124633808 36787104 8508800
08:10:49 6951 8192000000 9992830288 10390470656 1086186000 11.706 100 41 82 2 1114296112 18422496 9687616
08:11:56 73252 8192000000 10000118064 10410995712 1091144128 11.754 100 38 76 0 1120544592 21316720 8083744
08:14:11 7652 8192000000 10013615664 10427981824 1094911360 11.789 100 30 57 1 1128293104 27182384 6199360
08:15:19 8011 8192000000 10015510656 10423017472 1093789664 11.779 100 29 57 1 1135653328 36137600 5726064
08:16:29 11861 8192000000 9991915936 10389180416 1062424896 11.480 97 30 60 2 1089525792 21585392 5515504
08:17:41 25019 8192000000 9987136224 10392150016 1044367872 11.307 96 24 49 1 1080945072 31740080 4837120
08:19:08 5021 8192000000 9943258304 10330963968 1025748720 11.127 98 51 98 2 1053507776 16832192 10926864
08:20:13 10032 8192000000 9934535616 10325151744 998110512 10.860 97 34 67 1 1022149824 16600400 7438912
08:21:23 53481 8192000000 9945606112 10323496960 1020594320 11.078 99 43 86 1 1054532352 24566864 9371168
08:23:17 4320 8192000000 9950553056 10339205120 1025707424 11.127 100 35 70 0 1054507808 21433008 7367376
08:24:24 7246 8192000000 9984991648 10377498624 1048411920 11.345 100 49 99 1 1073984640 15187808 10384912
08:25:33 6766 8192000000 9988214720 10384842752 1061107568 11.467 100 47 94 0 1096178928 24691968 10379392
08:26:41 123863 8192000000 10009807152 10411134976 1080996304 11.657 100 31 62 1 1110548768 23260400 6292064
08:30:35 29026 8192000000 10119613328 10524028928 1123013968 12.055 100 43 86 1 1219689936 87610832 9065136
08:32:09 7733 8192000000 10183962928 10576748544 1142021904 12.235 100 73 139 2 1285224784 128635680 14567200
08:33:21 23094 8192000000 10203130816 10613235712 1147158784 12.283 99 44 88 1 1303444224 146554576 9730864
08:34:49 5497 8192000000 10235927728 10650411008 1173074320 12.526 100 53 106 2 1350921056 165567536 12279200
08:35:59 9308 8192000000 10268614080 10693685248 1178768400 12.579 100 46 91 0 1377703104 187724208 11210496
08:37:16 4981 8192000000 10315960976 10728779776 1181039760 12.600 100 56 110 2 1416346752 223159392 12147600
08:38:28 4344 8192000000 10382735824 10785370112 1186448432 12.650 100 86 172 0 1478330336 272247920 19633984
08:39:41 3646 8192000000 10406781888 10808971264 1189612656 12.680 100 76 151 1 1508342512 301721840 17008016
08:40:53 2327 8192000000 10434474784 10833481728 1169677024 12.494 98 82 162 1 1535463856 345257184 20529648
08:42:03 4141 8192000000 10465292944 10868813824 1167216048 12.471 98 80 160 2 1545645344 359846208 18583088
08:43:20 7790 8192000000 10523875984 10932072448 1191876384 12.701 100 74 146 1 1624246384 415335936 17034064
08:44:35 8223 8192000000 10529716096 10951536640 1173241488 12.527 100 50 100 0 1642301632 455380944 13679200
08:45:48 9071 8192000000 10570849152 10997432320 1169951856 12.496 99 50 98 1 1673119392 490392256 12775280
08:46:58 9799 8192000000 10502827344 10944192512 1171618000 12.512 100 59 117 3 1601220224 414875440 14726784
08:48:09 8216 8192000000 10509443728 10968690688 1145190160 12.264 99 41 82 2 1621896224 465739296 10966768
08:49:49 21440 8192000000 10495443232 10952597504 1146568800 12.277 100 32 63 0 1596548000 441266992 8712208
08:51:11 20151 8192000000 10364341408 10816946176 1112573488 11.957 99 28 56 1 1474647360 354291776 7782096
08:52:44 37310 8192000000 10028511248 10444910592 1058940304 11.446 95 11 22 1 1088646416 27574336 2131776
08:54:23 163386 8192000000 10048413424 10489217024 1078666208 11.635 96 46 92 0 1140480112 52575728 9238176
08:58:22 101057 8192000000 10085880416 10537381888 1122180320 12.048 99 18 36 0 1182662976 56236416 4246240
09:01:03 125472 8192000000 10214803568 10662023168 1129741360 12.119 98 20 40 1 1236226448 102020832 4464256
09:05:33 20357 8192000000 10098014896 10530529280 1144706992 12.260 100 43 85 1 1181884304 28228640 8948672
09:07:08 71309 8192000000 10112300336 10554519552 1151851296 12.327 100 15 29 2 1207060608 52243968 2965344
09:09:19 45407 8192000000 10138164448 10628804608 1128618944 12.108 97 29 58 0 1240193968 105764160 5810864
09:11:09 45091 8192000000 10132743152 10574499840 1149739104 12.307 99 41 82 0 1206638976 48599872 8300000
09:12:56 106561 8192000000 10070412368 10517954560 1093124912 11.772 95 25 49 2 1133046496 35158880 4762704
09:15:49 101212 8192000000 10073417376 10525011968 1144810752 12.261 100 29 58 1 1172767648 21603872 6353024
09:18:35 43041 8192000000 10050823296 10521804800 1126148976 12.085 99 4 9 0 1152252672 25144848 958848
09:20:19 48919 8192000000 10100790752 10561191936 1130408576 12.125 99 13 26 1 1184017456 50671024 2937856
09:22:08 39645 8192000000 10079089632 10549231616 1128776288 12.110 99 11 22 1 1188496848 57202640 2517920
09:23:48 42981 8192000000 10122918560 10559078400 1128305120 12.105 98 25 50 1 1198147168 64527440 5314608
09:25:44 46442 8192000000 10157543296 10602012672 1117831008 12.006 97 24 48 0 1212902224 89682304 5388912
09:27:35 63020 8192000000 10175730208 10619113472 1148083200 12.292 100 34 68 1 1274512160 118661904 7767056
09:30:21 80882 8192000000 10084556320 10604437504 1118723104 12.015 98 13 26 1 1186534416 64844560 2966752
09:32:50 97918 8192000000 10024835616 10473803776 1068409808 11.537 95 33 65 0 1124071328 48768704 6892816
09:35:32 25660 8192000000 10041384768 10515128320 1086705664 11.711 99 8 16 0 1140613696 52498080 1409952
09:37:02 46588 8192000000 10088995840 10529140736 1089473120 11.738 98 15 30 1 1152550704 59967808 3109776
09:39:07 76456 8192000000 10074380128 10535108608 1092261568 11.764 98 19 38 1 1152541728 56175856 4104304
09:41:26 21750 8192000000 10108817504 10532294656 1123062000 12.056 100 52 103 0 1188627968 55193488 10372480
09:42:50 102632 8192000000 10065165792 10499739648 1068361056 11.536 95 8 16 1 1108283808 38445856 1476896
09:45:32 24060 8192000000 10086959072 10533187584 1107584144 11.910 99 35 70 2 1164993536 50311696 7097696
09:47:12 53561 8192000000 10071110768 10576130048 1095687312 11.797 98 13 24 1 1184810416 86604720 2518384
09:49:06 67446 8192000000 10087148880 10535714816 1068572832 11.538 95 15 30 0 1106964336 35439600 2951904
09:51:14 109870 8192000000 10079040928 10521100288 1116272288 11.992 99 19 38 1 1178072384 57864784 3935312
09:54:05 20576 8192000000 10093506800 10567274496 1132834480 12.148 100 7 14 1 1208154992 73548608 1771904
09:55:40 45636 8192000000 10110037200 10567237632 1140973136 12.225 100 25 50 0 1203634320 57440016 5221168
09:57:44 32481 8192000000 10091051360 10538889216 1124952736 12.074 98 26 51 0 1160837040 30621728 5262576
09:59:24 49977 8192000000 10162895040 10605670400 1134231328 12.161 98 25 50 0 1229329904 89486912 5611664
10:01:21 71689 8192000000 10102287648 10614456320 1130114624 12.122 99 17 34 0 1231438208 97313696 4009888
10:03:50 70810 8192000000 10148067584 10587295744 1100862912 11.846 96 30 59 0 1165194528 58643232 5688384
10:06:03 99156 8192000000 10073815600 10534342656 1128123504 12.104 99 12 24 0 1183095872 52339904 2632464
10:08:43 76579 8192000000 10062019616 10517270528 1088901520 11.732 95 11 21 0 1121170176 30371216 1897440
10:10:59 41066 8192000000 10101549888 10538262528 1138275520 12.199 100 23 46 1 1169513312 26345488 4892304
10:12:41 69518 8192000000 10088563568 10582552576 1106632176 11.901 96 20 39 3 1191462256 80617088 4212992
10:14:57 60653 8192000000 10113221280 10565488640 1111341328 11.945 97 31 63 1 1152277136 34475712 6460096
10:16:59 72144 8192000000 10106577904 10564075520 1116618656 11.995 98 15 31 0 1214416976 94555440 3242880
10:19:17 60524 8192000000 10095279856 10565230592 1108882256 11.922 97 16 32 1 1160610576 48087952 3640368
10:21:23 136776 8192000000 10087917248 10525454336 1110022624 11.933 97 30 60 0 1141687552 25431520 6233408
10:24:44 74544 8192000000 10104216992 10568413184 1131630800 12.137 99 9 18 1 1196839280 63198512 2009968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 23-03-2021 10:29:29 to 23-03-2021 10:29:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-03-2021 10:29:44 to 23-03-2021 10:29:46.

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          227964
MON$OLDEST_ACTIVE               230567
MON$OLDEST_SNAPSHOT             230567
MON$NEXT_TRANSACTION            230567
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                       2094592
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             789
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 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.2390 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 ---
:243: 
:244: mscs-test-161.budzdorov.ru    Tue Mar 23 10:27:01 2021
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Tue Mar 23 10:27:16 2021
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:250: 
--- end of diff output ---

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

23.03.21 10:29:47. 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-23 10:29:50 No severe PSQL-related problems occured
23.03.21 10:29:51. End of report.