Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.08.2021 10:37
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 58e1ddde69c44dad93d327777e5becec
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 2021-05-09 08:45
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 4793.92
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 15768256 kB
MemAvailable 30960116 kB
Buffers 0 kB
Cached 15891516 kB
SwapCached 3640 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.1
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-08-21 10:37:06
DTS_END                         2021-08-21 10:37:06.9670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43280
TRN_ID                          233488
ATT_ID                          694
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: 9439.37 22999 1132724
customer order: creation 166.44 418 19973
customer order: refuse 14.13 337 1695
order to supplier: creation 43.48 1415 5218
order to supplier: removal 3.41 125706 409
invoice (draft): creation 44.75 3538 5370
invoice (draft): removal 57.01 856 6841
invoice accept: apply 40.17 20546 4821
invoice accept: cancel 51.53 795 6183
customer reserve: creation 3680.28 204 441634
customer reserve: removal 5043.36 68 605203
realization accept: apply 80.53 227 9663
realization accept: cancel 90.76 120 10891
payment from customer: creation 57.22 136 6866
payment from customer: removal 5.68 98 681
payment to supplier: creation 46.92 165 5630
payment to supplier: removal 3.50 87 420
service: total inventory turnovers 3.18 4389 381
service: total monetary turnovers 6.19 585 743
service: aggregate perf. data 0.81 19891 97
service: refresh index statistics 0.04 280405 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-08-2021 10:37:50 to 21-08-2021 10:37:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5994
TEST_TIME 2 3624
TEST_TIME 3 4184
TEST_TIME 4 4460
TEST_TIME 5 5174
TEST_TIME 6 5882
TEST_TIME 7 6260
TEST_TIME 8 6625
TEST_TIME 9 6932
TEST_TIME 10 7213
TEST_TIME 11 7485
TEST_TIME 12 7493
TEST_TIME 13 7448
TEST_TIME 14 7580
TEST_TIME 15 7603
TEST_TIME 16 7639
TEST_TIME 17 7888
TEST_TIME 18 8052
TEST_TIME 19 8003
TEST_TIME 20 8518
TEST_TIME 21 8662
TEST_TIME 22 8759
TEST_TIME 23 8697
TEST_TIME 24 8824
TEST_TIME 25 9139
TEST_TIME 26 9272
TEST_TIME 27 9915
TEST_TIME 28 10402
TEST_TIME 29 11326
TEST_TIME 30 11319
TEST_TIME 31 11134
TEST_TIME 32 11005
TEST_TIME 33 10928
TEST_TIME 34 10837
TEST_TIME 35 10642
TEST_TIME 36 10644
TEST_TIME 37 10439
TEST_TIME 38 10399
TEST_TIME 39 10308
TEST_TIME 40 10217
TEST_TIME 41 10341
TEST_TIME 42 10353
TEST_TIME 43 10212
TEST_TIME 44 10160
TEST_TIME 45 10068
TEST_TIME 46 9867
TEST_TIME 47 9863
TEST_TIME 48 9789
TEST_TIME 49 9737
TEST_TIME 50 9807
TEST_TIME 51 9750
TEST_TIME 52 9719
TEST_TIME 53 9700
TEST_TIME 54 9729
TEST_TIME 55 9824
TEST_TIME 56 9783
TEST_TIME 57 9798
TEST_TIME 58 9917
TEST_TIME 59 9852
TEST_TIME 60 9900
TEST_TIME 61 10051
TEST_TIME 62 10010
TEST_TIME 63 9879
TEST_TIME 64 9800
TEST_TIME 65 9706
TEST_TIME 66 9730
TEST_TIME 67 9645
TEST_TIME 68 9579
TEST_TIME 69 9515
TEST_TIME 70 9550
TEST_TIME 71 9589
TEST_TIME 72 9535
TEST_TIME 73 9501
TEST_TIME 74 9510
TEST_TIME 75 9725
TEST_TIME 76 9727
TEST_TIME 77 9751
TEST_TIME 78 9716
TEST_TIME 79 9709
TEST_TIME 80 9856
TEST_TIME 81 9802
TEST_TIME 82 9707
TEST_TIME 83 9627
TEST_TIME 84 9663
TEST_TIME 85 9643
TEST_TIME 86 9607
TEST_TIME 87 9547
TEST_TIME 88 9523
TEST_TIME 89 9484
TEST_TIME 90 9484
TEST_TIME 91 9460
TEST_TIME 92 9440
TEST_TIME 93 9433
TEST_TIME 94 9465
TEST_TIME 95 9765
TEST_TIME 96 9784
TEST_TIME 97 9771
TEST_TIME 98 9786
TEST_TIME 99 9699
TEST_TIME 100 9644
TEST_TIME 101 9625
TEST_TIME 102 9605
TEST_TIME 103 9563
TEST_TIME 104 9548
TEST_TIME 105 9549
TEST_TIME 106 9520
TEST_TIME 107 9511
TEST_TIME 108 9464
TEST_TIME 109 9470
TEST_TIME 110 9435
TEST_TIME 111 9406
TEST_TIME 112 9418
TEST_TIME 113 9398
TEST_TIME 114 9430
TEST_TIME 115 9427
TEST_TIME 116 9413
TEST_TIME 117 9449
TEST_TIME 118 9457
TEST_TIME 119 9502
TEST_TIME 120 9440

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 21-08-2021 10:37:54 to 21-08-2021 10:37:57.

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 1542578 1542578 0 0.00 0 24818 7 0 0
doc_list_biud 1542578 1542578 0 0.00 0 37574 33 0 0
sp_add_invoice_to_stock 4908 4821 87 1.77 27 201639 20546 0 87
sp_cancel_adding_invoice 6183 6183 0 0.00 6 55397 796 0 0
sp_cancel_client_order 1695 1695 0 0.00 5 8667 338 0 0
sp_cancel_customer_reserve 605203 605203 0 0.00 1 36711 68 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 8765 99 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 3535 87 0 0
sp_cancel_supplier_invoice 6847 6841 6 0.08 2 20124 856 0 6
sp_cancel_supplier_order 409 409 0 0.00 12 1162153 125706 0 0
sp_cancel_write_off 10891 10891 0 0.00 1 12674 120 0 0
sp_client_order 19973 19973 0 0.00 4 32021 418 0 0
sp_customer_reserve 441634 441634 0 0.00 0 94478 204 0 0
sp_fill_shopping_cart 30546 30546 0 0.00 1 42076 45 0 0
sp_fill_shopping_cart_clo_res 441632 441632 0 0.00 0 45692 18 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 0 51761 407 0 0
sp_kill_cost_storno 18175 18175 0 0.00 0 9976 56 0 0
sp_kill_qstorno_ret_qs2qd 1228547 1228547 0 0.00 0 37572 36 0 0
sp_kill_qty_storno 645704 645704 0 0.00 0 37574 78 0 0
sp_lock_selected_doc 20126 20126 0 0.00 0 8419 4 0 0
sp_make_cost_storno 26978 26978 0 0.00 0 24503 63 0 0
sp_multiply_rows_for_pdistr 26978 26978 0 0.00 0 24623 39 0 0
sp_multiply_rows_for_qdistr 19973 19973 0 0.00 0 22048 212 0 0
sp_pay_from_customer 6866 6866 0 0.00 4 30170 136 0 0
sp_pay_to_supplier 5630 5630 0 0.00 5 26140 165 0 0
sp_payment_common 12496 12496 0 0.00 1 24432 42 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 8657 303 0 0
sp_qd_handle_on_invoice_upd_sts 11002 11002 0 0.00 0 17831 368 0 0
sp_qd_handle_on_reserve_upd_sts 9663 9663 0 0.00 0 19318 69 0 0
sp_reserve_write_off 9663 9663 0 0.00 7 24653 228 0 0
sp_supplier_invoice 5370 5370 0 0.00 7 78392 3539 0 0
sp_supplier_order 5218 5218 0 0.00 5 38034 1416 0 0
srv_aggregate_perf_data 97 97 0 0.00 2972 173080 19892 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1204 118248 42771 0 0
srv_find_qd_qs_mism 1087350 1087350 0 0.00 0 10524 6 0 0
srv_make_invnt_saldo 381 381 0 0.00 66 81838 4390 0 0
srv_make_money_saldo 743 743 0 0.00 1 13905 586 0 0
srv_recalc_idx_stat 5 5 0 0.00 250897 357066 280406 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4822 4822 0 0.00 0 14534 200 0 0
t$perf-norm:sp_cancel_adding_invoice 406 406 0 0.00 1 1941 80 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 5138 21 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 3987 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 2505 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 164 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 1823 17 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 25407 1512 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 3260 10 0 0
t$perf-norm:sp_client_order 19973 19973 0 0.00 0 8099 19 0 0
t$perf-norm:sp_customer_reserve 962 962 0 0.00 0 3633 9 0 0
t$perf-norm:sp_pay_from_customer 6866 6866 0 0.00 0 6665 8 0 0
t$perf-norm:sp_pay_to_supplier 5630 5630 0 0.00 0 6115 10 0 0
t$perf-norm:sp_reserve_write_off 9663 9663 0 0.00 0 6700 14 0 0
t$perf-norm:sp_supplier_invoice 5370 5370 0 0.00 0 6296 24 0 0
t$perf-norm:sp_supplier_order 5218 5218 0 0.00 0 7965 23 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 147 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 6699 150 0 0
t$perf-norm:srv_make_invnt_saldo 381 381 0 0.00 0 2800 21 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 319 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9812 9638 174 1.77 0 19630 19 0 174
v_all_customers 35820 35820 0 0.00 0 205 1 0 0
v_all_suppliers 29212 29212 0 0.00 0 28 1 0 0
v_all_wares 39946 39946 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 812 812 0 0.00 0 1849 8 0 0
v_cancel_client_order 3390 3390 0 0.00 0 3435 7 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 2863 10 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3214 7 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2584 13 0 0
v_cancel_supplier_invoice 758 746 12 1.58 0 3045 16 0 12
v_cancel_supplier_order 818 818 0 0.00 0 1155 4 0 0
v_cancel_write_off 1980 1980 0 0.00 0 1831 15 0 0
v_max_id_clo_ord 5215 5215 0 0.00 0 745 1 0 0
v_max_id_clo_res 962 962 0 0.00 0 5616 23 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 7955 6 0 0
v_max_non_paid_invoice 1597 1597 0 0.00 0 1062 3 0 0
v_max_non_paid_realizn 3792 3792 0 0.00 0 725 3 0 0
v_min_id_clo_ord 5215 5215 0 0.00 0 1653 6 0 0
v_min_id_clo_res 962 962 0 0.00 0 19680 47 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 6294 6 0 0
v_min_non_paid_invoice 5630 5630 0 0.00 0 22066 40 0 0
v_min_non_paid_realizn 6866 6866 0 0.00 0 18901 27 0 0
v_our_firm 10274 10274 0 0.00 0 12 1 0 0
v_reserve_write_off 19326 19326 0 0.00 0 20362 9 0 0
x_lock_depdocs_on_canc_invoice 12651 12651 0 0.00 0 27140 100 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 23266 378 0 0
x_make_qstorno_1000_1200 5218 5218 0 0.00 2 38021 1329 0 0
x_make_qstorno_1200_2000 5370 5370 0 0.00 4 78365 3371 0 0
x_make_qstorno_2100_3300 441634 441634 0 0.00 0 94476 184 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-08-2021 10:37:58 to 21-08-2021 10:38:02.

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
08:07:10 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 33927 8192000000 9722975760 10021916672 802166560 8.918 100 92 184 0 832985984 7645120 23174304
08:09:55 17232 8192000000 9765477920 10071293952 838268336 9.282 100 92 184 0 874793232 14333536 22191360
08:11:21 13762 8192000000 9806886480 10124382208 870721504 9.607 100 88 178 0 915270560 24160112 20388944
08:12:41 13265 8192000000 9864358896 10198274048 913457936 10.031 100 83 165 2 973413856 41014688 18941232
08:14:01 10647 8192000000 9926986208 10273759232 965681088 10.545 100 73 146 0 1035239504 54136256 15422160
08:15:15 4301 8192000000 9981705856 10337243136 1009708912 10.973 100 88 174 1 1093244672 65718576 17817184
08:16:22 4118 8192000000 10019101696 10381987840 1033902384 11.206 100 93 186 0 1128506688 75836640 18767664
08:17:29 2097 8192000000 10036236080 10406150144 1057090992 11.429 100 90 176 1 1146281152 71566880 17623280
08:18:32 3348 8192000000 10033323936 10408427520 1067405312 11.527 100 85 170 0 1149006640 63705616 17895712
08:19:36 6952 8192000000 10024284512 10417041408 1075454624 11.604 100 44 86 2 1119625072 35716128 8454320
08:20:44 7872 8192000000 9975640032 10377269248 1082945952 11.676 100 29 59 1 1103364288 14494288 5924048
08:21:53 3495 8192000000 10012329696 10405830656 1087548560 11.719 100 43 87 5 1123322560 25901216 9872784
08:22:57 58263 8192000000 10005427760 10411347968 1091106624 11.753 100 28 56 0 1113833952 16839056 5888272
08:24:57 6333 8192000000 10017578736 10424365056 1099483040 11.833 100 50 101 2 1126045280 15065568 11496672
08:26:03 8212 8192000000 10005598368 10408689664 1092489024 11.766 99 35 68 1 1126417760 26233408 7695328
08:27:13 4810 8192000000 9989359728 10385653760 1074639056 11.596 99 46 93 3 1099349776 14557872 10152848
08:28:22 47026 8192000000 9968014912 10362085376 993428464 10.815 92 38 75 2 1025590048 23147424 9014160
08:30:10 3611 8192000000 9958736640 10333745152 1028748000 11.156 100 56 112 3 1065328768 24606160 11974608
08:31:14 4953 8192000000 9926717792 10304544768 1002236128 10.900 98 49 98 1 1037073568 22886896 11950544
08:32:22 5382 8192000000 9961505200 10349838336 1036775408 11.234 100 34 68 0 1071792592 27420816 7596368
08:33:29 45077 8192000000 9949328272 10355359744 1039844336 11.263 99 34 68 1 1066063600 18562960 7656304
08:35:14 4951 8192000000 9981837632 10375561216 1063057040 11.486 100 33 67 2 1107083648 36609248 7417360
08:36:19 7246 8192000000 9987107104 10386432000 1067599728 11.529 100 31 57 5 1104356976 30196800 6560448
08:37:29 17086 8192000000 10029921248 10419888128 1096832944 11.808 100 63 123 1 1132035408 21314672 13887792
08:39:00 82258 8192000000 10061370720 10478972928 1113550784 11.966 100 30 59 2 1182027280 62580448 5896048
08:41:38 8129 8192000000 10102270192 10512306176 1131257728 12.133 100 59 115 1 1206465584 62991232 12216624
08:42:51 3049 8192000000 10146829728 10547425280 1136548848 12.183 100 74 148 0 1250265824 97314880 16402096
08:43:56 6550 8192000000 10156872176 10569154560 1146982128 12.281 100 53 106 0 1267886896 109560720 11344048
08:45:13 11610 8192000000 10152296592 10588995584 1161338464 12.416 100 52 97 3 1260389008 88588144 10462400
08:46:31 9925 8192000000 10191212320 10612199424 1165319712 12.453 100 55 110 1 1305146688 128133216 11693760
08:47:46 3943 8192000000 10217786400 10625179648 1156267776 12.368 99 77 154 1 1313840880 141203808 16369296
08:48:54 3797 8192000000 10206799504 10608758784 1141661376 12.231 100 66 132 0 1305615440 149233840 14720224
08:50:04 2563 8192000000 10212266016 10614812672 1157198528 12.377 100 88 177 1 1322207536 144495968 20513040
08:51:13 10530 8192000000 10258122048 10670026752 1147590096 12.287 100 69 138 1 1365237280 201903360 15743824
08:52:30 2332 8192000000 10277529536 10677796864 1139208816 12.208 100 84 169 0 1380693408 222197248 19287344
08:53:37 3738 8192000000 10320847472 10731356160 1163744160 12.438 100 58 116 0 1426456416 248063088 14649168
08:54:46 1201 8192000000 10342825856 10749083648 1145613744 12.268 100 92 183 2 1443569360 277033840 20921776
08:55:52 1833 8192000000 10388955168 10787180544 1152231264 12.330 100 97 194 1 1479655552 305339568 22084720
08:56:59 2640 8192000000 10424549936 10831060992 1143396832 12.247 99 73 146 0 1513967840 353164976 17406032
08:58:07 9421 8192000000 10367760656 10801881088 1148977792 12.300 100 46 92 0 1487559360 326454880 12126688
08:59:20 9494 8192000000 10411549472 10836250624 1158014240 12.385 100 40 80 0 1511078352 342958256 10105856
09:00:32 5426 8192000000 10454730544 10874617856 1158942160 12.393 100 45 85 0 1553735280 384017280 10775840
09:01:39 9465 8192000000 10472923632 10911285248 1146287872 12.275 100 36 72 0 1567199904 410946016 9966016
09:02:55 8944 8192000000 10453857024 10896216064 1149932784 12.309 100 45 90 0 1537424880 375942976 11549120
09:04:07 25515 8192000000 10457136160 10948620288 1132284960 12.143 100 25 50 1 1599446144 460055104 7106080
09:05:41 42886 8192000000 10081356528 10612887552 1088965664 11.733 98 12 24 0 1323554240 231464736 3123840
09:07:25 73713 8192000000 10049601696 10478456832 1091001920 11.752 97 14 28 0 1145059680 51259040 2798720
09:09:39 63416 8192000000 10089135472 10537218048 1121158576 12.038 99 24 46 1 1181365104 55391488 4815040
09:11:47 111944 8192000000 10072562240 10534199296 1126566992 12.089 99 27 54 1 1174100496 41383808 6149696
09:14:45 25014 8192000000 10097795872 10538434560 1132662832 12.146 100 34 68 1 1194394672 54016992 7714848
09:16:20 43724 8192000000 10129169952 10572828672 1133146752 12.151 99 27 54 1 1220900704 81903920 5850032
09:18:08 48177 8192000000 10116717504 10634661888 1143339024 12.247 100 13 26 0 1234746704 88604640 2803040
09:19:57 16967 8192000000 10054735872 10509996032 1132518288 12.145 100 9 16 1 1161613168 27390848 1704032
09:21:30 60529 8192000000 10078960000 10546364416 1130784240 12.129 99 24 49 1 1171647040 35189200 5673600
09:23:32 50561 8192000000 10091893808 10549809152 1136231472 12.180 99 21 42 0 1198902640 58476048 4195120
09:25:23 59832 8192000000 10104865680 10567409664 1146066512 12.273 100 15 30 3 1209564880 60226880 3271488
09:27:26 63219 8192000000 10096933872 10607419392 1137769584 12.195 99 11 22 0 1185450560 45474048 2206928
09:29:30 44056 8192000000 10122458368 10572029952 1125981232 12.083 98 12 24 0 1188635152 60129696 2524224
09:31:15 60566 8192000000 10093818368 10577018880 1133422400 12.154 99 21 42 0 1233582272 95306624 4853248
09:33:23 47016 8192000000 10136345792 10575269888 1119376432 12.021 97 31 61 1 1185695376 60181808 6137136
09:35:14 55332 8192000000 10070024704 10554744832 1055767344 11.416 93 16 32 0 1142443040 83046320 3629376
09:37:12 93470 8192000000 10065421312 10600914944 1102519808 11.862 98 9 18 0 1177038400 72403936 2114656
09:39:47 93378 8192000000 10071127408 10500722688 1112780672 11.959 98 43 85 0 1144371216 23044464 8546080
09:42:29 17917 8192000000 10069039536 10502742016 1103496672 11.871 98 38 76 0 1158465248 47542256 7426320
09:43:47 37988 8192000000 10033014912 10519011328 1081136496 11.658 98 11 21 1 1130861056 47518880 2205680
09:45:31 34592 8192000000 10046256752 10541125632 1102930912 11.865 100 6 12 0 1158155824 54175072 1049840
09:47:06 66590 8192000000 10080362224 10577891328 1120764608 12.034 99 13 27 0 1176550240 53029472 2756160
09:49:15 37522 8192000000 10105244032 10531180544 1089667312 11.739 96 23 46 0 1164361440 69563584 5130544
09:50:53 84349 8192000000 10130919136 10677989376 1091758000 11.759 97 18 36 1 1224483504 128173312 4552192
09:53:22 39391 8192000000 10084913904 10535030784 1108944352 11.922 98 35 70 1 1164325760 47564544 7816864
09:55:16 36047 8192000000 10106348464 10542411776 1132655152 12.146 100 25 50 0 1183653136 46018816 4979168
09:56:56 97450 8192000000 10049979216 10499850240 1089506224 11.738 96 16 32 0 1190845104 97552832 3786048
09:59:37 118248 8192000000 10126379856 10608623616 1134029264 12.159 100 43 86 1 1244829904 101634416 9166224
10:03:42 54544 8192000000 10099112880 10548748288 1138381344 12.200 100 16 32 0 1201846640 59536000 3929296
10:05:42 25132 8192000000 10132496656 10578690048 1140367552 12.219 100 30 60 1 1233973072 86876512 6729008
10:07:14 49739 8192000000 10190524336 10646896640 1133512960 12.154 98 33 66 0 1254025488 112289264 8223264
10:09:12 43286 8192000000 10262455200 10705752064 1161194400 12.414 99 35 70 2 1342094896 172673904 8226592
10:11:08 52939 8192000000 10289512048 10826391552 1154134800 12.348 100 22 44 0 1430045632 270386416 5524416
10:13:33 50291 8192000000 10084730752 10543456256 1137384416 12.191 98 14 28 1 1194095904 54045376 2666112
10:15:24 34272 8192000000 10093435920 10531344384 1157536768 12.380 100 47 94 0 1196758624 29221024 10000832
10:16:58 73403 8192000000 10101303680 10565984256 1152171584 12.330 100 43 86 1 1217363760 56632192 8559984
10:19:48 108259 8192000000 10166527072 10660818944 1147749824 12.288 98 13 26 1 1216409584 65351632 3308128
10:22:40 49766 8192000000 10105867264 10591584256 1146373616 12.275 99 11 22 0 1251354992 102389904 2591472
10:24:49 80310 8192000000 10122445456 10596257792 1149951040 12.309 99 17 34 0 1196608336 42992656 3664640
10:27:13 61271 8192000000 10062299024 10515513344 1129387936 12.116 98 6 12 0 1152089824 21705616 996272
10:29:15 75022 8192000000 10110195168 10579390464 1084425008 11.690 94 26 52 1 1160131200 70579984 5126208
10:31:45 81286 8192000000 10085718688 10558660608 1096723392 11.807 96 18 36 0 1146872304 46608944 3539968
10:34:17 106225 8192000000 10090850208 10540494848 1091585872 11.758 96 23 46 0 1150552208 54475504 4490832
10:37:03 13728 8192000000 8985743008 9204580352 140792544 1.689 13 1 2 0 151021120 10193168 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 21-08-2021 10:38:04 to 21-08-2021 10:38:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-08-2021 10:38:20 to 21-08-2021 10:38:22.

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          233788
MON$OLDEST_ACTIVE               233789
MON$OLDEST_SNAPSHOT             233789
MON$NEXT_TRANSACTION            233789
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                       2089760
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             799
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17: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 ---
:63: 
:64: mscs-test-161.budzdorov.ru    Sat Aug 21 10:37:20 2021
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Sat Aug 21 10:37:20 2021
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
:71: 
:72: mscs-test-161.budzdorov.ru    Sat Aug 21 10:37:20 2021
:73:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:74: 
--- end of diff output ---

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

21.08.21 10:38:23. 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-08-21 10:38:26 No severe PSQL-related problems occured
21.08.21 10:38:27. End of report.