Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2020 13:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16978816 kB
MemAvailable 31452776 kB
Buffers 0 kB
Cached 14726520 kB
SwapCached 3484 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 2020-11-14 13:26:54
DTS_END                         2020-11-14 13:26:54.4230
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/52272
TRN_ID                          234594
ATT_ID                          682
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: 8901.23 22361 1068147
customer order: creation 169.84 388 20381
customer order: refuse 15.03 304 1804
order to supplier: creation 45.02 1222 5402
order to supplier: removal 3.12 104688 374
invoice (draft): creation 46.88 3265 5625
invoice (draft): removal 48.44 764 5813
invoice accept: apply 41.67 18853 5000
invoice accept: cancel 43.60 823 5232
customer reserve: creation 3882.13 185 465856
customer reserve: removal 4322.41 61 518689
realization accept: apply 83.18 160 9982
realization accept: cancel 71.66 127 8599
payment from customer: creation 57.93 96 6951
payment from customer: removal 5.39 64 647
payment to supplier: creation 50.60 109 6072
payment to supplier: removal 3.51 69 421
service: total inventory turnovers 3.95 3053 474
service: total monetary turnovers 5.98 431 717
service: aggregate perf. data 0.86 14069 103
service: refresh index statistics 0.04 298484 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2020 13:30:12 to 14-11-2020 13:30:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2224
TEST_TIME 2 4177
TEST_TIME 3 4627
TEST_TIME 4 4707
TEST_TIME 5 4742
TEST_TIME 6 4815
TEST_TIME 7 5107
TEST_TIME 8 5338
TEST_TIME 9 5571
TEST_TIME 10 6005
TEST_TIME 11 6106
TEST_TIME 12 6156
TEST_TIME 13 6128
TEST_TIME 14 6374
TEST_TIME 15 6613
TEST_TIME 16 7047
TEST_TIME 17 7034
TEST_TIME 18 8317
TEST_TIME 19 8386
TEST_TIME 20 8481
TEST_TIME 21 8598
TEST_TIME 22 8930
TEST_TIME 23 9522
TEST_TIME 24 10610
TEST_TIME 25 10604
TEST_TIME 26 10240
TEST_TIME 27 10027
TEST_TIME 28 9859
TEST_TIME 29 9629
TEST_TIME 30 9510
TEST_TIME 31 9447
TEST_TIME 32 9274
TEST_TIME 33 9171
TEST_TIME 34 9298
TEST_TIME 35 9141
TEST_TIME 36 9095
TEST_TIME 37 9002
TEST_TIME 38 9002
TEST_TIME 39 9085
TEST_TIME 40 9140
TEST_TIME 41 9191
TEST_TIME 42 9265
TEST_TIME 43 9246
TEST_TIME 44 9152
TEST_TIME 45 9255
TEST_TIME 46 9239
TEST_TIME 47 9181
TEST_TIME 48 9243
TEST_TIME 49 9373
TEST_TIME 50 9230
TEST_TIME 51 9089
TEST_TIME 52 9023
TEST_TIME 53 8949
TEST_TIME 54 8963
TEST_TIME 55 8857
TEST_TIME 56 8840
TEST_TIME 57 8915
TEST_TIME 58 8858
TEST_TIME 59 8790
TEST_TIME 60 9040
TEST_TIME 61 8983
TEST_TIME 62 9046
TEST_TIME 63 9024
TEST_TIME 64 9227
TEST_TIME 65 9267
TEST_TIME 66 9316
TEST_TIME 67 9417
TEST_TIME 68 9391
TEST_TIME 69 9355
TEST_TIME 70 9405
TEST_TIME 71 9458
TEST_TIME 72 9401
TEST_TIME 73 9362
TEST_TIME 74 9338
TEST_TIME 75 9311
TEST_TIME 76 9290
TEST_TIME 77 9335
TEST_TIME 78 9352
TEST_TIME 79 9352
TEST_TIME 80 9301
TEST_TIME 81 9203
TEST_TIME 82 9171
TEST_TIME 83 9115
TEST_TIME 84 9103
TEST_TIME 85 9059
TEST_TIME 86 9022
TEST_TIME 87 8983
TEST_TIME 88 8907
TEST_TIME 89 8874
TEST_TIME 90 8910
TEST_TIME 91 8889
TEST_TIME 92 8856
TEST_TIME 93 8871
TEST_TIME 94 8912
TEST_TIME 95 8996
TEST_TIME 96 8970
TEST_TIME 97 9027
TEST_TIME 98 9014
TEST_TIME 99 9028
TEST_TIME 100 9069
TEST_TIME 101 9046
TEST_TIME 102 8990
TEST_TIME 103 8922
TEST_TIME 104 8962
TEST_TIME 105 8902
TEST_TIME 106 8878
TEST_TIME 107 8846
TEST_TIME 108 8808
TEST_TIME 109 8816
TEST_TIME 110 8797
TEST_TIME 111 8788
TEST_TIME 112 8805
TEST_TIME 113 8811
TEST_TIME 114 8772
TEST_TIME 115 8822
TEST_TIME 116 8889
TEST_TIME 117 8889
TEST_TIME 118 8909
TEST_TIME 119 8925
TEST_TIME 120 8905

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-11-2020 13:30:16 to 14-11-2020 13:30:19.

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 1497848 1497848 0 0.00 0 18210 7 0 0
doc_list_biud 1497847 1497847 0 0.00 0 23456 27 0 0
sp_add_invoice_to_stock 5110 5000 110 2.15 42 203392 18854 0 110
sp_cancel_adding_invoice 5232 5232 0 0.00 5 64584 823 0 0
sp_cancel_client_order 1804 1804 0 0.00 5 6466 304 0 0
sp_cancel_customer_reserve 518689 518689 0 0.00 1 21543 61 0 0
sp_cancel_pay_from_customer 647 647 0 0.00 3 3255 65 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 2950 70 0 0
sp_cancel_supplier_invoice 5817 5813 4 0.06 2 19008 765 0 4
sp_cancel_supplier_order 374 374 0 0.00 10 1047679 104689 0 0
sp_cancel_write_off 8599 8599 0 0.00 1 13677 127 0 0
sp_client_order 20381 20381 0 0.00 4 36437 388 0 0
sp_customer_reserve 465856 465856 0 0.00 0 71602 185 0 0
sp_fill_shopping_cart 31395 31395 0 0.00 1 16245 44 0 0
sp_fill_shopping_cart_clo_res 465847 465847 0 0.00 0 20092 20 0 0
sp_get_clo_for_invoice 4989 4989 0 0.00 0 37476 416 0 0
sp_kill_cost_storno 14899 14899 0 0.00 0 6144 54 0 0
sp_kill_qstorno_ret_qs2qd 1052164 1052164 0 0.00 0 21540 33 0 0
sp_kill_qty_storno 555482 555482 0 0.00 0 23456 71 0 0
sp_lock_selected_doc 20697 20697 0 0.00 0 6090 5 0 0
sp_make_cost_storno 27994 27994 0 0.00 0 18194 50 0 0
sp_multiply_rows_for_pdistr 27994 27994 0 0.00 0 6627 26 0 0
sp_multiply_rows_for_qdistr 20381 20381 0 0.00 0 14074 221 0 0
sp_pay_from_customer 6951 6951 0 0.00 4 10379 96 0 0
sp_pay_to_supplier 6072 6072 0 0.00 5 12109 110 0 0
sp_payment_common 13023 13023 0 0.00 2 10312 24 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 5987 264 0 0
sp_qd_handle_on_invoice_upd_sts 10221 10221 0 0.00 0 23455 336 0 0
sp_qd_handle_on_reserve_upd_sts 9982 9982 0 0.00 0 9292 43 0 0
sp_reserve_write_off 9982 9982 0 0.00 7 18307 160 0 0
sp_supplier_invoice 5625 5625 0 0.00 7 105036 3266 0 0
sp_supplier_order 5402 5402 0 0.00 5 41836 1222 0 0
srv_aggregate_perf_data 103 103 0 0.00 2805 229481 14070 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1401 157597 50112 0 0
srv_find_qd_qs_mism 1019627 1019627 0 0.00 0 13855 7 0 0
srv_make_invnt_saldo 474 474 0 0.00 49 99712 3054 0 0
srv_make_money_saldo 717 717 0 0.00 1 10272 431 0 0
srv_recalc_idx_stat 5 5 0 0.00 108171 561700 298485 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 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 5000 5000 0 0.00 0 13060 131 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 3744 80 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 3979 14 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 4533 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 647 647 0 0.00 0 2590 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 4488 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1062 19 0 0
t$perf-norm:sp_cancel_supplier_order 374 374 0 0.00 0 14215 1316 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 1011 6 0 0
t$perf-norm:sp_client_order 20381 20381 0 0.00 0 6522 17 0 0
t$perf-norm:sp_customer_reserve 1027 1027 0 0.00 0 3226 10 0 0
t$perf-norm:sp_pay_from_customer 6951 6951 0 0.00 0 9104 8 0 0
t$perf-norm:sp_pay_to_supplier 6072 6072 0 0.00 0 2402 5 0 0
t$perf-norm:sp_reserve_write_off 9982 9982 0 0.00 0 9103 9 0 0
t$perf-norm:sp_supplier_invoice 5625 5625 0 0.00 0 9875 13 0 0
t$perf-norm:sp_supplier_order 5402 5402 0 0.00 0 5759 15 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 514 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 14 0 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 294 4 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 2410 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 13 5 0 0
v_add_invoice_to_stock 10198 9978 220 2.15 0 5424 17 0 220
v_all_customers 37018 37018 0 0.00 0 53 1 0 0
v_all_suppliers 30954 30954 0 0.00 0 17 1 0 0
v_all_wares 40760 40760 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 1680 8 0 0
v_cancel_client_order 3608 3608 0 0.00 0 4274 10 0 0
v_cancel_customer_prepayment 1294 1294 0 0.00 0 430 3 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 2916 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 395 4 0 0
v_cancel_supplier_invoice 750 742 8 1.06 0 2857 18 0 8
v_cancel_supplier_order 748 748 0 0.00 0 128 2 0 0
v_cancel_write_off 2052 2052 0 0.00 0 3473 15 0 0
v_max_id_clo_ord 5399 5399 0 0.00 0 1077 1 0 0
v_max_id_clo_res 1027 1027 0 0.00 0 5851 17 0 0
v_max_id_ord_sup 5616 5616 0 0.00 0 4230 7 0 0
v_max_non_paid_invoice 1610 1610 0 0.00 0 3369 4 0 0
v_max_non_paid_realizn 3808 3808 0 0.00 0 2357 3 0 0
v_min_id_clo_ord 5399 5399 0 0.00 0 4147 5 0 0
v_min_id_clo_res 1027 1027 0 0.00 0 5004 23 0 0
v_min_id_ord_sup 5616 5616 0 0.00 0 8212 9 0 0
v_min_non_paid_invoice 6072 6072 0 0.00 0 10065 17 0 0
v_min_non_paid_realizn 6951 6951 0 0.00 0 10291 15 0 0
v_our_firm 10028 10028 0 0.00 0 18 1 0 0
v_reserve_write_off 19964 19964 0 0.00 0 4403 5 0 0
x_lock_depdocs_on_canc_invoice 10674 10674 0 0.00 0 17116 102 0 0
x_lock_depdocs_on_canc_sup_ord 374 374 0 0.00 0 9149 352 0 0
x_make_qstorno_1000_1200 5402 5402 0 0.00 2 41821 1145 0 0
x_make_qstorno_1200_2000 5625 5625 0 0.00 3 105029 3100 0 0
x_make_qstorno_2100_3300 465856 465856 0 0.00 0 71601 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2020 13:30:20 to 14-11-2020 13:30:24.

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
10:56:58 2 8192000000 8796641312 8807108608 3168704 0.038 1 1 2 0 3266400 61840 35856
10:57:59 41990 8192000000 9730973360 10028093440 798570848 8.882 100 93 186 0 830208928 8770736 22867344
10:59:48 27652 8192000000 9768474432 10076389376 841266848 9.312 100 84 168 0 874655824 13482448 19906528
11:01:18 10917 8192000000 9791447520 10112974848 869645440 9.596 100 73 146 0 904956048 19082896 16227712
11:02:33 11253 8192000000 9883577232 10212929536 914364352 10.040 100 83 167 1 980740640 48694544 17681744
11:03:46 12413 8192000000 9931900080 10276085760 960871488 10.498 100 81 161 3 1039992880 62207440 16913952
11:05:00 5329 8192000000 9971517952 10326786048 994935328 10.829 100 74 148 0 1083918944 73829360 15154256
11:06:08 3953 8192000000 9996981760 10362580992 1030150304 11.170 100 82 165 2 1106515632 59720896 16644432
11:07:13 2434 8192000000 10007910720 10376617984 1051770816 11.378 100 86 172 1 1121200976 50919488 18510672
11:08:18 1191 8192000000 10055834832 10428116992 1066648736 11.520 100 90 179 0 1168062080 83517200 17896144
11:09:20 1625 8192000000 10029211936 10416238592 1074225584 11.592 100 79 159 2 1140199344 48995168 16978592
11:10:25 4723 8192000000 10011527072 10409803776 1079397664 11.642 100 43 86 1 1126858016 37989552 9470800
11:11:33 61216 8192000000 10016879824 10425192448 1084772576 11.693 100 38 76 1 1118771088 25824176 8174336
11:13:35 6514 8192000000 10021717376 10419232768 1096327504 11.803 100 46 90 0 1138028640 31636464 10064672
11:14:43 5369 8192000000 10015737840 10421710848 1099861344 11.836 100 39 78 2 1127249680 19434112 7954224
11:15:49 7225 8192000000 10024005792 10435026944 1102804704 11.864 100 39 80 1 1145652096 33983696 8863696
11:16:58 13856 8192000000 10041252784 10435698688 1103307408 11.869 100 68 139 2 1146709216 27657568 15744240
11:18:12 10263 8192000000 9978214528 10382413824 1061817552 11.474 98 37 74 2 1099000400 28820400 8362448
11:19:24 6581 8192000000 9934166176 10315755520 978939856 10.674 93 31 63 0 1012577936 26600832 7037248
11:20:32 31818 8192000000 9936614704 10336428032 957803216 10.468 92 20 38 3 989539312 27829584 3906512
11:22:04 5550 8192000000 9934702928 10323873792 1018046448 11.053 99 43 84 1 1032781280 5585328 9149504
11:23:10 10554 8192000000 9953456800 10349686784 1032837968 11.196 100 45 89 3 1068821616 25787184 10196464
11:24:21 69256 8192000000 9964997232 10375847936 1047548160 11.337 99 25 50 0 1079960624 26579360 5833104
11:26:30 6916 8192000000 9987608736 10397753344 1084772080 11.693 100 31 61 2 1102685248 11090848 6822320
11:27:44 5023 8192000000 10035201712 10429358080 1095909776 11.799 100 58 117 1 1141809488 34423472 11476240
11:28:52 6608 8192000000 10081367792 10480250880 1108848848 11.922 100 58 116 0 1185243616 64011872 12382896
11:30:04 8948 8192000000 10077261408 10482987008 1121372400 12.040 100 44 88 2 1192588224 61604144 9611680
11:31:22 28937 8192000000 10113699008 10528702464 1131116144 12.132 100 39 78 3 1224560512 84444176 9000192
11:32:59 13343 8192000000 10157118768 10575699968 1143716112 12.250 100 52 104 0 1265526752 110237248 11573392
11:34:18 5334 8192000000 10185735616 10605817856 1156134144 12.367 100 57 112 0 1293157072 124854400 12168528
11:35:27 5309 8192000000 10227830544 10637148160 1169283136 12.490 100 62 122 1 1332867824 150186256 13398432
11:36:39 2180 8192000000 10254582176 10662027264 1177315136 12.565 100 76 151 1 1360131168 165208592 17607440
11:37:48 3171 8192000000 10273128304 10686128128 1180985616 12.599 100 62 124 0 1379877808 184671680 14220512
11:38:56 5221 8192000000 10333807600 10741526528 1179746688 12.588 100 80 157 2 1428080784 231231584 17102512
11:40:06 4995 8192000000 10371066176 10781679616 1185225616 12.639 100 75 150 1 1470274624 267903424 17145584
11:41:19 4000 8192000000 10375926784 10791432192 1166053392 12.460 100 70 140 1 1472273184 290865920 15353872
11:42:29 1399 8192000000 10374899632 10795114496 1167916992 12.477 100 87 173 2 1474481120 285847840 20716288
11:43:35 6663 8192000000 10415299264 10824716288 1156773104 12.373 100 70 140 0 1508716192 334625920 17317168
11:44:48 19566 8192000000 10309118528 10737778688 1158918000 12.393 99 32 64 1 1414752768 247675808 8158960
11:46:14 19487 8192000000 10342636688 10770956288 1145375520 12.266 99 38 75 1 1418575840 263958032 9242288
11:47:38 20817 8192000000 10333476928 10797686784 1144154432 12.255 100 25 50 4 1453391552 302611344 6625776
11:49:03 27867 8192000000 10222562944 10695655424 1118963136 12.017 100 18 36 1 1358281904 234585728 4733040
11:50:35 48710 8192000000 10082348448 10521034752 1085398080 11.699 96 11 22 0 1164069776 76079264 2592432
11:52:35 85963 8192000000 10045697152 10486345728 1075931296 11.609 96 18 36 2 1114497056 34905968 3659792
11:55:02 65434 8192000000 10041948672 10486599680 1081034304 11.657 98 19 38 2 1112926208 28011888 3880016
11:57:23 58548 8192000000 10066532128 10531463168 1067052656 11.524 96 14 28 0 1157152480 87266896 2832928
11:59:24 45598 8192000000 10084606768 10541699072 1087473568 11.719 97 31 61 3 1192797136 98529456 6794112
12:01:59 31749 8192000000 10061269056 10508341248 1110873056 11.941 99 39 77 0 1178435520 59821232 7741232
12:04:01 73432 8192000000 10120327360 10556735488 1110237904 11.935 99 14 28 2 1202314256 89310672 2765680
12:06:15 51524 8192000000 10048900160 10522050560 1076584720 11.615 97 11 22 1 1154010240 74887152 2538368
12:08:09 54901 8192000000 10084627056 10559848448 1123813104 12.063 100 31 62 0 1213849424 83791968 6244352
12:10:04 26265 8192000000 10074023792 10488426496 1100239840 11.840 98 46 90 2 1131286192 21909760 9136592
12:11:33 101070 8192000000 10099727168 10519068672 1123353168 12.059 99 30 58 1 1202028336 72716160 5959008
12:14:16 88148 8192000000 10096942144 10601639936 1126964896 12.093 99 14 28 0 1175978416 46466864 2546656
12:16:51 157597 8192000000 10102080576 10579869696 1127591120 12.099 99 27 54 0 1193870224 60444320 5834784
12:20:29 62599 8192000000 10107560608 10611105792 1128888496 12.111 99 11 22 2 1192390928 61357920 2144512
12:22:38 94427 8192000000 10148715168 10642825216 1134593760 12.165 98 22 44 0 1241203856 101958624 4651472
12:26:11 90191 8192000000 10106043552 10545446912 1123204448 12.057 99 12 24 2 1171315456 45571632 2539376
12:28:42 99403 8192000000 10159174480 10624729088 1074601312 11.596 93 19 38 0 1174490576 95828688 4060576
12:31:24 120788 8192000000 10112510672 10620690432 1106755120 11.902 98 9 18 2 1155916384 47575296 1585968
12:34:25 63669 8192000000 10124860496 10626142208 1136265216 12.180 100 9 18 0 1244333472 106266832 1801424
12:36:47 118290 8192000000 10086045568 10590625792 1130279120 12.124 99 7 14 0 1183522848 51853504 1390224
12:39:46 38262 8192000000 10067080976 10515083264 1105690320 11.892 97 22 43 2 1147041920 37106432 4245168
12:41:30 18831 8192000000 10051691632 10479771648 1072319344 11.574 96 25 50 2 1107605744 30282624 5003776
12:42:50 107697 8192000000 10053249664 10515038208 1064630352 11.501 95 20 40 2 1131410720 62097904 4682464
12:45:39 62801 8192000000 10067343456 10504716288 1090362032 11.746 97 23 46 0 1144085392 49412144 4311216
12:47:43 65417 8192000000 10066552048 10518024192 1109634896 11.929 99 31 62 2 1152789200 36734912 6419392
12:49:49 62904 8192000000 10028747104 10500939776 1080410752 11.651 96 10 20 1 1103666688 21498832 1757104
12:51:52 58448 8192000000 10021375872 10465288192 1088550512 11.729 98 20 40 1 1109496352 16958288 3987552
12:53:56 78755 8192000000 10059386608 10517602304 1108783728 11.921 99 17 34 0 1146120752 33758768 3578256
12:56:15 60565 8192000000 10035632752 10476949504 1064977440 11.504 96 17 33 1 1104954896 36702336 3275120
12:58:36 41723 8192000000 10090119936 10550530048 1113361904 11.964 99 31 62 0 1175947136 55437232 7148000
13:00:20 70697 8192000000 10053853680 10561036288 1089377936 11.737 97 14 28 0 1166861664 74375280 3108448
13:02:32 15253 8192000000 10085301312 10509635584 1111451072 11.946 99 49 98 1 1167506432 46453920 9601440
13:03:54 31400 8192000000 10104474944 10543525888 1116336720 11.992 99 30 60 1 1172246160 49851952 6057488
13:05:28 30741 8192000000 10102665216 10528346112 1103782624 11.874 98 42 84 1 1186318816 74188480 8347712
13:07:00 42314 8192000000 10078463120 10503647232 1114754928 11.977 99 32 63 2 1173393008 52162208 6475872
13:08:42 101104 8192000000 10083850624 10567802880 1110261792 11.935 99 25 48 1 1225052432 109474256 5316384
13:11:57 66217 8192000000 10038279440 10500386816 1085268880 11.698 97 10 20 0 1132559136 45083072 2207184
13:14:03 67457 8192000000 10050081072 10504257536 1067077360 11.524 95 27 53 2 1122677648 49680144 5920144
13:16:33 40764 8192000000 10072599536 10532687872 1088845552 11.732 97 19 38 0 1137231328 43741184 4644592
13:18:14 58074 8192000000 10077995424 10555965440 1092491616 11.766 98 20 39 1 1163025328 66289600 4244112
13:20:14 43479 8192000000 10151052896 10578505728 1127864064 12.101 99 29 56 0 1214828144 80814096 6149984
13:22:01 116250 8192000000 10063366480 10505236480 1100845648 11.846 98 17 35 1 1136425984 31945312 3635024
13:24:59 50450 8192000000 10070783072 10509795328 1088153504 11.725 97 12 24 1 1123753376 33217600 2382272

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 14-11-2020 13:30:26 to 14-11-2020 13:30:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 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 14-11-2020 13:30:41 to 14-11-2020 13:30:43.

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          234199
MON$OLDEST_ACTIVE               235070
MON$OLDEST_SNAPSHOT             235070
MON$NEXT_TRANSACTION            235070
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                       2078752
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             798
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2265 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2265 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.2265 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 ---
--- end of diff output ---

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

14.11.20 13:30:44. 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
2020-11-14 13:30:46 No severe PSQL-related problems occured
14.11.20 13:30:48. End of report.