Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.02.2021 04: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 16415432 kB
MemAvailable 31092204 kB
Buffers 20 kB
Cached 15176372 kB
SwapCached 4092 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-02-16 04:26:57
DTS_END                         2021-02-16 04:26:57.0970
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47744
TRN_ID                          225029
ATT_ID                          655
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: 8966.61 26528 1075993
customer order: creation 157.24 478 18869
customer order: refuse 13.90 310 1668
order to supplier: creation 42.59 1368 5111
order to supplier: removal 3.45 121624 414
invoice (draft): creation 42.70 3481 5124
invoice (draft): removal 53.40 953 6408
invoice accept: apply 39.01 17751 4681
invoice accept: cancel 49.12 881 5894
customer reserve: creation 3552.41 174 426289
customer reserve: removal 4733.65 70 568038
realization accept: apply 77.03 168 9244
realization accept: cancel 85.65 128 10278
payment from customer: creation 54.10 97 6492
payment from customer: removal 5.11 71 613
payment to supplier: creation 43.98 123 5277
payment to supplier: removal 3.13 104 375
service: total inventory turnovers 3.48 3532 417
service: total monetary turnovers 5.83 638 699
service: aggregate perf. data 0.82 20804 98
service: refresh index statistics 0.03 357812 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-02-2021 04:28:00 to 16-02-2021 04:28:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3296
TEST_TIME 2 5371
TEST_TIME 3 5011
TEST_TIME 4 6142
TEST_TIME 5 6007
TEST_TIME 6 5957
TEST_TIME 7 5962
TEST_TIME 8 6431
TEST_TIME 9 6553
TEST_TIME 10 6640
TEST_TIME 11 6696
TEST_TIME 12 6860
TEST_TIME 13 6916
TEST_TIME 14 7370
TEST_TIME 15 8068
TEST_TIME 16 8178
TEST_TIME 17 8359
TEST_TIME 18 8838
TEST_TIME 19 9369
TEST_TIME 20 9253
TEST_TIME 21 9333
TEST_TIME 22 9734
TEST_TIME 23 9631
TEST_TIME 24 9694
TEST_TIME 25 9717
TEST_TIME 26 9990
TEST_TIME 27 10438
TEST_TIME 28 11731
TEST_TIME 29 11724
TEST_TIME 30 11603
TEST_TIME 31 11352
TEST_TIME 32 11106
TEST_TIME 33 10948
TEST_TIME 34 10725
TEST_TIME 35 10621
TEST_TIME 36 10450
TEST_TIME 37 10418
TEST_TIME 38 10284
TEST_TIME 39 10332
TEST_TIME 40 10284
TEST_TIME 41 10252
TEST_TIME 42 10074
TEST_TIME 43 9890
TEST_TIME 44 9820
TEST_TIME 45 10023
TEST_TIME 46 10206
TEST_TIME 47 10147
TEST_TIME 48 10066
TEST_TIME 49 10208
TEST_TIME 50 10175
TEST_TIME 51 10152
TEST_TIME 52 10163
TEST_TIME 53 10127
TEST_TIME 54 10124
TEST_TIME 55 10106
TEST_TIME 56 10118
TEST_TIME 57 10070
TEST_TIME 58 9899
TEST_TIME 59 9738
TEST_TIME 60 9762
TEST_TIME 61 9657
TEST_TIME 62 9559
TEST_TIME 63 9473
TEST_TIME 64 9401
TEST_TIME 65 9413
TEST_TIME 66 9390
TEST_TIME 67 9355
TEST_TIME 68 9392
TEST_TIME 69 9366
TEST_TIME 70 9334
TEST_TIME 71 9473
TEST_TIME 72 9483
TEST_TIME 73 9447
TEST_TIME 74 9544
TEST_TIME 75 9519
TEST_TIME 76 9524
TEST_TIME 77 9563
TEST_TIME 78 9483
TEST_TIME 79 9368
TEST_TIME 80 9364
TEST_TIME 81 9307
TEST_TIME 82 9280
TEST_TIME 83 9226
TEST_TIME 84 9170
TEST_TIME 85 9157
TEST_TIME 86 9129
TEST_TIME 87 9094
TEST_TIME 88 9100
TEST_TIME 89 9111
TEST_TIME 90 9142
TEST_TIME 91 9148
TEST_TIME 92 9196
TEST_TIME 93 9183
TEST_TIME 94 9307
TEST_TIME 95 9303
TEST_TIME 96 9280
TEST_TIME 97 9229
TEST_TIME 98 9163
TEST_TIME 99 9126
TEST_TIME 100 9081
TEST_TIME 101 9023
TEST_TIME 102 9010
TEST_TIME 103 8992
TEST_TIME 104 8995
TEST_TIME 105 8967
TEST_TIME 106 8937
TEST_TIME 107 8927
TEST_TIME 108 8995
TEST_TIME 109 8982
TEST_TIME 110 8991
TEST_TIME 111 9097
TEST_TIME 112 9054
TEST_TIME 113 9015
TEST_TIME 114 8954
TEST_TIME 115 8978
TEST_TIME 116 8981
TEST_TIME 117 8981
TEST_TIME 118 8965
TEST_TIME 119 8944
TEST_TIME 120 8967

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-02-2021 04:28:03 to 16-02-2021 04:28:07.

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 1470881 1470881 0 0.00 0 38666 6 0 0
doc_list_biud 1470880 1470880 0 0.00 0 74145 34 0 0
sp_add_invoice_to_stock 4793 4681 112 2.33 37 170175 17752 0 112
sp_cancel_adding_invoice 5894 5894 0 0.00 5 67334 881 0 0
sp_cancel_client_order 1668 1668 0 0.00 4 13775 311 0 0
sp_cancel_customer_reserve 568038 568038 0 0.00 1 74148 70 0 0
sp_cancel_pay_from_customer 613 613 0 0.00 3 4373 72 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 6461 104 0 0
sp_cancel_supplier_invoice 6415 6408 7 0.10 2 34069 954 0 7
sp_cancel_supplier_order 414 414 0 0.00 8 1137128 121624 0 0
sp_cancel_write_off 10278 10278 0 0.00 1 14993 129 0 0
sp_client_order 18869 18869 0 0.00 4 20650 479 0 0
sp_customer_reserve 426289 426289 0 0.00 0 93664 174 0 0
sp_fill_shopping_cart 29085 29085 0 0.00 1 17683 45 0 0
sp_fill_shopping_cart_clo_res 426286 426286 0 0.00 0 29227 20 0 0
sp_get_clo_for_invoice 4675 4675 0 0.00 0 49365 447 0 0
sp_kill_cost_storno 17160 17160 0 0.00 0 38635 60 0 0
sp_kill_qstorno_ret_qs2qd 1153176 1153176 0 0.00 0 74142 38 0 0
sp_kill_qty_storno 606615 606615 0 0.00 0 74145 82 0 0
sp_lock_selected_doc 19366 19366 0 0.00 0 8443 4 0 0
sp_make_cost_storno 25684 25684 0 0.00 0 16870 52 0 0
sp_multiply_rows_for_pdistr 25683 25683 0 0.00 0 8531 31 0 0
sp_multiply_rows_for_qdistr 18869 18869 0 0.00 0 20639 304 0 0
sp_pay_from_customer 6492 6492 0 0.00 4 14253 97 0 0
sp_pay_to_supplier 5277 5277 0 0.00 5 14248 124 0 0
sp_payment_common 11768 11768 0 0.00 1 8421 16 0 0
sp_qd_handle_on_cancel_clo 1668 1668 0 0.00 0 13770 284 0 0
sp_qd_handle_on_invoice_upd_sts 10565 10565 0 0.00 0 27729 410 0 0
sp_qd_handle_on_reserve_upd_sts 9244 9244 0 0.00 0 12724 49 0 0
sp_reserve_write_off 9244 9244 0 0.00 7 13695 169 0 0
sp_supplier_invoice 5124 5124 0 0.00 7 112166 3482 0 0
sp_supplier_order 5111 5111 0 0.00 6 90539 1368 0 0
srv_aggregate_perf_data 98 98 0 0.00 2604 399889 20805 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1025 145689 53168 0 0
srv_find_qd_qs_mism 1032379 1032379 0 0.00 0 15588 5 0 0
srv_make_invnt_saldo 417 417 0 0.00 41 95948 3533 0 0
srv_make_money_saldo 699 699 0 0.00 1 16572 638 0 0
srv_recalc_idx_stat 4 4 0 0.00 112003 511085 357812 0 0
t$perf-abend:sp_add_invoice_to_stock 112 112 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4681 4681 0 0.00 0 14846 148 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 4928 86 0 0
t$perf-norm:sp_cancel_client_order 1668 1668 0 0.00 0 19801 23 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 827 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 613 613 0 0.00 0 396 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 1569 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1005 15 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 21972 1465 0 0
t$perf-norm:sp_cancel_write_off 924 924 0 0.00 0 800 7 0 0
t$perf-norm:sp_client_order 18869 18869 0 0.00 0 19802 18 0 0
t$perf-norm:sp_customer_reserve 926 926 0 0.00 0 2212 8 0 0
t$perf-norm:sp_pay_from_customer 6492 6492 0 0.00 0 11273 9 0 0
t$perf-norm:sp_pay_to_supplier 5277 5277 0 0.00 0 4695 8 0 0
t$perf-norm:sp_reserve_write_off 9244 9244 0 0.00 0 13201 15 0 0
t$perf-norm:sp_supplier_invoice 5124 5124 0 0.00 0 3524 16 0 0
t$perf-norm:sp_supplier_order 5111 5111 0 0.00 0 15221 19 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 182 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 469 8 0 0
t$perf-norm:srv_make_invnt_saldo 417 417 0 0.00 0 2168 10 0 0
t$perf-norm:srv_make_money_saldo 699 699 0 0.00 0 1365 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 9562 9338 224 2.34 0 11824 15 0 224
v_all_customers 34554 34554 0 0.00 0 26 1 0 0
v_all_suppliers 27858 27858 0 0.00 0 251 1 0 0
v_all_wares 37738 37738 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 613 7 0 0
v_cancel_client_order 3336 3336 0 0.00 0 3641 6 0 0
v_cancel_customer_prepayment 1226 1226 0 0.00 0 2583 6 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 4378 10 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 3846 12 0 0
v_cancel_supplier_invoice 786 772 14 1.78 0 10344 36 0 14
v_cancel_supplier_order 828 828 0 0.00 0 484 4 0 0
v_cancel_write_off 1848 1848 0 0.00 0 5513 19 0 0
v_max_id_clo_ord 5111 5111 0 0.00 0 1216 2 0 0
v_max_id_clo_res 926 926 0 0.00 0 5765 34 0 0
v_max_id_ord_sup 5105 5105 0 0.00 0 4202 4 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 1162 3 0 0
v_max_non_paid_realizn 3404 3404 0 0.00 0 1848 3 0 0
v_min_id_clo_ord 5111 5111 0 0.00 0 3080 5 0 0
v_min_id_clo_res 926 926 0 0.00 0 1360 5 0 0
v_min_id_ord_sup 5105 5105 0 0.00 0 7551 8 0 0
v_min_non_paid_invoice 5276 5276 0 0.00 0 3567 9 0 0
v_min_non_paid_realizn 6492 6492 0 0.00 0 8418 11 0 0
v_our_firm 9360 9360 0 0.00 0 15 1 0 0
v_reserve_write_off 18488 18488 0 0.00 0 6095 7 0 0
x_lock_depdocs_on_canc_invoice 11916 11916 0 0.00 0 28154 125 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 27575 418 0 0
x_make_qstorno_1000_1200 5111 5111 0 0.00 1 90523 1289 0 0
x_make_qstorno_1200_2000 5124 5124 0 0.00 3 112155 3313 0 0
x_make_qstorno_2100_3300 426289 426289 0 0.00 0 93663 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-02-2021 04:28:08 to 16-02-2021 04:28:12.

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
01:57:00 1 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 35843 8192000000 9725892640 10022158336 798324560 8.879 100 84 168 0 826054144 7286160 20443424
01:59:47 27827 8192000000 9777316544 10086182912 850825072 9.408 100 89 178 0 884199584 12281232 21093280
02:01:19 21410 8192000000 9807949184 10130075648 881088272 9.711 100 84 168 1 921220704 21161744 18970688
02:02:44 14005 8192000000 9883479904 10218790912 930571872 10.200 100 84 168 2 985108848 36243856 18293120
02:04:06 7362 8192000000 9946192368 10292899840 971590896 10.602 100 78 155 1 1057145808 69590960 15963952
02:05:21 9980 8192000000 9974488160 10336882688 1007238848 10.949 100 73 146 2 1088101168 66153520 14708800
02:06:33 2245 8192000000 10015918976 10378727424 1044485296 11.308 100 83 167 1 1117386896 56849168 16052432
02:07:38 2164 8192000000 10029316288 10399707136 1059500064 11.452 100 83 166 0 1146092448 70121840 16470544
02:08:42 1609 8192000000 10070483808 10442661888 1070342720 11.555 100 91 183 1 1180812400 91922608 18547072
02:09:44 3525 8192000000 10021359088 10416099328 1078249728 11.631 100 49 98 0 1146070160 57257472 10562960
02:10:51 10415 8192000000 9986560832 10380177408 1081768224 11.664 100 28 56 0 1110633024 22823616 6041184
02:12:03 6622 8192000000 9988304480 10392178688 1089088416 11.734 100 33 67 1 1107414672 11314176 7012080
02:13:12 34050 8192000000 10007468768 10423844864 1095061696 11.791 100 38 75 0 1121938256 18866352 8010208
02:14:47 4299 8192000000 10034916944 10445168640 1102295904 11.859 100 43 86 0 1148525600 36821232 9408464
02:15:53 7872 8192000000 10043797488 10439344128 1104066144 11.876 100 55 110 4 1152451392 36318800 12066448
02:17:02 6106 8192000000 9989834480 10382688256 1074464064 11.595 99 39 78 0 1099945424 16829504 8651856
02:18:14 7650 8192000000 10016682960 10396622848 1071230144 11.564 99 81 164 2 1104414528 13888176 19296208
02:19:21 3967 8192000000 9969692080 10351980544 1027703840 11.146 99 50 99 1 1064446320 25155760 11586720
02:20:26 5277 8192000000 9950367088 10327822336 1003835488 10.916 98 43 87 1 1030786864 17941728 9009648
02:21:32 52014 8192000000 9917582064 10296696832 999083760 10.870 98 39 76 1 1025704336 17841136 8779440
02:23:30 6102 8192000000 9943427792 10336600064 1031800928 11.186 100 40 80 2 1071226464 29743456 9682080
02:24:37 6151 8192000000 9969853488 10361991168 1054644496 11.405 100 40 80 2 1082052496 18623120 8784880
02:25:44 8302 8192000000 9973017504 10366033920 1059569600 11.452 100 40 74 4 1089066880 21304464 8192816
02:26:55 35434 8192000000 9997951744 10391515136 1068103600 11.534 100 56 112 0 1097801168 17963248 11734320
02:28:35 11491 8192000000 10087406672 10481618944 1101465184 11.852 100 69 138 0 1194347968 78843792 14038992
02:29:54 17222 8192000000 10113574384 10517127168 1119716000 12.024 100 60 118 0 1220905072 88667280 12521792
02:31:14 8058 8192000000 10128002272 10534420480 1136255232 12.180 100 48 92 0 1242349744 95084496 11010016
02:32:27 9694 8192000000 10180494464 10593054720 1143953472 12.253 100 56 112 2 1294525696 138325808 12246416
02:33:41 6684 8192000000 10207402480 10617389056 1150665088 12.316 100 55 110 2 1310492784 147889392 11938304
02:34:54 1024 8192000000 10251457696 10657558528 1166724256 12.466 100 71 140 0 1355648144 172717552 16206336
02:35:59 7244 8192000000 10286361184 10694107136 1173467408 12.529 100 70 139 0 1389659632 201540896 14651328
02:37:08 23018 8192000000 10354922368 10760896512 1164448992 12.445 99 69 138 1 1440070400 260440944 15180464
02:38:36 4967 8192000000 10340067264 10757054464 1165478224 12.455 100 50 99 1 1444932192 267602480 11851488
02:39:44 9077 8192000000 10365374832 10787168256 1147431888 12.285 99 62 124 0 1469438240 308027200 13979152
02:40:57 3260 8192000000 10338446624 10762072064 1131733840 12.138 98 80 159 0 1430085968 280407456 17944672
02:42:06 3915 8192000000 10298009776 10694733824 1125058528 12.075 99 77 153 0 1397176640 254278896 17839216
02:43:14 1682 8192000000 10355684784 10767044608 1139070144 12.207 100 71 139 2 1447788384 292060480 16657760
02:44:21 11585 8192000000 10304763728 10744791040 1114876816 11.979 98 38 72 4 1393336272 269143104 9316352
02:45:35 17412 8192000000 10286722512 10738688000 1127111312 12.094 99 42 83 2 1383965232 246584944 10268976
02:46:59 1974 8192000000 10305436304 10705821696 1133472256 12.154 100 70 140 1 1393731264 244085680 16173328
02:48:05 14016 8192000000 10315846336 10738204672 1146094544 12.273 100 42 80 2 1421826192 265321504 10410144
02:49:25 25757 8192000000 10355312496 10782535680 1140164416 12.217 99 30 60 0 1439174880 291186448 7824016
02:51:03 11186 8192000000 10414139376 10838024192 1162205360 12.424 100 47 93 1 1504651840 330735344 11711136
02:52:17 32228 8192000000 10464067200 10944368640 1155585776 12.362 100 24 48 0 1562619728 399992048 7041904
02:53:52 17103 8192000000 10318816816 10825867264 1130097360 12.122 100 20 40 2 1475210400 339558560 5554480
02:55:13 47966 8192000000 10070230176 10521235456 1070201312 11.554 94 14 27 0 1134237456 60962960 3073184
02:57:02 145689 8192000000 10062970848 10530881536 1087974704 11.723 98 27 52 1 1153381984 59669600 5737680
03:00:42 60685 8192000000 10089154480 10568040448 1121177328 12.038 100 11 22 1 1213404944 89484784 2742832
03:02:45 66261 8192000000 10146321904 10604089344 1124695008 12.071 99 33 66 0 1244784128 112907712 7181408
03:04:59 60949 8192000000 10158350512 10660749312 1122453152 12.050 99 27 54 3 1263089024 135185712 5450160
03:07:15 129284 8192000000 10154761568 10603917312 1121028880 12.037 99 25 50 1 1204218384 76971280 6218224
03:10:25 96078 8192000000 10173697168 10697134080 1132844304 12.148 99 14 27 1 1314696112 178301088 3550720
03:13:47 28790 8192000000 10113839904 10551545856 1147685408 12.288 100 38 76 2 1201096304 44959520 8451376
03:15:17 115781 8192000000 10090540336 10536910848 1110025248 11.933 97 2 4 0 1147110336 36836176 248912
03:18:16 64224 8192000000 10084353632 10538721280 1136175792 12.180 99 20 40 1 1171222240 30802704 4243744
03:20:24 80367 8192000000 10103878000 10547949568 1153302192 12.340 100 16 31 1 1198831168 42674160 2854816
03:22:48 83388 8192000000 10130348752 10650107904 1134638976 12.165 98 3 6 0 1201237104 66088096 510032
03:25:53 98373 8192000000 10076544096 10535571456 1150510848 12.314 100 13 24 1 1228837200 76040560 2285792
03:28:32 34065 8192000000 10072623408 10546139136 1155864960 12.365 100 7 14 0 1182154656 24806048 1483648
03:30:08 44183 8192000000 10106349936 10555453440 1120565808 12.032 97 16 30 1 1166373360 41925568 3881984
03:32:18 75168 8192000000 10099569120 10561409024 1090260112 11.745 95 16 32 0 1125370992 32312480 2798400
03:34:52 86977 8192000000 10113258640 10561163264 1089162192 11.735 95 20 40 1 1156955424 63491744 4301488
03:37:19 95022 8192000000 10112268784 10590425088 1118591216 12.014 98 18 36 1 1167932960 45360208 3981536
03:39:59 99280 8192000000 10052929424 10500370432 1068953440 11.542 94 25 49 1 1159694416 85473968 5267008
03:42:38 99956 8192000000 10095241424 10560413696 1101851424 11.855 97 15 30 0 1170579024 65700656 3026944
03:45:20 123719 8192000000 10102325504 10595639296 1092754704 11.769 97 28 56 3 1156602624 57946880 5901040
03:48:49 21302 8192000000 10043761360 10546028544 1115934336 11.989 100 3 7 0 1152317248 35801072 581840
03:50:11 79283 8192000000 10084340048 10537279488 1095939088 11.799 97 22 44 2 1137292448 36092000 5261360
03:52:34 50208 8192000000 10104439696 10542747648 1091838928 11.760 97 25 50 0 1172718288 75384960 5494400
03:54:25 45857 8192000000 10107148624 10541113344 1109782672 11.930 98 34 68 1 1184782928 67341840 7658416
03:56:30 82500 8192000000 10112237664 10589679616 1124733728 12.072 99 14 28 1 1223782640 96462400 2586512
03:59:00 50256 8192000000 10148433552 10612973568 1105834304 11.893 97 16 32 0 1185622128 76586800 3201024
04:00:51 84381 8192000000 10084346368 10521542656 1140509872 12.220 100 5 10 0 1183001872 41571632 920368
04:03:17 105465 8192000000 10068476416 10527481856 1107803904 11.912 97 28 56 2 1179634960 65847472 5983584
04:06:41 41701 8192000000 10060798656 10525450240 1110965200 11.942 98 9 18 0 1152354432 39428064 1961168
04:08:44 68557 8192000000 10092480992 10564579328 1140764352 12.223 100 23 46 0 1190906240 45134752 5007136
04:10:56 44070 8192000000 10092535072 10543247360 1119621136 12.023 97 16 32 1 1161456096 38111616 3723344
04:12:40 68591 8192000000 10155686992 10614726656 1139524944 12.211 99 22 44 0 1237432240 93176464 4730832
04:14:52 61093 8192000000 10129192256 10579165184 1157001360 12.375 100 29 58 0 1237817392 74253392 6562640
04:16:58 86905 8192000000 10093496384 10540261376 1126129776 12.085 97 22 42 1 1180668032 50222784 4315472
04:19:28 144895 8192000000 10132366272 10640871424 1155311376 12.359 100 20 40 0 1263599568 104100176 4188016
04:22:53 70763 8192000000 10097898064 10593140736 1131720352 12.138 98 7 14 0 1164885552 31411312 1753888
04:25:34 73976 8192000000 10082187872 10572808192 1135808032 12.176 99 30 61 2 1194797376 53028480 5960864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 16-02-2021 04:28:14 to 16-02-2021 04:28:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 112 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-02-2021 04:28:29 to 16-02-2021 04:28:31.

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          225369
MON$OLDEST_ACTIVE               225370
MON$OLDEST_SNAPSHOT             225370
MON$NEXT_TRANSACTION            225370
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                       2096704
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2365 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2365 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.2365 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 ---
:509: 
:510: mscs-test-161.budzdorov.ru    Tue Feb 16 04:27:09 2021
:511:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:512: 
--- end of diff output ---

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

16.02.21 04:28:32. 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-02-16 04:28:34 No severe PSQL-related problems occured
16.02.21 04:28:36. End of report.