Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.01.2021 07: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 16865480 kB
MemAvailable 31326920 kB
Buffers 0 kB
Cached 14752440 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-07 07:26:54
DTS_END                         2021-01-07 07:26:54.1100
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/57038
TRN_ID                          226939
ATT_ID                          602
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: 8563.87 21347 1027664
customer order: creation 164.43 398 19732
customer order: refuse 14.99 380 1799
order to supplier: creation 43.74 1454 5249
order to supplier: removal 3.46 111991 415
invoice (draft): creation 43.37 3525 5204
invoice (draft): removal 49.94 964 5993
invoice accept: apply 38.75 18881 4650
invoice accept: cancel 44.39 826 5327
customer reserve: creation 3579.18 185 429501
customer reserve: removal 4303.92 70 516470
realization accept: apply 79.33 198 9520
realization accept: cancel 77.02 144 9242
payment from customer: creation 56.78 104 6813
payment from customer: removal 5.13 89 615
payment to supplier: creation 46.36 141 5563
payment to supplier: removal 3.14 65 377
service: total inventory turnovers 3.63 3399 436
service: total monetary turnovers 5.46 576 655
service: aggregate perf. data 0.83 16023 99
service: refresh index statistics 0.03 267522 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-01-2021 07:28:28 to 07-01-2021 07:28:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1180
TEST_TIME 2 2489
TEST_TIME 3 3027
TEST_TIME 4 3016
TEST_TIME 5 3161
TEST_TIME 6 3452
TEST_TIME 7 3683
TEST_TIME 8 4046
TEST_TIME 9 4076
TEST_TIME 10 4878
TEST_TIME 11 4845
TEST_TIME 12 4909
TEST_TIME 13 5490
TEST_TIME 14 6017
TEST_TIME 15 6131
TEST_TIME 16 6261
TEST_TIME 17 6767
TEST_TIME 18 6941
TEST_TIME 19 7382
TEST_TIME 20 7932
TEST_TIME 21 7948
TEST_TIME 22 8246
TEST_TIME 23 8539
TEST_TIME 24 8541
TEST_TIME 25 8989
TEST_TIME 26 9262
TEST_TIME 27 10144
TEST_TIME 28 10385
TEST_TIME 29 10210
TEST_TIME 30 9994
TEST_TIME 31 9907
TEST_TIME 32 10296
TEST_TIME 33 10183
TEST_TIME 34 9947
TEST_TIME 35 9695
TEST_TIME 36 9635
TEST_TIME 37 9552
TEST_TIME 38 9525
TEST_TIME 39 9459
TEST_TIME 40 9458
TEST_TIME 41 9406
TEST_TIME 42 9288
TEST_TIME 43 9209
TEST_TIME 44 9368
TEST_TIME 45 9231
TEST_TIME 46 9285
TEST_TIME 47 9298
TEST_TIME 48 9201
TEST_TIME 49 9212
TEST_TIME 50 9290
TEST_TIME 51 9293
TEST_TIME 52 9490
TEST_TIME 53 9410
TEST_TIME 54 9433
TEST_TIME 55 9386
TEST_TIME 56 9389
TEST_TIME 57 9382
TEST_TIME 58 9537
TEST_TIME 59 9399
TEST_TIME 60 9303
TEST_TIME 61 9241
TEST_TIME 62 9173
TEST_TIME 63 9110
TEST_TIME 64 9083
TEST_TIME 65 9038
TEST_TIME 66 8975
TEST_TIME 67 8927
TEST_TIME 68 8919
TEST_TIME 69 8984
TEST_TIME 70 9169
TEST_TIME 71 9126
TEST_TIME 72 9152
TEST_TIME 73 9154
TEST_TIME 74 9117
TEST_TIME 75 9166
TEST_TIME 76 9120
TEST_TIME 77 9195
TEST_TIME 78 9137
TEST_TIME 79 9035
TEST_TIME 80 8964
TEST_TIME 81 8908
TEST_TIME 82 8871
TEST_TIME 83 8860
TEST_TIME 84 8872
TEST_TIME 85 8900
TEST_TIME 86 8867
TEST_TIME 87 8863
TEST_TIME 88 8838
TEST_TIME 89 8824
TEST_TIME 90 8824
TEST_TIME 91 8874
TEST_TIME 92 8871
TEST_TIME 93 8880
TEST_TIME 94 8954
TEST_TIME 95 8983
TEST_TIME 96 8984
TEST_TIME 97 8971
TEST_TIME 98 8912
TEST_TIME 99 8863
TEST_TIME 100 8814
TEST_TIME 101 8789
TEST_TIME 102 8748
TEST_TIME 103 8739
TEST_TIME 104 8736
TEST_TIME 105 8733
TEST_TIME 106 8707
TEST_TIME 107 8715
TEST_TIME 108 8696
TEST_TIME 109 8708
TEST_TIME 110 8718
TEST_TIME 111 8743
TEST_TIME 112 8765
TEST_TIME 113 8790
TEST_TIME 114 8776
TEST_TIME 115 8712
TEST_TIME 116 8681
TEST_TIME 117 8637
TEST_TIME 118 8610
TEST_TIME 119 8609
TEST_TIME 120 8565

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-01-2021 07:28:31 to 07-01-2021 07:28:35.

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 1425503 1425503 0 0.00 0 25165 7 0 0
doc_list_biud 1425502 1425502 0 0.00 0 58937 33 0 0
sp_add_invoice_to_stock 4787 4650 137 2.86 26 230169 18881 0 137
sp_cancel_adding_invoice 5327 5327 0 0.00 3 76299 827 0 0
sp_cancel_client_order 1799 1799 0 0.00 4 13694 381 0 0
sp_cancel_customer_reserve 516470 516470 0 0.00 1 58940 71 0 0
sp_cancel_pay_from_customer 615 615 0 0.00 3 10345 89 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 1727 66 0 0
sp_cancel_supplier_invoice 6008 5993 15 0.24 2 18451 964 0 15
sp_cancel_supplier_order 415 415 0 0.00 6 1138281 111992 0 0
sp_cancel_write_off 9242 9242 0 0.00 1 33314 145 0 0
sp_client_order 19732 19732 0 0.00 4 26209 399 0 0
sp_customer_reserve 429501 429501 0 0.00 0 95507 186 0 0
sp_fill_shopping_cart 30183 30183 0 0.00 1 17877 44 0 0
sp_fill_shopping_cart_clo_res 429500 429500 0 0.00 0 22177 19 0 0
sp_get_clo_for_invoice 4649 4649 0 0.00 0 61401 446 0 0
sp_kill_cost_storno 15561 15561 0 0.00 0 12416 64 0 0
sp_kill_qstorno_ret_qs2qd 1048590 1048590 0 0.00 0 58933 39 0 0
sp_kill_qty_storno 553414 553414 0 0.00 0 58936 83 0 0
sp_lock_selected_doc 19758 19758 0 0.00 0 10857 7 0 0
sp_make_cost_storno 26545 26545 0 0.00 0 18422 59 0 0
sp_multiply_rows_for_pdistr 26544 26544 0 0.00 0 12407 34 0 0
sp_multiply_rows_for_qdistr 19732 19732 0 0.00 0 17742 219 0 0
sp_pay_from_customer 6813 6813 0 0.00 4 12388 104 0 0
sp_pay_to_supplier 5563 5563 0 0.00 5 19442 141 0 0
sp_payment_common 12376 12376 0 0.00 1 7932 22 0 0
sp_qd_handle_on_cancel_clo 1799 1799 0 0.00 0 13689 351 0 0
sp_qd_handle_on_invoice_upd_sts 9975 9975 0 0.00 0 27042 384 0 0
sp_qd_handle_on_reserve_upd_sts 9520 9520 0 0.00 0 22889 61 0 0
sp_reserve_write_off 9520 9520 0 0.00 7 22972 198 0 0
sp_supplier_invoice 5204 5204 0 0.00 6 104328 3526 0 0
sp_supplier_order 5249 5249 0 0.00 6 27843 1454 0 0
srv_aggregate_perf_data 99 99 0 0.00 2365 183795 16024 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1037 172392 46615 0 0
srv_find_qd_qs_mism 982775 982775 0 0.00 0 25164 6 0 0
srv_make_invnt_saldo 436 436 0 0.00 36 94044 3400 0 0
srv_make_money_saldo 655 655 0 0.00 1 10585 577 0 0
srv_recalc_idx_stat 4 4 0 0.00 115963 357992 267523 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4650 4650 0 0.00 0 24268 175 0 0
t$perf-norm:sp_cancel_adding_invoice 344 344 0 0.00 0 2063 83 0 0
t$perf-norm:sp_cancel_client_order 1799 1799 0 0.00 0 3480 11 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 673 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 615 615 0 0.00 0 1923 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 927 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 2817 21 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 20371 1541 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 1655 11 0 0
t$perf-norm:sp_client_order 19732 19732 0 0.00 0 14022 19 0 0
t$perf-norm:sp_customer_reserve 929 929 0 0.00 0 2159 11 0 0
t$perf-norm:sp_pay_from_customer 6813 6813 0 0.00 0 18467 13 0 0
t$perf-norm:sp_pay_to_supplier 5563 5563 0 0.00 0 4442 10 0 0
t$perf-norm:sp_reserve_write_off 9520 9520 0 0.00 0 6577 11 0 0
t$perf-norm:sp_supplier_invoice 5204 5204 0 0.00 0 8300 24 0 0
t$perf-norm:sp_supplier_order 5249 5249 0 0.00 0 9619 20 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 124 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2298 49 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 8300 27 0 0
t$perf-norm:srv_make_money_saldo 655 655 0 0.00 0 845 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9570 9296 274 2.86 0 6879 20 0 274
v_all_customers 36286 36286 0 0.00 0 19 1 0 0
v_all_suppliers 29202 29202 0 0.00 0 15 1 0 0
v_all_wares 39464 39464 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 688 688 0 0.00 0 4627 12 0 0
v_cancel_client_order 3598 3598 0 0.00 0 4958 6 0 0
v_cancel_customer_prepayment 1230 1230 0 0.00 0 2313 11 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 2526 6 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 1489 7 0 0
v_cancel_supplier_invoice 818 788 30 3.66 0 2495 18 0 30
v_cancel_supplier_order 830 830 0 0.00 0 2015 7 0 0
v_cancel_write_off 1996 1996 0 0.00 0 5612 19 0 0
v_max_id_clo_ord 5249 5249 0 0.00 0 523 1 0 0
v_max_id_clo_res 929 929 0 0.00 0 5944 23 0 0
v_max_id_ord_sup 5202 5202 0 0.00 0 8060 6 0 0
v_max_non_paid_invoice 1413 1413 0 0.00 0 3638 5 0 0
v_max_non_paid_realizn 3489 3489 0 0.00 0 1425 4 0 0
v_min_id_clo_ord 5249 5249 0 0.00 0 11745 10 0 0
v_min_id_clo_res 929 929 0 0.00 0 4573 11 0 0
v_min_id_ord_sup 5202 5202 0 0.00 0 4178 7 0 0
v_min_non_paid_invoice 5563 5563 0 0.00 0 7133 15 0 0
v_min_non_paid_realizn 6813 6813 0 0.00 0 6724 14 0 0
v_our_firm 9826 9826 0 0.00 0 13 1 0 0
v_reserve_write_off 19038 19038 0 0.00 0 7095 9 0 0
x_lock_depdocs_on_canc_invoice 10926 10926 0 0.00 0 13950 105 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 12729 327 0 0
x_make_qstorno_1000_1200 5249 5249 0 0.00 2 27822 1371 0 0
x_make_qstorno_1200_2000 5204 5204 0 0.00 3 97231 3355 0 0
x_make_qstorno_2100_3300 429501 429501 0 0.00 0 95506 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-01-2021 07:28:36 to 07-01-2021 07:28:40.

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
04:56:57 15 8192000000 8798406816 8809533440 4445584 0.054 2 1 2 0 4545056 63616 35856
04:57:59 24075 8192000000 9708231456 10002079744 791969328 8.815 100 90 180 0 821639088 7643360 22026400
04:59:27 18476 8192000000 9751262304 10054729728 830563168 9.205 100 90 179 0 863671696 11998816 21109712
05:00:53 19714 8192000000 9797885616 10109513728 857229136 9.472 100 91 181 2 901945760 23993136 20723488
05:02:19 17707 8192000000 9862563584 10188341248 902394736 9.922 100 78 156 2 969157136 49430960 17331440
05:03:41 8933 8192000000 9900463072 10240135168 941431680 10.307 100 72 144 3 1008040208 52118128 14490400
05:04:53 8938 8192000000 9965272576 10315980800 989128656 10.773 100 85 170 0 1084410464 77539104 17742704
05:06:04 5262 8192000000 9982420560 10339831808 1012055088 10.995 100 84 165 1 1096878352 67997296 16825968
05:07:12 2891 8192000000 10018537712 10383069184 1037065888 11.236 100 87 173 2 1132496624 78042784 17387952
05:08:17 1781 8192000000 10030375024 10399981568 1060184624 11.458 100 85 166 3 1142856688 65291024 17381040
05:09:21 1403 8192000000 10036930752 10409906176 1067219088 11.526 100 80 158 2 1145406576 61295536 16891952
05:10:23 4832 8192000000 9977266208 10364801024 1075497488 11.605 100 45 90 4 1110882720 25675760 9709472
05:11:29 4398 8192000000 10007461568 10402881536 1082562976 11.672 100 39 75 3 1125204512 34383568 8257968
05:12:37 9265 8192000000 10000676368 10395942912 1090074608 11.743 100 28 56 1 1120213808 24559872 5579328
05:13:47 52710 8192000000 9998820208 10410246144 1089717168 11.740 100 29 58 0 1110126160 13955376 6453616
05:15:40 3884 8192000000 10017648176 10421043200 1101729008 11.854 100 53 103 4 1132098128 18396528 11972592
05:16:46 9945 8192000000 10019969632 10428547072 1094060880 11.781 100 36 72 2 1124995504 23633184 7301440
05:17:57 8582 8192000000 10000837056 10394292224 1063225408 11.487 98 24 48 0 1087057936 18616592 5215936
05:19:08 39074 8192000000 9981672192 10386792448 1047112528 11.333 97 43 87 3 1080109408 24469120 8527760
05:20:48 4970 8192000000 9944465520 10334859264 1021935744 11.091 98 41 77 1 1055561152 24936848 8688560
05:21:58 9210 8192000000 9919395392 10308288512 993088656 10.811 98 37 74 4 1021992160 20949408 7954096
05:23:16 6756 8192000000 9939955024 10328825856 1016009520 11.033 99 31 64 1 1043288512 20211488 7067504
05:24:25 29712 8192000000 9955267184 10354888704 1027727472 11.147 99 22 44 1 1061892992 29359440 4806080
05:26:00 5787 8192000000 9960288384 10357854208 1057806096 11.435 100 46 90 2 1084352432 17299248 9247088
05:27:12 18976 8192000000 10001046992 10391789568 1081410672 11.661 100 44 86 6 1105252368 14722656 9119040
05:28:42 17855 8192000000 10072023264 10470719488 1107503568 11.909 100 49 96 1 1175270496 57279408 10487520
05:30:07 41765 8192000000 10081581856 10486972416 1119745552 12.025 100 38 74 0 1197237792 68995072 8497168
05:32:05 9737 8192000000 10159295920 10558042112 1137500272 12.192 100 62 122 3 1262203120 111557408 13145440
05:33:19 15918 8192000000 10209756688 10609008640 1139716032 12.213 100 61 122 2 1313753248 160729232 13307984
05:34:42 4640 8192000000 10220535024 10626777088 1151545264 12.324 100 52 103 1 1323912400 161584656 10782480
05:35:54 18286 8192000000 10279083632 10703826944 1156580496 12.371 99 50 100 1 1370779472 202535408 11663568
05:37:20 9425 8192000000 10289493312 10696986624 1172071488 12.516 100 55 109 0 1390763392 205899456 12792448
05:38:34 3269 8192000000 10329668016 10736660480 1177608928 12.568 100 78 155 1 1436218480 241005120 17604432
05:39:41 3444 8192000000 10373283584 10789965824 1181629760 12.605 100 75 150 1 1475112688 275230064 18252864
05:40:50 2656 8192000000 10366026464 10782109696 1179350064 12.584 100 74 147 4 1471116784 275267200 16499520
05:42:00 3137 8192000000 10411746960 10820161536 1178873456 12.580 99 77 154 5 1505298944 308752112 17673376
05:43:17 1031 8192000000 10450202256 10867671040 1186923888 12.655 100 83 167 1 1547081264 340214288 19943088
05:44:23 4574 8192000000 10414884144 10828947456 1180365648 12.594 100 61 121 1 1511446896 317089408 13991840
05:45:36 18104 8192000000 10381145440 10815893504 1138107760 12.198 98 33 64 2 1479310240 332981056 8221424
05:46:57 11193 8192000000 10363281760 10822238208 1148708416 12.297 100 27 54 0 1464971904 309351072 6912416
05:48:25 8069 8192000000 10392891696 10832285696 1150727840 12.316 100 40 80 0 1498905072 338198800 9978432
05:49:37 13978 8192000000 10339225328 10800234496 1148169504 12.292 100 27 54 3 1440111104 284874912 7066688
05:51:06 23178 8192000000 10315551824 10798841856 1128425712 12.107 100 29 58 2 1450457888 314785056 7247120
05:52:39 16790 8192000000 10243920320 10689687552 1111526224 11.947 100 21 40 2 1362547920 246021232 5000464
05:53:58 36623 8192000000 10042177232 10525442048 1084682720 11.692 97 7 14 1 1170282016 84029392 1569904
05:55:59 31646 8192000000 10094101648 10514194432 1119911248 12.026 99 48 95 1 1176601680 47101840 9588592
05:57:34 87415 8192000000 10049594288 10523820032 1074915104 11.599 95 13 26 0 1155487296 77954848 2617344
06:00:06 172392 8192000000 10053087456 10507776000 1072580480 11.577 95 20 40 0 1101985840 25088576 4316784
06:04:00 7985 8192000000 10084311520 10502823936 1115841728 11.988 100 56 112 1 1183838080 53980176 14016176
06:05:09 52568 8192000000 10054311120 10518736896 1105419344 11.889 99 24 48 5 1173607488 63042384 5145760
06:07:03 62639 8192000000 10104619632 10562990080 1112007104 11.951 98 10 20 0 1167205792 53128448 2070240
06:09:39 29965 8192000000 10126883568 10579214336 1141745872 12.232 100 30 61 0 1233569648 84594608 7229168
06:11:16 17667 8192000000 10096300144 10584170496 1131908832 12.139 99 11 21 0 1196211040 61751536 2550672
06:12:42 83593 8192000000 10069665328 10542317568 1126842368 12.092 99 10 20 0 1165377056 36431904 2102784
06:15:09 54163 8192000000 10138847408 10589859840 1139369904 12.210 99 27 54 1 1222003408 77204064 5429440
06:17:05 80628 8192000000 10103556368 10600775680 1134431984 12.163 100 12 24 0 1203266640 66280464 2554192
06:19:44 30004 8192000000 10095104320 10520993792 1102149904 11.858 97 27 52 2 1174825152 66466448 6208800
06:21:15 23058 8192000000 10108897936 10531516416 1136007680 12.178 100 41 81 2 1187461584 42751808 8702096
06:22:51 63595 8192000000 10161764240 10628562944 1139760128 12.213 100 22 44 1 1266552000 121529424 5262448
06:25:12 92581 8192000000 10068470944 10497417216 1141659920 12.231 100 29 59 0 1198171472 50664848 5846704
06:27:45 61206 8192000000 10076745472 10532110336 1128132720 12.104 99 12 23 2 1161173008 30361296 2678992
06:29:48 60918 8192000000 10038903600 10507997184 1070093312 11.553 94 8 16 2 1111542880 40151152 1298416
06:31:50 52107 8192000000 10098088592 10536665088 1107755488 11.911 98 27 54 0 1180871472 67372096 5743888
06:33:54 50747 8192000000 10208599472 10660831232 1131417360 12.135 99 34 68 4 1257449920 117721744 8310816
06:36:13 70686 8192000000 10062684624 10518118400 1077593920 11.625 96 11 22 0 1139404272 59542112 2268240
06:38:36 51346 8192000000 10068583040 10513850368 1099188240 11.830 98 25 50 0 1131446832 27214304 5044288
06:40:30 21548 8192000000 10116897648 10542538752 1122802432 12.053 99 42 84 1 1182913520 51551536 8559552
06:42:00 15263 8192000000 10085148544 10493620224 1122991600 12.055 99 71 141 1 1163766272 25923728 14850944
06:43:21 39277 8192000000 10104990288 10583728128 1104755472 11.883 98 19 38 2 1197136880 88532480 3848928
06:45:01 83462 8192000000 10058940400 10542915584 1111499376 11.947 99 20 40 0 1156719232 40737616 4482240
06:47:42 79751 8192000000 10086060048 10562596864 1109108624 11.924 99 5 10 0 1152381040 42269584 1002832
06:50:02 115136 8192000000 10047676704 10510127104 1100215840 11.840 98 17 34 1 1148604720 44334608 4054272
06:52:59 35443 8192000000 10065156592 10499670016 1109656352 11.929 99 35 66 1 1146244736 29843856 6744528
06:54:36 50463 8192000000 10083353888 10514530304 1082581344 11.672 96 36 72 1 1144709696 54813344 7315008
06:56:27 61273 8192000000 10049078304 10501738496 1107647456 11.910 99 14 27 1 1151424032 40649248 3127328
06:58:28 96083 8192000000 10071244432 10551599104 1123442432 12.060 99 13 26 1 1190172880 64280320 2450128
07:01:05 43659 8192000000 10095557856 10551500800 1120117744 12.028 99 21 42 1 1166168496 41761328 4289424
07:02:51 120514 8192000000 10082567280 10571382784 1128239984 12.105 100 9 18 1 1189366896 58707968 2418944
07:05:53 57286 8192000000 10066490352 10518933504 1130674608 12.128 100 14 28 0 1153142192 19417872 3049712
07:07:52 82019 8192000000 10090194368 10553966592 1116043280 11.990 98 7 14 0 1148314912 30934976 1336656
07:10:15 55786 8192000000 10063217568 10527944704 1122528960 12.051 99 25 50 0 1171951296 43843360 5578976
07:12:28 65940 8192000000 10116723520 10567401472 1133426896 12.154 99 32 64 0 1183352432 43228768 6696768
07:14:35 69773 8192000000 10085363312 10527240192 1127100400 12.094 98 18 36 0 1185700368 54747696 3852272
07:16:46 90474 8192000000 10121727104 10561724416 1125298464 12.077 98 13 26 1 1211340160 83458688 2583008
07:19:16 53082 8192000000 10074345296 10528563200 1096934144 11.809 97 34 68 0 1156894544 53498832 6461568
07:21:11 132272 8192000000 10064955856 10539737088 1123409888 12.059 100 21 42 1 1162879568 34301872 5167808
07:24:28 67516 8192000000 10092142224 10567065600 1098752640 11.826 97 6 12 0 1153411024 53218464 1439920
07:26:36 27691 8192000000 9151172080 9439080448 277582400 3.277 25 13 24 1 330568736 49826096 3160240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-01-2021 07:28:42 to 07-01-2021 07:28:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-01-2021 07:28:58 to 07-01-2021 07:29:00.

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          227300
MON$OLDEST_ACTIVE               227301
MON$OLDEST_SNAPSHOT             227301
MON$NEXT_TRANSACTION            227301
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                       2074208
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             786
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 ---
:94: 
:95: mscs-test-161.budzdorov.ru    Thu Jan  7 07:27:05 2021
:96:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:97: 
--- end of diff output ---

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

07.01.21 07:29:01. 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-07 07:29:03 No severe PSQL-related problems occured
07.01.21 07:29:05. End of report.