Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.01.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 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 16913744 kB
MemAvailable 31339728 kB
Buffers 0 kB
Cached 14701388 kB
SwapCached 4864 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-01-05 10:26:56
DTS_END                         2021-01-05 10:26:56.3000
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/56024
TRN_ID                          225687
ATT_ID                          617
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: 8558.51 24799 1027021
customer order: creation 160.65 454 19278
customer order: refuse 14.28 339 1713
order to supplier: creation 42.77 1515 5132
order to supplier: removal 3.20 122257 384
invoice (draft): creation 43.38 4070 5205
invoice (draft): removal 46.88 940 5625
invoice accept: apply 39.38 18924 4726
invoice accept: cancel 41.94 941 5033
customer reserve: creation 3681.33 182 441760
customer reserve: removal 4202.73 74 504327
realization accept: apply 80.18 188 9621
realization accept: cancel 81.19 126 9743
payment from customer: creation 54.93 109 6592
payment from customer: removal 5.59 107 671
payment to supplier: creation 46.19 130 5543
payment to supplier: removal 3.38 78 405
service: total inventory turnovers 3.79 3439 455
service: total monetary turnovers 5.84 674 701
service: aggregate perf. data 0.86 16605 103
service: refresh index statistics 0.03 324821 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-01-2021 10:28:39 to 05-01-2021 10:28:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3788
TEST_TIME 2 3723
TEST_TIME 3 5065
TEST_TIME 4 5114
TEST_TIME 5 5303
TEST_TIME 6 5219
TEST_TIME 7 5168
TEST_TIME 8 5310
TEST_TIME 9 5264
TEST_TIME 10 5735
TEST_TIME 11 5678
TEST_TIME 12 5777
TEST_TIME 13 5793
TEST_TIME 14 6222
TEST_TIME 15 6577
TEST_TIME 16 6959
TEST_TIME 17 7024
TEST_TIME 18 7457
TEST_TIME 19 7360
TEST_TIME 20 7504
TEST_TIME 21 7606
TEST_TIME 22 7776
TEST_TIME 23 8379
TEST_TIME 24 8697
TEST_TIME 25 8836
TEST_TIME 26 9430
TEST_TIME 27 9579
TEST_TIME 28 10505
TEST_TIME 29 10629
TEST_TIME 30 10428
TEST_TIME 31 10331
TEST_TIME 32 10039
TEST_TIME 33 9994
TEST_TIME 34 10005
TEST_TIME 35 9864
TEST_TIME 36 9774
TEST_TIME 37 9698
TEST_TIME 38 9562
TEST_TIME 39 9414
TEST_TIME 40 9462
TEST_TIME 41 9363
TEST_TIME 42 9230
TEST_TIME 43 9126
TEST_TIME 44 9091
TEST_TIME 45 8982
TEST_TIME 46 8884
TEST_TIME 47 8840
TEST_TIME 48 8864
TEST_TIME 49 8967
TEST_TIME 50 9079
TEST_TIME 51 9036
TEST_TIME 52 9132
TEST_TIME 53 9114
TEST_TIME 54 9075
TEST_TIME 55 9175
TEST_TIME 56 9158
TEST_TIME 57 9208
TEST_TIME 58 9163
TEST_TIME 59 9227
TEST_TIME 60 9245
TEST_TIME 61 9099
TEST_TIME 62 9020
TEST_TIME 63 8933
TEST_TIME 64 8901
TEST_TIME 65 8865
TEST_TIME 66 8843
TEST_TIME 67 8809
TEST_TIME 68 8778
TEST_TIME 69 8769
TEST_TIME 70 8883
TEST_TIME 71 8872
TEST_TIME 72 8909
TEST_TIME 73 8882
TEST_TIME 74 8889
TEST_TIME 75 8883
TEST_TIME 76 8926
TEST_TIME 77 8939
TEST_TIME 78 8921
TEST_TIME 79 8910
TEST_TIME 80 8800
TEST_TIME 81 8785
TEST_TIME 82 8748
TEST_TIME 83 8734
TEST_TIME 84 8739
TEST_TIME 85 8730
TEST_TIME 86 8731
TEST_TIME 87 8748
TEST_TIME 88 8726
TEST_TIME 89 8718
TEST_TIME 90 8738
TEST_TIME 91 8737
TEST_TIME 92 8820
TEST_TIME 93 8899
TEST_TIME 94 8884
TEST_TIME 95 8874
TEST_TIME 96 8794
TEST_TIME 97 8752
TEST_TIME 98 8776
TEST_TIME 99 8753
TEST_TIME 100 8734
TEST_TIME 101 8709
TEST_TIME 102 8673
TEST_TIME 103 8649
TEST_TIME 104 8706
TEST_TIME 105 8726
TEST_TIME 106 8713
TEST_TIME 107 8691
TEST_TIME 108 8671
TEST_TIME 109 8735
TEST_TIME 110 8761
TEST_TIME 111 8774
TEST_TIME 112 8788
TEST_TIME 113 8729
TEST_TIME 114 8692
TEST_TIME 115 8643
TEST_TIME 116 8597
TEST_TIME 117 8568
TEST_TIME 118 8552
TEST_TIME 119 8531
TEST_TIME 120 8559

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-01-2021 10:28:43 to 05-01-2021 10:28:46.

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 1435266 1435266 0 0.00 0 30027 7 0 0
doc_list_biud 1435266 1435266 0 0.00 0 58613 33 0 0
sp_add_invoice_to_stock 4832 4726 106 2.19 57 168237 18925 0 106
sp_cancel_adding_invoice 5033 5033 0 0.00 5 82876 942 0 0
sp_cancel_client_order 1713 1713 0 0.00 4 17600 340 0 0
sp_cancel_customer_reserve 504327 504327 0 0.00 1 58617 75 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 5731 107 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 2304 79 0 0
sp_cancel_supplier_invoice 5636 5625 11 0.19 3 50619 941 0 11
sp_cancel_supplier_order 384 384 0 0.00 8 1177004 122258 0 0
sp_cancel_write_off 9743 9743 0 0.00 1 7671 126 0 0
sp_client_order 19278 19278 0 0.00 4 37893 455 0 0
sp_customer_reserve 441760 441760 0 0.00 0 85912 182 0 0
sp_fill_shopping_cart 29596 29596 0 0.00 1 11249 46 0 0
sp_fill_shopping_cart_clo_res 441760 441760 0 0.00 0 20665 19 0 0
sp_get_clo_for_invoice 4726 4726 0 0.00 0 50117 438 0 0
sp_kill_cost_storno 15852 15852 0 0.00 0 30019 65 0 0
sp_kill_qstorno_ret_qs2qd 1024406 1024406 0 0.00 0 56658 40 0 0
sp_kill_qty_storno 541172 541172 0 0.00 0 58613 86 0 0
sp_lock_selected_doc 20016 20016 0 0.00 0 16127 5 0 0
sp_make_cost_storno 26482 26482 0 0.00 0 9037 51 0 0
sp_multiply_rows_for_pdistr 26482 26482 0 0.00 0 9770 34 0 0
sp_multiply_rows_for_qdistr 19278 19278 0 0.00 0 18394 243 0 0
sp_pay_from_customer 6592 6592 0 0.00 4 9046 109 0 0
sp_pay_to_supplier 5543 5543 0 0.00 4 9055 130 0 0
sp_payment_common 12135 12135 0 0.00 1 9010 24 0 0
sp_qd_handle_on_cancel_clo 1713 1713 0 0.00 0 17593 306 0 0
sp_qd_handle_on_invoice_upd_sts 9759 9759 0 0.00 0 50390 414 0 0
sp_qd_handle_on_reserve_upd_sts 9621 9621 0 0.00 0 25693 63 0 0
sp_reserve_write_off 9621 9621 0 0.00 6 25722 189 0 0
sp_supplier_invoice 5205 5205 0 0.00 6 112073 4070 0 0
sp_supplier_order 5132 5132 0 0.00 5 60763 1516 0 0
srv_aggregate_perf_data 103 103 0 0.00 2745 169841 16605 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1980 133191 48868 0 0
srv_find_qd_qs_mism 981623 981623 0 0.00 0 10894 6 0 0
srv_make_invnt_saldo 455 455 0 0.00 54 111682 3439 0 0
srv_make_money_saldo 701 701 0 0.00 0 17807 675 0 0
srv_recalc_idx_stat 4 4 0 0.00 99112 596986 324821 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4726 4726 0 0.00 0 19953 164 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 9465 89 0 0
t$perf-norm:sp_cancel_client_order 1713 1713 0 0.00 0 2698 14 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 1526 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 4510 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 322 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 428 428 0 0.00 0 2548 23 0 0
t$perf-norm:sp_cancel_supplier_order 384 384 0 0.00 0 22822 1393 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 3664 15 0 0
t$perf-norm:sp_client_order 19278 19278 0 0.00 0 5942 21 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 3610 6 0 0
t$perf-norm:sp_pay_from_customer 6592 6592 0 0.00 0 7885 11 0 0
t$perf-norm:sp_pay_to_supplier 5543 5543 0 0.00 0 5587 8 0 0
t$perf-norm:sp_reserve_write_off 9621 9621 0 0.00 0 5037 8 0 0
t$perf-norm:sp_supplier_invoice 5205 5205 0 0.00 0 5766 20 0 0
t$perf-norm:sp_supplier_order 5132 5132 0 0.00 0 4340 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 60 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 665 14 0 0
t$perf-norm:srv_make_invnt_saldo 455 455 0 0.00 0 1271 8 0 0
t$perf-norm:srv_make_money_saldo 701 701 0 0.00 0 365 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9662 9450 212 2.19 0 14064 20 0 212
v_all_customers 35080 35080 0 0.00 0 63 1 0 0
v_all_suppliers 28678 28678 0 0.00 0 17 1 0 0
v_all_wares 38556 38556 0 0.00 0 139 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 2737 12 0 0
v_cancel_client_order 3426 3426 0 0.00 0 3344 8 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 5720 18 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 2717 9 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 2252 10 0 0
v_cancel_supplier_invoice 878 856 22 2.50 0 1211 16 0 22
v_cancel_supplier_order 768 768 0 0.00 0 3267 10 0 0
v_cancel_write_off 2012 2012 0 0.00 0 7623 25 0 0
v_max_id_clo_ord 5132 5132 0 0.00 0 1446 2 0 0
v_max_id_clo_res 985 985 0 0.00 0 9492 26 0 0
v_max_id_ord_sup 5186 5186 0 0.00 0 3135 3 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 858 3 0 0
v_max_non_paid_realizn 3519 3519 0 0.00 0 6102 5 0 0
v_min_id_clo_ord 5132 5132 0 0.00 0 2511 6 0 0
v_min_id_clo_res 985 985 0 0.00 0 388 2 0 0
v_min_id_ord_sup 5186 5186 0 0.00 0 5408 10 0 0
v_min_non_paid_invoice 5543 5543 0 0.00 0 8566 16 0 0
v_min_non_paid_realizn 6592 6592 0 0.00 0 9004 16 0 0
v_our_firm 9622 9622 0 0.00 0 20 1 0 0
v_reserve_write_off 19242 19242 0 0.00 0 3564 6 0 0
x_lock_depdocs_on_canc_invoice 10230 10230 0 0.00 0 20736 131 0 0
x_lock_depdocs_on_canc_sup_ord 384 384 0 0.00 0 9653 348 0 0
x_make_qstorno_1000_1200 5132 5132 0 0.00 2 60741 1435 0 0
x_make_qstorno_1200_2000 5205 5205 0 0.00 3 112060 3888 0 0
x_make_qstorno_2100_3300 441760 441760 0 0.00 0 85911 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-01-2021 10:28:47 to 05-01-2021 10:28:51.

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:59 6 8192000000 8796637808 8807108608 3167696 0.038 1 1 2 0 3265392 61840 35856
07:58:00 19681 8192000000 9727335344 10022371328 802928992 8.926 100 86 171 1 831120624 7246688 20944944
07:59:23 16783 8192000000 9759915488 10062561280 834383632 9.243 100 94 188 1 869171376 12613344 22174400
08:00:43 22772 8192000000 9785318512 10102329344 858770384 9.488 100 84 168 1 896006576 17931984 19304208
08:02:13 14579 8192000000 9872917056 10203000832 916680896 10.063 100 85 168 1 982232560 47517808 18033856
08:03:29 8316 8192000000 9905565984 10251522048 956520784 10.455 100 78 156 2 1024760944 51693568 16546592
08:04:41 5915 8192000000 9954955312 10305085440 990650880 10.788 100 84 168 0 1071511504 64000800 16859824
08:05:50 1761 8192000000 10016195792 10376290304 1017453072 11.047 100 93 186 1 1124381824 88307968 18620784
08:06:52 2060 8192000000 10004198576 10366836736 1041929648 11.283 100 92 184 1 1113186704 52804112 18452944
08:07:55 33575 8192000000 9978322256 10377199616 1054916800 11.408 100 46 93 4 1125304176 60809600 9577776
08:09:34 3567 8192000000 9999212800 10388946944 1068378656 11.537 100 56 112 3 1118492496 38334688 11779152
08:10:38 5193 8192000000 9987276272 10384920576 1076547312 11.615 100 40 80 1 1103377312 17958320 8871680
08:11:45 47284 8192000000 10001174864 10396528640 1090264128 11.745 100 43 82 2 1126755696 27198672 9292896
08:13:54 6963 8192000000 10026831552 10427314176 1094655632 11.787 100 47 93 1 1134362448 29128512 10578304
08:15:02 2727 8192000000 10021871152 10413084672 1092354448 11.765 100 48 97 2 1126241536 23662496 10224592
08:16:06 6859 8192000000 10007013856 10410999808 1076561312 11.615 99 40 78 0 1108005600 22939376 8504912
08:17:13 29775 8192000000 9983648064 10393751552 1037291712 11.239 95 22 44 2 1063870144 22224560 4353872
08:18:44 4307 8192000000 9971952448 10350284800 1035962928 11.226 97 49 96 3 1069862560 23362144 10537488
08:19:49 8375 8192000000 9936895376 10317127680 990058480 10.782 96 43 88 3 1017309440 16816464 10434496
08:20:58 47473 8192000000 9926470096 10300559360 981940512 10.703 96 51 102 3 1016509440 22604224 11964704
08:22:46 8205 8192000000 9937983904 10323329024 1011945632 10.994 99 37 74 4 1039997936 19682240 8370064
08:23:58 7808 8192000000 9978819600 10368385024 1046378800 11.326 100 50 100 3 1074504800 16814192 11311808
08:25:12 7915 8192000000 9970933344 10370179072 1060572848 11.462 100 38 74 2 1081051296 11337392 9141056
08:26:21 71031 8192000000 9988451152 10382888960 1070603952 11.558 100 44 88 2 1098839952 18668624 9567376
08:28:37 15609 8192000000 10080258048 10474815488 1108193584 11.915 100 67 133 1 1181779248 60384672 13200992
08:30:01 11903 8192000000 10083426384 10481651712 1126030032 12.084 100 59 117 3 1195152656 57043600 12079024
08:31:19 15485 8192000000 10119630240 10523516928 1137034336 12.188 100 58 116 2 1229565200 80017184 12513680
08:32:38 13758 8192000000 10162113664 10575978496 1150149616 12.311 100 52 102 1 1276633056 114924528 11558912
08:34:05 17268 8192000000 10230986224 10643943424 1160132496 12.405 100 46 92 1 1324733616 154213408 10387712
08:35:29 8716 8192000000 10238035904 10652065792 1168638272 12.484 100 50 98 1 1349139936 168612096 11889568
08:36:41 7432 8192000000 10244035104 10671529984 1175036176 12.544 100 51 98 0 1355110336 169106096 10968064
08:37:53 6647 8192000000 10248292224 10664706048 1170242576 12.499 100 56 109 0 1357444080 173539472 13662032
08:39:06 5271 8192000000 10290100096 10700570624 1174094960 12.535 100 75 145 1 1391063184 200680368 16287856
08:40:19 11992 8192000000 10304460912 10741465088 1163574800 12.437 99 62 124 0 1409018704 231814048 13629856
08:41:37 3127 8192000000 10346808432 10752925696 1180024320 12.590 100 81 162 2 1447069920 248658128 18387472
08:42:44 4142 8192000000 10320901760 10747994112 1175778080 12.551 100 73 144 1 1420837024 228413824 16645120
08:43:52 2509 8192000000 10348302736 10770755584 1186349520 12.649 100 78 153 0 1440863120 237501808 17011792
08:44:57 8374 8192000000 10348113504 10758901760 1167551792 12.474 100 63 126 1 1449558336 267019392 14987152
08:46:14 2535 8192000000 10439827584 10846220288 1180380000 12.594 100 89 176 1 1533802912 332075120 21347792
08:47:22 1979 8192000000 10410869952 10819928064 1125105504 12.075 98 75 150 1 1495963040 353251200 17606336
08:48:27 2862 8192000000 10411739616 10814603264 1147216432 12.283 99 78 154 1 1509340208 343649696 18474080
08:49:33 11493 8192000000 10406280976 10868682752 1157784304 12.383 100 39 78 1 1529989008 362021712 10182992
08:50:52 10895 8192000000 10383795456 10823909376 1149244432 12.302 100 38 76 1 1488813680 329896608 9672640
08:52:11 14913 8192000000 10359249776 10845261824 1136105856 12.179 100 26 52 3 1460679232 317531696 7041680
08:54:13 79028 8192000000 10088686224 10570776576 1057950080 11.437 95 11 22 0 1175706880 115531760 2225040
08:56:36 61683 8192000000 10105036672 10540785664 1109534880 11.928 99 33 65 3 1198755024 82375264 6844880
08:58:38 59217 8192000000 10100857456 10551676928 1122377376 12.049 100 33 64 1 1202819328 74089344 6352608
09:00:48 41545 8192000000 10063652576 10519539712 1123500496 12.060 100 17 34 1 1170988064 44297472 3190096
09:02:39 98465 8192000000 10082933280 10536062976 1089723696 11.740 96 28 56 2 1154982784 60091888 5167200
09:05:21 38808 8192000000 10083770080 10519674880 1082292080 11.669 96 15 31 1 1162261664 76475264 3494320
09:07:05 33470 8192000000 10045530128 10505457664 1125224848 12.076 100 18 36 0 1165968304 35993344 4750112
09:08:43 117267 8192000000 10045407472 10516709376 1110566288 11.938 98 30 60 3 1180220032 63447040 6206704
09:11:49 95966 8192000000 10144914704 10612862976 1137575728 12.193 99 17 34 0 1218508864 77527360 3405776
09:14:42 63952 8192000000 10113557696 10583367680 1114777584 11.978 97 18 36 2 1217383936 98667232 3939120
09:16:56 52395 8192000000 10105951760 10548490240 1128631920 12.108 99 25 51 1 1196519328 62393328 5494080
09:19:14 65779 8192000000 10090382000 10543579136 1112845552 11.959 97 24 47 0 1152413488 34851088 4716848
09:21:20 92031 8192000000 10085937136 10515300352 1135214288 12.170 99 25 50 2 1204139472 63719216 5205968
09:23:54 58999 8192000000 10129304256 10567847936 1154599744 12.353 100 26 52 2 1224256096 64591472 5064880
09:26:07 133191 8192000000 10079110928 10533154816 1158750304 12.392 100 9 18 0 1194016064 33136784 2128976
09:29:28 31624 8192000000 10093083872 10563272704 1164322176 12.444 100 5 10 1 1192535232 27577040 636016
09:31:04 78455 8192000000 10088790080 10559598592 1098630512 11.825 94 17 35 1 1147747280 45679888 3436880
09:33:23 60465 8192000000 10126766080 10584301568 1157986896 12.384 100 14 28 0 1242181440 80910464 3284080
09:35:24 49898 8192000000 10158969120 10620542976 1129592944 12.118 98 29 58 1 1238569072 102407088 6569040
09:37:18 25308 8192000000 10073767072 10493730816 1087135648 11.715 96 56 111 2 1126799808 26901248 12762912
09:38:47 68330 8192000000 10083522720 10522185728 1107526704 11.909 98 33 65 2 1168950880 54390240 7033936
09:41:01 48370 8192000000 10078122768 10508349440 1074376608 11.594 96 35 69 0 1121455872 39732032 7347232
09:42:50 60144 8192000000 10050093008 10508849152 1088567632 11.729 97 4 8 0 1141578912 52395680 615600
09:44:51 77531 8192000000 10073057072 10534723584 1085776192 11.702 96 36 72 1 1135370288 42085616 7508480
09:48:28 55939 8192000000 10097739888 10549870592 1113566080 11.966 99 15 30 2 1183245936 66201328 3478528
09:50:58 56652 8192000000 10074972992 10538594304 1096239968 11.802 98 17 35 0 1158548096 58305200 4002928
09:53:04 32048 8192000000 10079154656 10538143744 1087790496 11.722 97 32 62 3 1134295968 39510800 6994672
09:54:47 55203 8192000000 10084898080 10532294656 1089102720 11.734 98 23 46 2 1138688400 44968976 4616704
09:56:46 96148 8192000000 10077512656 10576285696 1105274128 11.888 98 12 24 1 1179881824 72296240 2311456
09:59:32 121178 8192000000 10105055488 10569740288 1088760336 11.731 96 7 14 2 1129649440 39944128 944976
10:02:58 92027 8192000000 10079573856 10592612352 1119965792 12.027 99 16 32 0 1197181984 73795152 3421040
10:05:33 60514 8192000000 10047533456 10538401792 1101491024 11.852 98 2 4 1 1135459104 33803248 164832
10:07:34 68427 8192000000 10072465056 10501525504 1123280992 12.058 99 26 52 2 1153045776 24021152 5743632
10:09:47 39688 8192000000 10142469504 10582659072 1138528496 12.202 100 24 48 0 1216922000 72984400 5409104
10:12:24 85558 8192000000 10086524752 10548629504 1112006576 11.951 98 21 42 2 1157894608 41736288 4151744
10:14:50 67548 8192000000 10084655552 10555428864 1123336928 12.059 99 8 16 0 1189793744 64836640 1620176
10:16:58 74301 8192000000 10082365200 10516172800 1123316672 12.058 99 21 41 1 1163842144 35652640 4872832
10:19:14 115531 8192000000 10075959248 10548727808 1143857072 12.252 100 18 36 0 1185659552 38312672 3489808
10:22:14 44940 8192000000 10058568736 10514239488 1123028944 12.056 98 7 14 0 1157577376 33111152 1437280
10:24:04 83166 8192000000 10085758736 10537594880 1132560912 12.145 99 19 38 0 1169580048 33133200 3885936
10:26:27 40969 8192000000 9287877744 9650692096 395251520 4.602 35 5 10 0 466434096 69846448 1336128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-01-2021 10:28:53 to 05-01-2021 10:29:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 05-01-2021 10:29:09 to 05-01-2021 10:29:10.

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          223934
MON$OLDEST_ACTIVE               226099
MON$OLDEST_SNAPSHOT             226099
MON$NEXT_TRANSACTION            226099
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                       2074912
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             777
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2317 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2317 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.2317 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 ---
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Jan  5 10:27:24 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Jan  5 10:27:25 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

05.01.21 10:29:12. 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-01-05 10:29:14 No severe PSQL-related problems occured
05.01.21 10:29:15. End of report.