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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16664972 kB
MemAvailable 31391984 kB
Buffers 0 kB
Cached 14955272 kB
SwapCached 2924 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-11 10:26:55
DTS_END                         2020-11-11 10:26:55.1430
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59682
TRN_ID                          235711
ATT_ID                          684
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: 9059.90 24178 1087188
customer order: creation 171.94 379 20633
customer order: refuse 14.72 270 1766
order to supplier: creation 45.16 1330 5419
order to supplier: removal 3.17 103818 380
invoice (draft): creation 46.52 3185 5582
invoice (draft): removal 50.09 787 6011
invoice accept: apply 41.85 16086 5022
invoice accept: cancel 45.45 874 5454
customer reserve: creation 3867.71 158 464125
customer reserve: removal 4470.57 59 536468
realization accept: apply 84.07 163 10088
realization accept: cancel 89.75 102 10770
payment from customer: creation 60.38 97 7245
payment from customer: removal 5.41 79 649
payment to supplier: creation 48.36 103 5803
payment to supplier: removal 3.48 73 417
service: total inventory turnovers 4.03 2795 484
service: total monetary turnovers 6.28 483 753
service: aggregate perf. data 0.96 12660 115
service: refresh index statistics 0.03 340056 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-11-2020 10:27:45 to 11-11-2020 10:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1496
TEST_TIME 2 2827
TEST_TIME 3 4580
TEST_TIME 4 4473
TEST_TIME 5 4849
TEST_TIME 6 5524
TEST_TIME 7 5435
TEST_TIME 8 6070
TEST_TIME 9 5968
TEST_TIME 10 6314
TEST_TIME 11 6466
TEST_TIME 12 6675
TEST_TIME 13 6664
TEST_TIME 14 6630
TEST_TIME 15 6756
TEST_TIME 16 6857
TEST_TIME 17 7279
TEST_TIME 18 7585
TEST_TIME 19 8592
TEST_TIME 20 8401
TEST_TIME 21 8910
TEST_TIME 22 8896
TEST_TIME 23 8965
TEST_TIME 24 10354
TEST_TIME 25 10291
TEST_TIME 26 9984
TEST_TIME 27 10011
TEST_TIME 28 10217
TEST_TIME 29 10252
TEST_TIME 30 10131
TEST_TIME 31 10062
TEST_TIME 32 9886
TEST_TIME 33 9709
TEST_TIME 34 9713
TEST_TIME 35 10173
TEST_TIME 36 10076
TEST_TIME 37 9969
TEST_TIME 38 10110
TEST_TIME 39 10071
TEST_TIME 40 10044
TEST_TIME 41 10100
TEST_TIME 42 10177
TEST_TIME 43 10059
TEST_TIME 44 9939
TEST_TIME 45 9761
TEST_TIME 46 9585
TEST_TIME 47 9697
TEST_TIME 48 9576
TEST_TIME 49 9608
TEST_TIME 50 9652
TEST_TIME 51 9689
TEST_TIME 52 9562
TEST_TIME 53 9575
TEST_TIME 54 9479
TEST_TIME 55 9449
TEST_TIME 56 9340
TEST_TIME 57 9478
TEST_TIME 58 9421
TEST_TIME 59 9440
TEST_TIME 60 9348
TEST_TIME 61 9522
TEST_TIME 62 9485
TEST_TIME 63 9583
TEST_TIME 64 9676
TEST_TIME 65 9714
TEST_TIME 66 9718
TEST_TIME 67 9664
TEST_TIME 68 9722
TEST_TIME 69 9724
TEST_TIME 70 9719
TEST_TIME 71 9672
TEST_TIME 72 9632
TEST_TIME 73 9568
TEST_TIME 74 9533
TEST_TIME 75 9418
TEST_TIME 76 9348
TEST_TIME 77 9375
TEST_TIME 78 9371
TEST_TIME 79 9326
TEST_TIME 80 9337
TEST_TIME 81 9296
TEST_TIME 82 9318
TEST_TIME 83 9267
TEST_TIME 84 9297
TEST_TIME 85 9253
TEST_TIME 86 9282
TEST_TIME 87 9346
TEST_TIME 88 9384
TEST_TIME 89 9405
TEST_TIME 90 9405
TEST_TIME 91 9308
TEST_TIME 92 9228
TEST_TIME 93 9185
TEST_TIME 94 9157
TEST_TIME 95 9162
TEST_TIME 96 9122
TEST_TIME 97 9108
TEST_TIME 98 9127
TEST_TIME 99 9118
TEST_TIME 100 9132
TEST_TIME 101 9133
TEST_TIME 102 9186
TEST_TIME 103 9248
TEST_TIME 104 9215
TEST_TIME 105 9275
TEST_TIME 106 9303
TEST_TIME 107 9269
TEST_TIME 108 9205
TEST_TIME 109 9155
TEST_TIME 110 9131
TEST_TIME 111 9088
TEST_TIME 112 9085
TEST_TIME 113 9081
TEST_TIME 114 9072
TEST_TIME 115 9086
TEST_TIME 116 9061
TEST_TIME 117 9077
TEST_TIME 118 9092
TEST_TIME 119 9078
TEST_TIME 120 9064

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-11-2020 10:27:49 to 11-11-2020 10:27:52.

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 1516588 1516588 0 0.00 0 17595 6 0 0
doc_list_biud 1516587 1516587 0 0.00 0 60248 26 0 0
sp_add_invoice_to_stock 5142 5022 120 2.33 42 167385 16086 0 120
sp_cancel_adding_invoice 5454 5454 0 0.00 5 95517 874 0 0
sp_cancel_client_order 1766 1766 0 0.00 4 15314 271 0 0
sp_cancel_customer_reserve 536468 536468 0 0.00 1 60249 59 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 10532 79 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3581 74 0 0
sp_cancel_supplier_invoice 6015 6011 4 0.06 2 11029 788 0 4
sp_cancel_supplier_order 380 380 0 0.00 9 1002355 103819 0 0
sp_cancel_write_off 10770 10770 0 0.00 1 8177 103 0 0
sp_client_order 20633 20633 0 0.00 4 59371 380 0 0
sp_customer_reserve 464125 464125 0 0.00 0 88794 158 0 0
sp_fill_shopping_cart 31631 31631 0 0.00 1 14334 40 0 0
sp_fill_shopping_cart_clo_res 464121 464121 0 0.00 0 18971 14 0 0
sp_get_clo_for_invoice 5018 5018 0 0.00 0 51794 370 0 0
sp_kill_cost_storno 17290 17290 0 0.00 0 8103 53 0 0
sp_kill_qstorno_ret_qs2qd 1090097 1090097 0 0.00 0 60245 32 0 0
sp_kill_qty_storno 575955 575955 0 0.00 0 60247 69 0 0
sp_lock_selected_doc 20802 20802 0 0.00 0 19288 8 0 0
sp_make_cost_storno 28154 28154 0 0.00 0 12766 48 0 0
sp_multiply_rows_for_pdistr 28154 28154 0 0.00 0 6483 30 0 0
sp_multiply_rows_for_qdistr 20633 20633 0 0.00 0 59361 211 0 0
sp_pay_from_customer 7245 7245 0 0.00 4 9007 98 0 0
sp_pay_to_supplier 5803 5803 0 0.00 5 12637 103 0 0
sp_payment_common 13048 13048 0 0.00 1 5740 18 0 0
sp_qd_handle_on_cancel_clo 1766 1766 0 0.00 0 15305 250 0 0
sp_qd_handle_on_invoice_upd_sts 10472 10472 0 0.00 0 40089 328 0 0
sp_qd_handle_on_reserve_upd_sts 10088 10088 0 0.00 0 22424 46 0 0
sp_reserve_write_off 10088 10088 0 0.00 7 22447 164 0 0
sp_supplier_invoice 5582 5582 0 0.00 6 98847 3185 0 0
sp_supplier_order 5419 5419 0 0.00 5 111721 1331 0 0
srv_aggregate_perf_data 115 115 0 0.00 2562 128512 12661 0 0
srv_fill_mon_cache_memory 59 59 0 0.00 1387 134469 48225 0 0
srv_find_qd_qs_mism 1039216 1039216 0 0.00 0 17594 5 0 0
srv_make_invnt_saldo 484 484 0 0.00 71 110298 2795 0 0
srv_make_money_saldo 753 753 0 0.00 1 15171 484 0 0
srv_recalc_idx_stat 4 4 0 0.00 120139 478423 340056 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 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 5022 5022 0 0.00 0 26393 158 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 11168 106 0 0
t$perf-norm:sp_cancel_client_order 1766 1766 0 0.00 0 5384 18 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 4667 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 665 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 178 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 362 362 0 0.00 0 1732 19 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 13229 1322 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 3219 8 0 0
t$perf-norm:sp_client_order 20633 20633 0 0.00 0 6766 17 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 371 4 0 0
t$perf-norm:sp_pay_from_customer 7245 7245 0 0.00 0 4228 8 0 0
t$perf-norm:sp_pay_to_supplier 5803 5803 0 0.00 0 7595 9 0 0
t$perf-norm:sp_reserve_write_off 10088 10088 0 0.00 0 14542 14 0 0
t$perf-norm:sp_supplier_invoice 5582 5582 0 0.00 0 4104 14 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 6940 17 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 616 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 59 59 0 0.00 0 495 9 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1217 12 0 0
t$perf-norm:srv_make_money_saldo 753 753 0 0.00 0 1572 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10276 10036 240 2.33 0 3515 15 0 240
v_all_customers 37488 37488 0 0.00 0 214 1 0 0
v_all_suppliers 30042 30042 0 0.00 0 17 1 0 0
v_all_wares 41264 41264 0 0.00 0 31 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 1671 8 0 0
v_cancel_client_order 3532 3532 0 0.00 0 3789 5 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 1650 8 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 357 3 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1837 8 0 0
v_cancel_supplier_invoice 732 724 8 1.09 0 262 10 0 8
v_cancel_supplier_order 760 760 0 0.00 0 7350 16 0 0
v_cancel_write_off 2000 2000 0 0.00 0 922 11 0 0
v_max_id_clo_ord 5418 5418 0 0.00 0 199 1 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 7283 26 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 4298 5 0 0
v_max_non_paid_invoice 1781 1781 0 0.00 0 502 2 0 0
v_max_non_paid_realizn 3912 3912 0 0.00 0 3135 4 0 0
v_min_id_clo_ord 5418 5418 0 0.00 0 2139 5 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 2002 7 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 6659 9 0 0
v_min_non_paid_invoice 5803 5803 0 0.00 0 3566 10 0 0
v_min_non_paid_realizn 7245 7245 0 0.00 0 3798 13 0 0
v_our_firm 10442 10442 0 0.00 0 12 1 0 0
v_reserve_write_off 20176 20176 0 0.00 0 1843 4 0 0
x_lock_depdocs_on_canc_invoice 11103 11103 0 0.00 0 16422 113 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 9758 310 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 2 111699 1264 0 0
x_make_qstorno_1200_2000 5582 5582 0 0.00 3 98805 3026 0 0
x_make_qstorno_2100_3300 464124 464124 0 0.00 0 88793 142 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2020 10:27:53 to 11-11-2020 10:27:57.

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:56:58 2 8192000000 8796633152 8807174144 3163616 0.038 1 1 2 0 3261312 61840 35856
07:57:59 45363 8192000000 9716272768 10014113792 787335744 8.768 100 87 174 0 816544784 7895904 21313136
07:59:52 23577 8192000000 9769735760 10076266496 837006608 9.270 100 94 188 0 873766672 14557248 22202816
08:01:35 21005 8192000000 9832923008 10158034944 883252032 9.732 100 81 158 3 936736464 35766672 17717760
08:02:57 8722 8192000000 9892156416 10235682816 940150816 10.294 100 67 132 4 1003088144 48267072 14670256
08:04:08 3306 8192000000 9954903248 10304770048 990101104 10.782 100 75 145 3 1064887856 59673680 15113072
08:05:14 3179 8192000000 9993343920 10351095808 1016989056 11.043 100 74 148 1 1103718528 72128848 14600624
08:06:18 6692 8192000000 10015488464 10385068032 1034198912 11.209 100 69 138 0 1127624016 80089216 13335888
08:07:27 2348 8192000000 10027481536 10396352512 1058209904 11.439 100 96 193 0 1142204800 63978480 20016416
08:08:32 2549 8192000000 10024963696 10399449088 1063153936 11.487 100 85 170 1 1136109680 55012336 17943408
08:09:36 6844 8192000000 9987568272 10385969152 1071127040 11.563 100 34 66 2 1112101264 34172256 6801968
08:10:43 14232 8192000000 10002177792 10384871424 1077771696 11.626 100 78 157 0 1104950784 9268016 17911072
08:11:58 8930 8192000000 10007933424 10402676736 1082728416 11.673 100 38 76 4 1112329968 21328944 8272608
08:13:07 6216 8192000000 9992774016 10400583680 1088442384 11.728 100 27 52 1 1109476752 14849120 6185248
08:14:17 4413 8192000000 10018250272 10409144320 1094372480 11.784 100 60 120 2 1128520240 19944256 14203504
08:15:25 10039 8192000000 10019612160 10414436352 1098312640 11.822 100 69 139 4 1127358912 12709760 16336512
08:16:36 7324 8192000000 9994681056 10387468288 1061687040 11.473 97 34 68 1 1085367600 16199152 7481408
08:17:48 6558 8192000000 9989538160 10386534400 1045570832 11.318 97 49 98 3 1082915632 26621168 10723632
08:18:59 65981 8192000000 9944906208 10345824256 997055168 10.850 95 31 62 0 1021517568 17309312 7153088
08:21:06 6223 8192000000 9935405056 10320666624 1004788896 10.925 99 41 83 1 1031823696 18441808 8592992
08:22:16 8513 8192000000 9943536592 10323922944 1022752224 11.099 99 40 80 2 1048597552 16274944 9570384
08:23:27 9019 8192000000 9968445616 10366005248 1046621728 11.328 100 32 64 2 1070590256 17130768 6837760
08:24:36 41899 8192000000 9970717728 10375200768 1059592144 11.453 100 30 59 2 1083912464 17421136 6899184
08:26:18 6547 8192000000 9994602400 10385727488 1078576720 11.634 100 34 68 3 1101916784 16100112 7239952
08:27:30 20408 8192000000 10008407776 10402799616 1090129840 11.744 100 44 86 1 1109570208 10432800 9007568
08:29:01 10853 8192000000 10089194912 10479980544 1117995424 12.008 100 62 123 0 1203757152 72121184 13640544
08:30:18 27749 8192000000 10099417808 10505252864 1123799680 12.063 100 40 80 1 1204863024 72524464 8538880
08:31:51 9060 8192000000 10161905952 10568663040 1133497584 12.154 100 51 103 2 1268089536 124193072 10398880
08:33:05 7759 8192000000 10161600048 10562588672 1141869392 12.233 100 60 116 1 1265921760 111149872 12902496
08:34:20 6333 8192000000 10163826864 10598277120 1150127472 12.311 100 52 104 1 1275212192 112930784 12153936
08:35:31 5349 8192000000 10192124496 10602106880 1146942896 12.281 99 56 112 0 1290730976 130944528 12843552
08:36:42 7318 8192000000 10230743888 10637541376 1163112336 12.432 100 62 124 0 1338677488 162116112 13449040
08:37:56 1385 8192000000 10282785056 10679570432 1159010800 12.394 99 78 154 4 1374561696 198566800 16984096
08:39:01 1770 8192000000 10287911808 10681610240 1146503120 12.277 98 91 183 2 1377975040 210775744 20696176
08:40:08 5274 8192000000 10349883488 10748366848 1168298800 12.481 99 72 142 0 1431664560 247219472 16146288
08:41:19 1447 8192000000 10389379520 10794471424 1182369536 12.612 100 77 154 0 1483009760 282650752 17989472
08:42:24 3183 8192000000 10419096496 10817654784 1167030656 12.469 100 82 162 4 1522219600 335963136 19225808
08:43:32 2951 8192000000 10431073216 10847834112 1156624512 12.372 100 55 110 0 1534872160 364684624 13563024
08:44:39 11278 8192000000 10424473792 10853052416 1165268240 12.453 100 42 84 2 1535806592 359706768 10831584
08:46:01 23735 8192000000 10327881552 10782240768 1128897776 12.111 97 26 51 1 1416734864 281160128 6676960
08:47:52 37436 8192000000 10259787264 10729361408 1096500160 11.804 98 19 38 1 1378797344 277241248 5055936
08:49:42 21402 8192000000 10288454864 10740412416 1107912192 11.913 99 22 44 0 1403297296 289913872 5471232
08:51:11 80607 8192000000 10079747744 10503204864 1100073456 11.838 98 18 36 0 1168550080 64062064 4414560
08:53:52 58454 8192000000 10087805456 10618085376 1094376784 11.784 99 9 18 0 1213330384 116891104 2062496
08:55:51 50750 8192000000 10068957808 10493435904 1116607648 11.995 100 28 56 0 1166503840 44398688 5497504
08:57:44 89939 8192000000 10113090336 10544103424 1084111616 11.687 96 16 32 0 1144383344 57302496 2969232
09:01:44 110970 8192000000 10077505936 10490331136 1112731680 11.958 99 16 31 1 1174217360 57554560 3931120
09:04:37 55885 8192000000 10069258784 10535415808 1116758368 11.996 99 17 34 1 1152477728 31726368 3992992
09:06:45 57394 8192000000 10096077984 10573746176 1126013840 12.084 99 13 26 3 1194885008 66014608 2856560
09:08:44 17987 8192000000 10079086272 10521071616 1139833584 12.214 100 30 58 1 1173245056 26824784 6586688
09:10:11 84787 8192000000 10122319072 10551263232 1145876160 12.271 100 18 34 2 1209881440 60128400 3876880
09:13:18 31220 8192000000 10121083664 10603941888 1157113664 12.376 100 14 28 0 1216837040 56487840 3235536
09:14:50 46959 8192000000 10137628304 10605252608 1156028224 12.366 100 28 55 1 1248082816 86501616 5552976
09:16:51 91538 8192000000 10101935488 10553839616 1154070272 12.348 99 23 47 0 1239689152 80519904 5098976
09:20:08 71906 8192000000 10092889984 10559856640 1137850080 12.195 98 17 34 1 1208304304 66711088 3743136
09:22:21 56464 8192000000 10107113696 10612260864 1146623376 12.278 99 15 30 1 1251791920 101454672 3713872
09:24:39 70873 8192000000 10107790112 10565971968 1141636960 12.231 98 16 32 0 1177791680 32521696 3633024
09:26:50 111374 8192000000 10161320848 10670399488 1144988720 12.262 98 22 44 1 1261738208 112401104 4348384
09:29:41 96990 8192000000 10094401024 10533793792 1073084480 11.582 92 9 18 0 1096366336 21654576 1627280
09:32:25 52311 8192000000 10087644560 10501001216 1064288560 11.498 93 33 66 0 1124758768 53980544 6489664
09:35:18 17043 8192000000 10091007328 10501967872 1125083728 12.075 100 53 105 2 1169058800 33315648 10659424
09:36:35 43572 8192000000 10095487136 10527182848 1076315696 11.612 96 32 64 0 1139408128 57176176 5916256
09:38:22 102919 8192000000 10038948992 10485145600 1105545008 11.890 99 30 58 1 1163774912 51572400 6657504
09:43:19 60735 8192000000 10044891168 10579488768 1080793616 11.655 98 17 33 1 1175298224 90081088 4423520
09:45:26 45157 8192000000 10041652704 10474942464 1083899728 11.685 98 18 36 2 1118287920 30624976 3763216
09:47:14 41190 8192000000 10078496256 10506924032 1118375200 12.012 99 24 47 2 1156982032 33513584 5093248
09:48:56 46422 8192000000 10081556032 10511437824 1103414640 11.870 98 28 54 0 1145718432 36788016 5515776
09:50:44 30252 8192000000 10055761552 10479845376 1087927392 11.723 97 30 58 2 1115326112 21086832 6311888
09:52:17 46002 8192000000 10129975328 10553217024 1126641808 12.090 100 36 72 2 1207648000 73391248 7614944
09:54:10 56528 8192000000 10067665072 10533597184 1105221312 11.887 98 16 32 1 1171171936 62578624 3372000
09:56:43 134469 8192000000 10074383024 10523729920 1120862896 12.035 99 12 24 0 1136341920 13371712 2107312
10:00:03 71179 8192000000 10052487152 10527862784 1114705504 11.977 98 5 10 0 1147568368 31965072 897792
10:02:52 54370 8192000000 10135547360 10583474176 1151203552 12.321 100 23 46 1 1194669392 38553008 4912832
10:04:59 70056 8192000000 10152714544 10595700736 1132767040 12.147 98 14 28 1 1223136560 87349872 3019648
10:07:10 107930 8192000000 10166019168 10681839616 1130883680 12.130 97 13 26 2 1203670432 70274768 2511984
10:10:03 42509 8192000000 10116167744 10544848896 1153790528 12.345 100 13 25 2 1207627136 51393120 2443488
10:12:59 51515 8192000000 10127632976 10558504960 1114168304 11.972 96 27 54 2 1156757680 36392848 6196528
10:14:56 94080 8192000000 10080494736 10562043904 1135075712 12.169 99 12 24 1 1200731984 62979392 2676880
10:17:30 49827 8192000000 10105502128 10563031040 1149821008 12.308 100 8 16 0 1199865616 48192128 1852480
10:19:21 43117 8192000000 10087164160 10552975360 1138625840 12.203 99 16 32 2 1172930384 30771504 3533040
10:21:04 75414 8192000000 10057830880 10510880768 1079684880 11.644 95 21 42 0 1140903952 56738512 4480560
10:23:20 77974 8192000000 10074480000 10548740096 1111397648 11.946 98 15 30 0 1164293856 49536480 3359728
10:25:48 41826 8192000000 10080324224 10510880768 1086479568 11.709 96 33 66 0 1146244672 53333392 6431712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-11-2020 10:27:59 to 11-11-2020 10:28:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 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 11-11-2020 10:28:15 to 11-11-2020 10:28:17.

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          235710
MON$OLDEST_ACTIVE               238613
MON$OLDEST_SNAPSHOT             238613
MON$NEXT_TRANSACTION            238613
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                       2112064
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             805
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2250 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2250 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.2250 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed Nov 11 10:26:56 2020
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed Nov 11 10:26:58 2020
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed Nov 11 10:27:03 2020
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Wed Nov 11 10:27:03 2020
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

11.11.20 10:28:18. 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-11 10:28:20 No severe PSQL-related problems occured
11.11.20 10:28:21. End of report.