Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.09.2021 01:38
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 16444964 kB
MemAvailable 30767540 kB
Buffers 0 kB
Cached 15224708 kB
SwapCached 668 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-22 01:37:50
DTS_END                         2021-09-22 01:37:50.6120
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/54834
TRN_ID                          236478
ATT_ID                          664
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: 8971.70 21215 1076604
customer order: creation 166.98 427 20038
customer order: refuse 13.83 345 1660
order to supplier: creation 45.61 1321 5473
order to supplier: removal 3.41 99781 409
invoice (draft): creation 44.08 3358 5290
invoice (draft): removal 50.80 807 6096
invoice accept: apply 41.01 19198 4921
invoice accept: cancel 46.10 804 5532
customer reserve: creation 3794.47 190 455336
customer reserve: removal 4470.60 60 536472
realization accept: apply 82.43 185 9891
realization accept: cancel 85.98 99 10318
payment from customer: creation 57.34 135 6881
payment from customer: removal 5.92 79 710
payment to supplier: creation 47.89 138 5747
payment to supplier: removal 3.17 61 380
service: total inventory turnovers 4.85 1186 582
service: total monetary turnovers 6.32 23 758
service: aggregate perf. data 0.88 15753 106
service: refresh index statistics 0.03 280342 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 22-09-2021 01:39:07 to 22-09-2021 01:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1857
TEST_TIME 2 2477
TEST_TIME 3 3129
TEST_TIME 4 3801
TEST_TIME 5 4016
TEST_TIME 6 4006
TEST_TIME 7 4086
TEST_TIME 8 4461
TEST_TIME 9 4519
TEST_TIME 10 4974
TEST_TIME 11 5239
TEST_TIME 12 5768
TEST_TIME 13 6426
TEST_TIME 14 6570
TEST_TIME 15 6829
TEST_TIME 16 7552
TEST_TIME 17 7698
TEST_TIME 18 7509
TEST_TIME 19 7800
TEST_TIME 20 8350
TEST_TIME 21 8411
TEST_TIME 22 9359
TEST_TIME 23 9754
TEST_TIME 24 9937
TEST_TIME 25 9952
TEST_TIME 26 10136
TEST_TIME 27 9903
TEST_TIME 28 9893
TEST_TIME 29 9757
TEST_TIME 30 9588
TEST_TIME 31 9711
TEST_TIME 32 9499
TEST_TIME 33 9327
TEST_TIME 34 9269
TEST_TIME 35 9384
TEST_TIME 36 9231
TEST_TIME 37 9246
TEST_TIME 38 9273
TEST_TIME 39 9151
TEST_TIME 40 9167
TEST_TIME 41 9130
TEST_TIME 42 9251
TEST_TIME 43 9095
TEST_TIME 44 9013
TEST_TIME 45 8938
TEST_TIME 46 8909
TEST_TIME 47 8957
TEST_TIME 48 8916
TEST_TIME 49 8841
TEST_TIME 50 8754
TEST_TIME 51 8810
TEST_TIME 52 8805
TEST_TIME 53 8766
TEST_TIME 54 8763
TEST_TIME 55 8800
TEST_TIME 56 8821
TEST_TIME 57 8892
TEST_TIME 58 8920
TEST_TIME 59 9007
TEST_TIME 60 8997
TEST_TIME 61 8997
TEST_TIME 62 9167
TEST_TIME 63 9255
TEST_TIME 64 9359
TEST_TIME 65 9342
TEST_TIME 66 9239
TEST_TIME 67 9223
TEST_TIME 68 9200
TEST_TIME 69 9139
TEST_TIME 70 9189
TEST_TIME 71 9111
TEST_TIME 72 9076
TEST_TIME 73 9020
TEST_TIME 74 9014
TEST_TIME 75 8996
TEST_TIME 76 9000
TEST_TIME 77 8999
TEST_TIME 78 9033
TEST_TIME 79 9064
TEST_TIME 80 9054
TEST_TIME 81 9092
TEST_TIME 82 9176
TEST_TIME 83 9159
TEST_TIME 84 9147
TEST_TIME 85 9149
TEST_TIME 86 9274
TEST_TIME 87 9274
TEST_TIME 88 9242
TEST_TIME 89 9175
TEST_TIME 90 9170
TEST_TIME 91 9114
TEST_TIME 92 9104
TEST_TIME 93 9034
TEST_TIME 94 9040
TEST_TIME 95 9032
TEST_TIME 96 9046
TEST_TIME 97 9025
TEST_TIME 98 8993
TEST_TIME 99 9068
TEST_TIME 100 9080
TEST_TIME 101 9066
TEST_TIME 102 9026
TEST_TIME 103 9092
TEST_TIME 104 9169
TEST_TIME 105 9223
TEST_TIME 106 9193
TEST_TIME 107 9178
TEST_TIME 108 9152
TEST_TIME 109 9154
TEST_TIME 110 9110
TEST_TIME 111 9093
TEST_TIME 112 9056
TEST_TIME 113 9018
TEST_TIME 114 9005
TEST_TIME 115 8987
TEST_TIME 116 8964
TEST_TIME 117 8979
TEST_TIME 118 8979
TEST_TIME 119 8965
TEST_TIME 120 8974

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 22-09-2021 01:39:11 to 22-09-2021 01:39:14.

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 1497585 1497585 0 0.00 0 20947 7 0 0
doc_list_biud 1497584 1497584 0 0.00 0 40345 27 0 0
sp_add_invoice_to_stock 5011 4921 90 1.79 14 259334 19199 0 90
sp_cancel_adding_invoice 5532 5532 0 0.00 6 76377 805 0 0
sp_cancel_client_order 1660 1660 0 0.00 4 14270 346 0 0
sp_cancel_customer_reserve 536472 536472 0 0.00 1 21375 60 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 4649 79 0 0
sp_cancel_pay_to_supplier 380 380 0 0.00 3 3790 61 0 0
sp_cancel_supplier_invoice 6102 6096 6 0.09 2 40354 808 0 6
sp_cancel_supplier_order 409 409 0 0.00 7 841415 99782 0 0
sp_cancel_write_off 10318 10318 0 0.00 1 12790 100 0 0
sp_client_order 20038 20038 0 0.00 4 50246 427 0 0
sp_customer_reserve 455336 455336 0 0.00 0 47077 190 0 0
sp_fill_shopping_cart 30795 30795 0 0.00 1 10120 40 0 0
sp_fill_shopping_cart_clo_res 455329 455329 0 0.00 0 19489 17 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 36632 387 0 0
sp_kill_cost_storno 16940 16940 0 0.00 0 7231 50 0 0
sp_kill_qstorno_ret_qs2qd 1089767 1089767 0 0.00 0 40340 33 0 0
sp_kill_qty_storno 575290 575290 0 0.00 0 40343 70 0 0
sp_lock_selected_doc 20366 20366 0 0.00 0 7577 5 0 0
sp_make_cost_storno 27432 27432 0 0.00 0 20764 60 0 0
sp_multiply_rows_for_pdistr 27432 27432 0 0.00 0 9917 30 0 0
sp_multiply_rows_for_qdistr 20038 20038 0 0.00 0 49762 184 0 0
sp_pay_from_customer 6881 6881 0 0.00 4 28996 135 0 0
sp_pay_to_supplier 5747 5747 0 0.00 5 12758 139 0 0
sp_payment_common 12628 12628 0 0.00 2 28978 36 0 0
sp_qd_handle_on_cancel_clo 1660 1660 0 0.00 0 14262 319 0 0
sp_qd_handle_on_invoice_upd_sts 10444 10444 0 0.00 0 31228 321 0 0
sp_qd_handle_on_reserve_upd_sts 9891 9891 0 0.00 0 15939 58 0 0
sp_reserve_write_off 9891 9891 0 0.00 5 15960 186 0 0
sp_supplier_invoice 5290 5290 0 0.00 8 76070 3359 0 0
sp_supplier_order 5473 5473 0 0.00 5 48549 1321 0 0
srv_aggregate_perf_data 106 106 0 0.00 2942 164825 15754 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1573 104115 41970 0 0
srv_find_qd_qs_mism 1029582 1029582 0 0.00 0 14535 6 0 0
srv_make_invnt_saldo 582 582 0 0.00 31 113632 1186 0 0
srv_make_money_saldo 758 758 0 0.00 1 3285 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 53605 404858 280342 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 4921 4921 0 0.00 0 18311 166 0 0
t$perf-norm:sp_cancel_adding_invoice 403 403 0 0.00 0 4254 82 0 0
t$perf-norm:sp_cancel_client_order 1660 1660 0 0.00 0 2041 10 0 0
t$perf-norm:sp_cancel_customer_reserve 639 639 0 0.00 0 1518 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 5420 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 380 380 0 0.00 0 359 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 4006 27 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 25768 1461 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 2796 18 0 0
t$perf-norm:sp_client_order 20038 20038 0 0.00 0 9097 15 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 16732 33 0 0
t$perf-norm:sp_pay_from_customer 6881 6881 0 0.00 0 10139 12 0 0
t$perf-norm:sp_pay_to_supplier 5747 5747 0 0.00 0 14535 16 0 0
t$perf-norm:sp_reserve_write_off 9891 9891 0 0.00 0 9147 10 0 0
t$perf-norm:sp_supplier_invoice 5290 5290 0 0.00 0 13709 19 0 0
t$perf-norm:sp_supplier_order 5473 5473 0 0.00 0 2924 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 235 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 582 582 0 0.00 0 599 15 0 0
t$perf-norm:srv_make_money_saldo 758 758 0 0.00 0 843 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 36 11 0 0
v_add_invoice_to_stock 10002 9822 180 1.79 0 11732 20 0 180
v_all_customers 36226 36226 0 0.00 0 82 1 0 0
v_all_suppliers 29862 29862 0 0.00 0 99 1 0 0
v_all_wares 40076 40076 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 806 806 0 0.00 0 3383 18 0 0
v_cancel_client_order 3320 3320 0 0.00 0 2661 5 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2859 9 0 0
v_cancel_customer_reserve 1278 1278 0 0.00 0 648 3 0 0
v_cancel_payment_to_supplier 760 760 0 0.00 0 3778 13 0 0
v_cancel_supplier_invoice 808 796 12 1.48 0 3953 22 0 12
v_cancel_supplier_order 818 818 0 0.00 0 5227 14 0 0
v_cancel_write_off 1930 1930 0 0.00 0 12681 20 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 806 1 0 0
v_max_id_clo_res 971 971 0 0.00 0 2295 13 0 0
v_max_id_ord_sup 5287 5287 0 0.00 0 6105 6 0 0
v_max_non_paid_invoice 1573 1573 0 0.00 0 686 3 0 0
v_max_non_paid_realizn 3773 3773 0 0.00 0 4037 6 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 6628 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 2570 10 0 0
v_min_id_ord_sup 5287 5287 0 0.00 0 5134 6 0 0
v_min_non_paid_invoice 5747 5747 0 0.00 0 6420 20 0 0
v_min_non_paid_realizn 6881 6881 0 0.00 0 6952 21 0 0
v_our_firm 10066 10066 0 0.00 0 22 1 0 0
v_reserve_write_off 19782 19782 0 0.00 0 14544 10 0 0
x_lock_depdocs_on_canc_invoice 11230 11230 0 0.00 0 18438 117 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 11386 313 0 0
x_make_qstorno_1000_1200 5473 5473 0 0.00 2 48523 1243 0 0
x_make_qstorno_1200_2000 5290 5290 0 0.00 4 74828 3210 0 0
x_make_qstorno_2100_3300 455336 455336 0 0.00 0 39229 171 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 22-09-2021 01:39:15 to 22-09-2021 01:39:20.

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
23:07:54 1 8192000000 8799426704 8810364928 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:56 30516 8192000000 10367848640 10688724992 1415583408 14.734 100 88 176 0 1451340224 6010656 29746160
23:10:40 26097 8192000000 10460765712 10795114496 1509870432 15.562 100 88 176 1 1546238432 8844480 27523520
23:12:09 17930 8192000000 10497350864 10843713536 1560377120 15.999 100 84 168 0 1596953600 10797968 25778512
23:13:34 14218 8192000000 10572989552 10933387264 1630239488 16.597 100 87 174 0 1668146736 11432112 26475136
23:14:51 9121 8192000000 10651393264 11021836288 1704376592 17.222 100 74 148 0 1737141776 11282800 21482384
23:16:04 4176 8192000000 10724247104 11100942336 1779120800 17.842 100 83 167 1 1816553712 14023904 23409008
23:17:09 2886 8192000000 10755172288 11139158016 1821473552 18.190 100 83 163 0 1856894688 13305952 22115184
23:18:13 2066 8192000000 10818445056 11203698688 1869247792 18.578 100 96 192 2 1910020192 14163856 26608544
23:19:17 45102 8192000000 10809120048 11215908864 1877514384 18.645 100 66 132 1 1907795680 11676496 18604800
23:21:03 4675 8192000000 10808422880 11231105024 1887557280 18.726 100 32 62 1 1908877168 12336752 8983136
23:22:10 6362 8192000000 10811127840 11235405824 1896067744 18.795 100 31 62 0 1910784912 5950432 8766736
23:23:23 7449 8192000000 10814166960 11245096960 1903478080 18.854 100 23 46 0 1916876800 6990176 6408544
23:24:31 46362 8192000000 10812820768 11257192448 1912461456 18.926 100 28 54 1 1924806544 5102448 7242640
23:26:25 7779 8192000000 10819214320 11255140352 1872378912 18.604 98 26 50 3 1889336992 10000816 6957264
23:27:33 6195 8192000000 10783308464 11209945088 1841232096 18.351 98 33 64 0 1855834880 5699968 8902816
23:28:41 13098 8192000000 10751484304 11175272448 1790124096 17.933 97 13 26 1 1799167696 4668576 4375024
23:30:00 42788 8192000000 10763460016 11161833472 1812686064 18.118 99 57 115 2 1835167008 5175200 17305744
23:31:43 6216 8192000000 10747398768 11159797760 1804254928 18.049 99 35 70 1 1821306464 6521888 10529648
23:32:50 8063 8192000000 10758413648 11171450880 1816496320 18.149 99 24 49 3 1829007760 5469920 7041520
23:34:00 13090 8192000000 10754065328 11174244352 1821345280 18.189 99 21 44 1 1833009760 5442384 6222096
23:35:16 59791 8192000000 10766447856 11202015232 1854940416 18.462 100 28 56 3 1868037584 5554496 7542672
23:37:16 10462 8192000000 10804847440 11233259520 1886455920 18.717 100 24 48 1 1898180080 4688464 7035696
23:38:31 22707 8192000000 10841775616 11260735488 1906666336 18.880 100 46 93 1 1926898688 6713376 13518976
23:40:02 19212 8192000000 10877946400 11301629952 1940446448 19.150 100 46 92 1 1968336816 13168576 14721792
23:41:23 3671 8192000000 10894659584 11317014528 1952893280 19.250 100 56 112 1 1986039296 16666800 16479216
23:42:29 8735 8192000000 10889296800 11323252736 1957514320 19.286 100 35 70 2 1983640144 15727056 10398768
23:43:41 9233 8192000000 10916296544 11334148096 1945855856 19.193 99 56 110 3 1978632912 17830064 14946992
23:44:58 5384 8192000000 10913220592 11327705088 1957577168 19.287 99 61 123 1 1995748128 20741184 17429776
23:46:06 7772 8192000000 10937037568 11374813184 1992155888 19.561 100 51 102 2 2033346256 25922656 15267712
23:47:18 4662 8192000000 10946694976 11382247424 1995045728 19.584 100 48 97 2 2041433424 31506624 14881072
23:48:26 1573 8192000000 10989734480 11414347776 2011782864 19.716 100 71 140 1 2073032000 39546768 21702368
23:49:33 6493 8192000000 11008540864 11433521152 2030937968 19.866 100 67 134 0 2093780048 42238960 20603120
23:50:46 8444 8192000000 10998462592 11431624704 2015573440 19.745 99 63 123 1 2071827520 36715792 19538288
23:52:01 5047 8192000000 10980691568 11420450816 1999449872 19.618 99 49 98 1 2053516176 38308656 15757648
23:53:09 6847 8192000000 10950593232 11394203648 1985441632 19.508 100 44 88 1 2041003072 41012720 14548720
23:54:20 3242 8192000000 10966772784 11395350528 1983755360 19.494 99 66 130 0 2035930320 32414448 19760512
23:55:26 11290 8192000000 10949511728 11390312448 1966823808 19.360 99 37 73 1 2016593184 37084144 12685232
23:56:43 3551 8192000000 10968348992 11397931008 1981812464 19.479 100 67 134 1 2047999360 44234352 21952544
23:57:57 10666 8192000000 10920067088 11362836480 1911234480 18.917 97 37 72 3 1965773424 41502672 13036272
23:59:13 15733 8192000000 10894933072 11346522112 1934580320 19.103 100 32 64 0 1984821792 39549552 10691920
00:00:32 19344 8192000000 10868372208 11328249856 1915704288 18.952 99 17 34 1 1946609744 25120704 5784752
00:01:56 18192 8192000000 10849840192 11273125888 1888841328 18.736 99 37 74 0 1918223392 18356192 11025872
00:03:15 28321 8192000000 10830628848 11288961024 1892399840 18.765 100 26 52 2 1921425952 21068720 7957392
00:04:47 100167 8192000000 10814070640 11246891008 1844325264 18.376 97 30 61 4 1865518800 11056576 10136960
00:07:28 75111 8192000000 10831047104 11293184000 1866331456 18.555 97 29 58 0 1883469856 9068928 8069472
00:09:45 37224 8192000000 10868579248 11309092864 1920451776 18.990 99 26 52 0 1938455728 9639408 8364544
00:11:25 51770 8192000000 10875757968 11337953280 1950992448 19.234 100 12 24 1 1966805648 11994496 3818704
00:13:19 19336 8192000000 10883793888 11318730752 1933828288 19.097 99 47 93 0 1956909904 10173680 12907936
00:14:44 35459 8192000000 10877716592 11342340096 1954668640 19.264 100 24 48 1 1975095856 13228208 7199008
00:16:21 52337 8192000000 10892464624 11354243072 1950211008 19.228 99 10 20 0 1968561856 15025456 3325392
00:18:22 99411 8192000000 10895618624 11360301056 1968191424 19.371 100 18 36 0 1988466192 14786848 5487920
00:21:19 67603 8192000000 10922394624 11379638272 1991566704 19.556 100 32 64 0 2010861872 10369488 8925680
00:23:27 30555 8192000000 10926068768 11373416448 1986716272 19.518 100 44 87 1 2010117280 11283984 12117024
00:25:01 46466 8192000000 10893813136 11360784384 1962118608 19.323 99 14 29 0 1976580432 10159920 4301904
00:27:07 72084 8192000000 10887708032 11366473728 1970424528 19.389 99 19 38 0 1984107744 8175840 5507376
00:29:22 64459 8192000000 10899881952 11356241920 1936172496 19.116 97 13 26 0 1950465616 9390624 4902496
00:31:27 32454 8192000000 10929387952 11393871872 1995898528 19.590 100 20 40 0 2011276240 9869520 5508192
00:33:02 67902 8192000000 10925580944 11397640192 1966082928 19.354 98 31 62 1 1990143136 15098864 8961344
00:35:10 45791 8192000000 10929105824 11399344128 1982979984 19.488 99 26 52 0 2008198496 17536704 7681808
00:36:56 55591 8192000000 10947605168 11401523200 1973409296 19.412 98 32 64 2 1994976080 12896336 8670448
00:38:52 49257 8192000000 10926951840 11385294848 1963921808 19.337 98 16 32 2 1978930896 10589504 4419584
00:40:58 104115 8192000000 10920766816 11394646016 1927374256 19.046 96 13 25 2 1942142128 11624608 3143264
00:43:44 86023 8192000000 10906942672 11372249088 1944723104 19.184 97 41 82 0 1969473440 13019968 11730368
00:46:12 17707 8192000000 10905041808 11342303232 1948072624 19.211 98 46 92 0 1972019488 11372544 12574320
00:47:30 50434 8192000000 10871688048 11348848640 1901644976 18.840 96 9 18 1 1913947248 9818272 2484000
00:49:21 17909 8192000000 10855710176 11313725440 1890723328 18.752 97 19 37 0 1910515520 15205456 4586736
00:50:39 62537 8192000000 10841337344 11313098752 1908486656 18.894 98 11 22 0 1926442368 14439424 3516288
00:53:14 42894 8192000000 10810004736 11269255168 1833362688 18.287 95 10 20 0 1846313088 9341056 3609344
00:55:11 41887 8192000000 10851465232 11317837824 1902575344 18.847 99 14 28 0 1920973552 13571680 4826528
00:56:56 57265 8192000000 10870042736 11322884096 1869008080 18.576 96 19 38 0 1884239296 10185104 5046112
00:59:03 82517 8192000000 10850951232 11302326272 1854371376 18.458 96 21 42 1 1873344112 13127696 5845040
01:01:28 38804 8192000000 10861564496 11307876352 1901876448 18.841 99 41 83 1 1928590864 14911760 11802656
01:03:11 51753 8192000000 10870923200 11338973184 1934503728 19.103 100 13 26 1 1960516944 22249776 3763440
01:05:03 49511 8192000000 10883502288 11340988416 1931508240 19.079 99 37 74 1 1953946304 12333232 10104832
01:07:05 33372 8192000000 10891079696 11346935808 1964898800 19.345 100 39 74 3 1982894080 6908992 11086288
01:08:38 49487 8192000000 10877865632 11351142400 1930249488 19.069 98 19 38 1 1946997184 11836448 4911248
01:10:28 50072 8192000000 10878214656 11357175808 1945342128 19.189 99 5 10 0 1955011552 8565504 1103920
01:12:28 42543 8192000000 10899210304 11358339072 1967977136 19.369 100 29 57 2 1982927520 6888896 8061488
01:14:14 40903 8192000000 10911671312 11372015616 1945524800 19.191 98 32 64 0 1963758512 9303040 8930672
01:15:56 59047 8192000000 10898913856 11361902592 1954556528 19.263 99 17 34 0 1967757920 8225488 4975904
01:18:08 66674 8192000000 10916726320 11377557504 1961133584 19.315 99 25 50 1 1981031776 12629456 7268736
01:20:21 77587 8192000000 10919572352 11412213760 1982793904 19.487 100 10 20 1 2006395648 20196064 3405680
01:22:39 73257 8192000000 10924831472 11379388416 1951955968 19.242 98 35 69 0 1968136272 7211920 8968384
01:24:59 22330 8192000000 10955290976 11404611584 1990921056 19.551 99 53 106 1 2020314432 14729248 14664128
01:26:22 93809 8192000000 10920732384 11375755264 1939652976 19.144 97 36 72 1 1965854688 15890144 10311568
01:29:18 57677 8192000000 10892805328 11378245632 1967024080 19.362 99 8 15 0 1980623472 11348304 2251088
01:31:17 55190 8192000000 10908213040 11390136320 1984957888 19.504 100 9 18 1 1997995680 10272096 2765696
01:33:14 61369 8192000000 10907495200 11381620736 1957296032 19.285 98 15 30 0 1971352608 9581200 4475376
01:35:30 102058 8192000000 10888365776 11352977408 1896431552 18.798 96 17 34 0 1912172624 10880256 4860816

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-09-2021 01:39:21 to 22-09-2021 01:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 22-09-2021 01:39:37 to 22-09-2021 01:39:39.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          233644
MON$OLDEST_ACTIVE               236602
MON$OLDEST_SNAPSHOT             236602
MON$NEXT_TRANSACTION            236602
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-03 16:41:55.3470
MON$PAGES                       1986560
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Wed Sep 22 01:37:51 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Wed Sep 22 01:37:52 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Wed Sep 22 01:37:53 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
:57: 
:58: mscs-test-161.budzdorov.ru    Wed Sep 22 01:38:12 2021
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Wed Sep 22 01:38:12 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
--- end of diff output ---

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

22.09.21 01:39:40. There are 3 logs in /var/tmp/logs-oltp30 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-09-22 01:39:43 No severe PSQL-related problems occured
22.09.21 01:39:44. End of report.