Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.07.2022 19: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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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.33
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 16239392 kB
MemAvailable 31005840 kB
Buffers 0 kB
Cached 15460392 kB
SwapCached 2516 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.11
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 2022-07-27 19:38:28
DTS_END                         2022-07-27 19:38:28.9220
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/60970
TRN_ID                          239851
ATT_ID                          724
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: 9289.61 20836 1114753
customer order: creation 169.94 394 20393
customer order: refuse 13.93 388 1671
order to supplier: creation 45.93 1271 5512
order to supplier: removal 3.41 96668 409
invoice (draft): creation 45.24 3087 5429
invoice (draft): removal 53.51 820 6421
invoice accept: apply 41.57 16981 4988
invoice accept: cancel 47.78 715 5734
customer reserve: creation 3873.57 167 464828
customer reserve: removal 4703.80 53 564456
realization accept: apply 83.38 180 10006
realization accept: cancel 80.14 113 9617
payment from customer: creation 58.52 115 7022
payment from customer: removal 5.77 91 692
payment to supplier: creation 47.71 151 5725
payment to supplier: removal 3.31 104 397
service: total inventory turnovers 4.93 1030 592
service: total monetary turnovers 6.27 38 752
service: aggregate perf. data 0.88 21051 105
service: refresh index statistics 0.03 273303 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-07-2022 19:39:15 to 27-07-2022 19:39:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3932
TEST_TIME 2 3853
TEST_TIME 3 3323
TEST_TIME 4 3640
TEST_TIME 5 3673
TEST_TIME 6 4016
TEST_TIME 7 4449
TEST_TIME 8 4927
TEST_TIME 9 5320
TEST_TIME 10 5831
TEST_TIME 11 5662
TEST_TIME 12 6044
TEST_TIME 13 6497
TEST_TIME 14 7010
TEST_TIME 15 7669
TEST_TIME 16 8144
TEST_TIME 17 8274
TEST_TIME 18 8336
TEST_TIME 19 8373
TEST_TIME 20 8509
TEST_TIME 21 8445
TEST_TIME 22 8990
TEST_TIME 23 9151
TEST_TIME 24 9069
TEST_TIME 25 9291
TEST_TIME 26 9026
TEST_TIME 27 9399
TEST_TIME 28 10069
TEST_TIME 29 10166
TEST_TIME 30 10050
TEST_TIME 31 9843
TEST_TIME 32 9723
TEST_TIME 33 9615
TEST_TIME 34 9604
TEST_TIME 35 9502
TEST_TIME 36 9274
TEST_TIME 37 9202
TEST_TIME 38 9309
TEST_TIME 39 9253
TEST_TIME 40 9178
TEST_TIME 41 9146
TEST_TIME 42 9180
TEST_TIME 43 9099
TEST_TIME 44 9103
TEST_TIME 45 9171
TEST_TIME 46 9214
TEST_TIME 47 9222
TEST_TIME 48 9185
TEST_TIME 49 9207
TEST_TIME 50 9317
TEST_TIME 51 9240
TEST_TIME 52 9268
TEST_TIME 53 9375
TEST_TIME 54 9364
TEST_TIME 55 9400
TEST_TIME 56 9421
TEST_TIME 57 9505
TEST_TIME 58 9348
TEST_TIME 59 9368
TEST_TIME 60 9268
TEST_TIME 61 9255
TEST_TIME 62 9208
TEST_TIME 63 9152
TEST_TIME 64 9081
TEST_TIME 65 9050
TEST_TIME 66 9164
TEST_TIME 67 9218
TEST_TIME 68 9227
TEST_TIME 69 9226
TEST_TIME 70 9175
TEST_TIME 71 9268
TEST_TIME 72 9276
TEST_TIME 73 9281
TEST_TIME 74 9332
TEST_TIME 75 9323
TEST_TIME 76 9387
TEST_TIME 77 9388
TEST_TIME 78 9400
TEST_TIME 79 9338
TEST_TIME 80 9270
TEST_TIME 81 9193
TEST_TIME 82 9212
TEST_TIME 83 9239
TEST_TIME 84 9179
TEST_TIME 85 9187
TEST_TIME 86 9155
TEST_TIME 87 9215
TEST_TIME 88 9178
TEST_TIME 89 9205
TEST_TIME 90 9177
TEST_TIME 91 9185
TEST_TIME 92 9242
TEST_TIME 93 9251
TEST_TIME 94 9305
TEST_TIME 95 9303
TEST_TIME 96 9400
TEST_TIME 97 9398
TEST_TIME 98 9371
TEST_TIME 99 9385
TEST_TIME 100 9417
TEST_TIME 101 9420
TEST_TIME 102 9345
TEST_TIME 103 9358
TEST_TIME 104 9322
TEST_TIME 105 9289
TEST_TIME 106 9241
TEST_TIME 107 9227
TEST_TIME 108 9178
TEST_TIME 109 9182
TEST_TIME 110 9153
TEST_TIME 111 9121
TEST_TIME 112 9110
TEST_TIME 113 9146
TEST_TIME 114 9168
TEST_TIME 115 9211
TEST_TIME 116 9220
TEST_TIME 117 9249
TEST_TIME 118 9267
TEST_TIME 119 9330
TEST_TIME 120 9290

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-07-2022 19:39:19 to 27-07-2022 19:39:22.

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 1544981 1544981 0 0.00 0 24046 6 0 0
doc_list_biud 1544981 1544981 0 0.00 0 78457 25 0 0
sp_add_invoice_to_stock 5082 4988 94 1.84 53 169754 16981 0 94
sp_cancel_adding_invoice 5734 5734 0 0.00 4 43814 715 0 0
sp_cancel_client_order 1671 1671 0 0.00 4 18203 388 0 0
sp_cancel_customer_reserve 564456 564456 0 0.00 0 38646 54 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 6597 92 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 6837 105 0 0
sp_cancel_supplier_invoice 6427 6421 6 0.09 1 78464 821 0 6
sp_cancel_supplier_order 409 409 0 0.00 7 823953 96668 0 0
sp_cancel_write_off 9617 9617 0 0.00 1 13434 113 0 0
sp_client_order 20393 20393 0 0.00 3 32385 394 0 0
sp_customer_reserve 464828 464828 0 0.00 0 90700 167 0 0
sp_fill_shopping_cart 31327 31327 0 0.00 1 18973 40 0 0
sp_fill_shopping_cart_clo_res 464827 464827 0 0.00 0 17520 14 0 0
sp_get_clo_for_invoice 4987 4987 0 0.00 0 39772 326 0 0
sp_kill_cost_storno 16440 16440 0 0.00 0 6183 51 0 0
sp_kill_qstorno_ret_qs2qd 1145359 1145359 0 0.00 0 78454 30 0 0
sp_kill_qty_storno 603301 603301 0 0.00 0 78456 64 0 0
sp_lock_selected_doc 20659 20659 0 0.00 0 8376 5 0 0
sp_make_cost_storno 27740 27740 0 0.00 0 21122 50 0 0
sp_multiply_rows_for_pdistr 27740 27740 0 0.00 0 15719 27 0 0
sp_multiply_rows_for_qdistr 20393 20393 0 0.00 0 17678 187 0 0
sp_pay_from_customer 7022 7022 0 0.00 4 22693 115 0 0
sp_pay_to_supplier 5725 5725 0 0.00 4 26699 151 0 0
sp_payment_common 12747 12747 0 0.00 1 13698 30 0 0
sp_qd_handle_on_cancel_clo 1671 1671 0 0.00 0 11569 320 0 0
sp_qd_handle_on_invoice_upd_sts 10721 10721 0 0.00 0 19845 301 0 0
sp_qd_handle_on_reserve_upd_sts 10006 10006 0 0.00 0 18222 58 0 0
sp_reserve_write_off 10006 10006 0 0.00 6 18233 181 0 0
sp_supplier_invoice 5429 5429 0 0.00 6 93519 3088 0 0
sp_supplier_order 5512 5512 0 0.00 5 37575 1271 0 0
srv_aggregate_perf_data 105 105 0 0.00 2210 461974 21051 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 973 160031 46245 0 0
srv_find_qd_qs_mism 1067227 1067227 0 0.00 0 24044 5 0 0
srv_make_invnt_saldo 592 592 0 0.00 38 51697 1030 0 0
srv_make_money_saldo 752 752 0 0.00 1 4032 38 0 0
srv_recalc_idx_stat 4 4 0 0.00 84106 442095 273303 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 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 4988 4988 0 0.00 0 10667 137 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 0 8937 133 0 0
t$perf-norm:sp_cancel_client_order 1671 1671 0 0.00 0 3029 13 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 3279 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 3898 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 1693 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 4353 28 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 30307 1775 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 1198 5 0 0
t$perf-norm:sp_client_order 20393 20393 0 0.00 0 7063 16 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 7127 13 0 0
t$perf-norm:sp_pay_from_customer 7022 7022 0 0.00 0 5468 9 0 0
t$perf-norm:sp_pay_to_supplier 5725 5725 0 0.00 0 7095 13 0 0
t$perf-norm:sp_reserve_write_off 10006 10006 0 0.00 0 15314 13 0 0
t$perf-norm:sp_supplier_invoice 5429 5429 0 0.00 0 11058 17 0 0
t$perf-norm:sp_supplier_order 5512 5512 0 0.00 0 4602 13 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 78 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 18 1 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 3132 29 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 4862 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10162 9974 188 1.85 0 17360 28 0 188
v_all_customers 36480 36480 0 0.00 0 119 1 0 0
v_all_suppliers 30120 30120 0 0.00 0 33 1 0 0
v_all_wares 40786 40786 0 0.00 0 177 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 4423 14 0 0
v_cancel_client_order 3342 3342 0 0.00 0 17060 26 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 3243 14 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 31105 46 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 673 4 0 0
v_cancel_supplier_invoice 800 788 12 1.50 0 5573 24 0 12
v_cancel_supplier_order 818 818 0 0.00 0 2594 7 0 0
v_cancel_write_off 1976 1976 0 0.00 0 5276 20 0 0
v_max_id_clo_ord 5511 5511 0 0.00 0 1062 1 0 0
v_max_id_clo_res 966 966 0 0.00 0 2724 21 0 0
v_max_id_ord_sup 5423 5423 0 0.00 0 8601 6 0 0
v_max_non_paid_invoice 1599 1599 0 0.00 0 5292 7 0 0
v_max_non_paid_realizn 3976 3976 0 0.00 0 8864 7 0 0
v_min_id_clo_ord 5511 5511 0 0.00 0 9114 7 0 0
v_min_id_clo_res 966 966 0 0.00 0 4478 12 0 0
v_min_id_ord_sup 5423 5423 0 0.00 0 7915 9 0 0
v_min_non_paid_invoice 5725 5725 0 0.00 0 13690 23 0 0
v_min_non_paid_realizn 7022 7022 0 0.00 0 12354 19 0 0
v_our_firm 10398 10398 0 0.00 0 16 1 0 0
v_reserve_write_off 20012 20012 0 0.00 0 17309 14 0 0
x_lock_depdocs_on_canc_invoice 11761 11761 0 0.00 0 37817 114 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 11734 359 0 0
x_make_qstorno_1000_1200 5512 5512 0 0.00 1 37356 1200 0 0
x_make_qstorno_1200_2000 5429 5429 0 0.00 3 90996 2935 0 0
x_make_qstorno_2100_3300 464828 464828 0 0.00 0 90699 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-07-2022 19:39:23 to 27-07-2022 19:39:27.

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
17:08:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:33 15158 8192000000 9889531760 10198609920 977458752 10.659 100 90 180 0 1007720992 6090992 24171248
17:10:53 12597 8192000000 9937973408 10259263488 1020926656 11.081 100 87 174 0 1051524080 7902176 22695248
17:12:10 18230 8192000000 9981536224 10311110656 1061890336 11.475 100 92 184 0 1095616176 10254816 23471024
17:13:31 10572 8192000000 10022824496 10367107072 1105286512 11.888 100 79 158 1 1134736128 10709744 18739872
17:14:46 9430 8192000000 10068551472 10422386688 1151664208 12.325 100 76 150 0 1180730000 11298400 17767392
17:16:02 3945 8192000000 10115280544 10483097600 1203265232 12.807 100 72 143 0 1230638672 11699024 15674416
17:17:08 2840 8192000000 10149721808 10525200384 1239768160 13.144 100 86 170 0 1273134768 14759648 18606960
17:18:12 1607 8192000000 10182077312 10562510848 1267897088 13.402 100 89 176 0 1300852912 13055024 19900800
17:19:16 1400 8192000000 10213483536 10598526976 1293019568 13.632 100 86 167 0 1324788272 12930848 18837856
17:20:18 8181 8192000000 10213553824 10608197632 1303948128 13.731 100 71 139 1 1328748784 8652992 16147664
17:21:27 16389 8192000000 10211536016 10618265600 1319261296 13.870 100 33 64 1 1332544976 5812320 7471360
17:22:44 10411 8192000000 10207989008 10620256256 1318532448 13.863 100 18 36 2 1328316960 5692272 4092240
17:23:56 5381 8192000000 10216083056 10638094336 1332150592 13.987 100 30 60 0 1344582000 6186144 6245264
17:25:04 7477 8192000000 10228971216 10644082688 1337585568 14.036 100 48 95 2 1355294208 6325712 11382928
17:26:16 22240 8192000000 10236658720 10656948224 1335521984 14.017 100 16 32 0 1348635664 9820240 3293440
17:27:39 3994 8192000000 10239042128 10656923648 1335664704 14.018 100 39 78 0 1356011568 11903888 8442976
17:28:45 5502 8192000000 10192126912 10602037248 1286322016 13.571 99 14 28 0 1295452336 6037760 3092560
17:29:52 15148 8192000000 10179652880 10581438464 1239861024 13.145 96 31 58 2 1252051456 5139904 7050528
17:31:09 11023 8192000000 10136451408 10544832512 1200192480 12.778 95 23 46 0 1211432704 6386816 4853408
17:32:25 5342 8192000000 10159866432 10566139904 1267131568 13.395 100 19 37 0 1276672208 5444288 4096352
17:33:34 6847 8192000000 10162218928 10562707456 1253640896 13.272 99 23 46 0 1263928144 5193568 5093680
17:34:44 12323 8192000000 10177257200 10588626944 1291481824 13.618 100 32 64 0 1304784784 5394064 7908896
17:35:59 22971 8192000000 10185885952 10598846464 1291399056 13.617 100 30 60 0 1304601328 6572416 6629856
17:37:22 6403 8192000000 10205938416 10611118080 1303093104 13.723 100 30 60 2 1314496640 4988592 6414944
17:38:31 35542 8192000000 10229409264 10642014208 1325795968 13.929 100 25 49 0 1337509824 5778496 5935360
17:40:11 23140 8192000000 10259775008 10672644096 1348002688 14.130 100 46 90 1 1372442064 14385104 10054272
17:41:49 84654 8192000000 10273396048 10701197312 1365248080 14.284 100 29 58 0 1390009184 17516896 7244208
17:44:18 15927 8192000000 10299199680 10719531008 1381044176 14.426 100 51 103 2 1409527552 16555520 11927856
17:45:40 14398 8192000000 10305238320 10737217536 1388833824 14.495 100 43 86 1 1418237472 18692976 10710672
17:47:11 12277 8192000000 10318813520 10743791616 1386418192 14.474 99 59 116 1 1418575200 18605408 13551600
17:48:29 13029 8192000000 10329854112 10761687040 1413063456 14.711 100 44 88 1 1443484848 19782320 10639072
17:49:52 8978 8192000000 10350348096 10770108416 1410542592 14.689 100 69 136 1 1453880032 26143504 17193936
17:51:11 7002 8192000000 10333779776 10755551232 1399033584 14.586 99 54 108 1 1442681248 30105184 13542480
17:52:23 2280 8192000000 10357771632 10769321984 1401724352 14.610 100 87 174 0 1459189024 36087280 21377392
17:53:31 1704 8192000000 10319281344 10734215168 1372034896 14.345 100 76 150 3 1421802512 31382592 18385024
17:54:38 972 8192000000 10313401552 10721128448 1373149824 14.355 100 83 164 0 1416166624 22788288 20228512
17:55:43 1435 8192000000 10313351376 10715852800 1356240592 14.204 99 80 161 1 1401478176 25975040 19262544
17:56:56 6616 8192000000 10323177888 10743365632 1383557920 14.448 100 53 106 1 1427530752 30891584 13081248
17:58:09 2949 8192000000 10323052592 10740613120 1369961600 14.327 99 67 131 1 1416366544 29978832 16426112
17:59:23 4214 8192000000 10326369728 10743468032 1379543392 14.412 100 50 99 0 1426792112 34125040 13123680
18:00:36 6608 8192000000 10319128064 10750222336 1380773120 14.423 100 39 78 0 1426911488 35365408 10772960
18:01:46 16102 8192000000 10293259040 10718621696 1348157696 14.131 98 35 64 0 1384102160 27384848 8559616
18:03:05 26077 8192000000 10303627632 10733322240 1353646208 14.180 99 26 51 0 1391461760 30971424 6844128
18:04:35 31972 8192000000 10293198032 10730827776 1352082336 14.166 100 21 42 0 1398157696 39597616 6477744
18:06:17 11269 8192000000 10294903680 10729345024 1356920000 14.210 100 24 47 0 1395434608 32876832 5637776
18:07:40 9659 8192000000 10274170416 10699407360 1360330112 14.240 100 40 78 0 1373439840 5247008 7862720
18:08:59 118817 8192000000 10272976992 10715807744 1316448752 13.845 96 20 41 1 1328013184 7445328 4119104
18:12:03 62670 8192000000 10272945456 10718322688 1347325824 14.123 98 15 30 0 1366052448 15063360 3663264
18:14:26 88434 8192000000 10282405472 10734669824 1377554064 14.395 100 40 80 0 1402643136 15905968 9183104
18:17:45 60317 8192000000 10281791472 10731835392 1364523744 14.278 99 27 54 1 1382465984 11528768 6413472
18:19:52 76800 8192000000 10296664448 10755391488 1387234208 14.481 100 20 40 0 1408647552 16476272 4937072
18:22:19 48208 8192000000 10294871760 10744549376 1374056832 14.363 99 14 28 0 1394031840 17130336 2844672
18:24:09 113968 8192000000 10286545968 10739769344 1375142512 14.373 99 15 30 0 1390357776 11746944 3468320
18:27:14 44896 8192000000 10302832528 10754969600 1396777296 14.566 100 32 64 0 1417285536 13574064 6934176
18:28:59 54919 8192000000 10330618960 10759176192 1401473632 14.608 100 23 46 0 1418764496 12479616 4811248
18:30:54 19540 8192000000 10316150816 10759819264 1402085968 14.614 100 36 72 0 1421939584 12329488 7524128
18:32:14 96379 8192000000 10293479344 10751033344 1325079152 13.923 94 16 32 0 1338307248 9748576 3479520
18:34:50 69604 8192000000 10305237728 10777329664 1396857424 14.567 100 10 20 0 1420440688 21034720 2548544
18:37:02 48377 8192000000 10282827024 10729537536 1365854128 14.290 99 35 70 0 1382305008 9009392 7441488
18:38:52 16298 8192000000 10277191984 10730897408 1366544880 14.296 99 25 50 0 1381582768 10010800 5027088
18:40:11 41415 8192000000 10261564896 10720845824 1354454176 14.188 98 5 10 0 1361563536 6212736 896624
18:42:26 42010 8192000000 10272326352 10729136128 1368138432 14.310 100 13 26 0 1382854624 11663360 3052832
18:44:45 63068 8192000000 10265743152 10716536832 1330218432 13.969 96 15 30 0 1345995088 12206752 3569904
18:46:49 55687 8192000000 10245270304 10692722688 1313233888 13.815 96 15 28 0 1331160672 14495360 3431424
18:48:51 44275 8192000000 10264773200 10701996032 1331097008 13.977 99 27 54 1 1358024464 20763856 6163600
18:50:45 54499 8192000000 10272554784 10706968576 1344664368 14.099 100 33 65 0 1366396352 14170320 7561664
18:52:48 28261 8192000000 10259701968 10696310784 1339081856 14.049 99 41 80 1 1359930864 12086864 8762144
18:54:17 84139 8192000000 10257520656 10703482880 1302243504 13.716 96 15 30 0 1316229200 10829856 3155840
18:56:47 21707 8192000000 10260081968 10691633152 1324266064 13.915 98 36 70 0 1339698016 7578272 7853680
18:58:09 88290 8192000000 10266702208 10714243072 1359745792 14.235 100 34 68 0 1378468560 10756080 7966688
19:01:31 42476 8192000000 10264442832 10728079360 1363194032 14.266 100 14 27 0 1380500720 14399760 2906928
19:03:17 64804 8192000000 10264580032 10719768576 1344505920 14.098 98 14 28 0 1360864832 13185536 3173376
19:05:28 81306 8192000000 10267753904 10722279424 1326573552 13.936 97 8 16 0 1343658976 15314304 1771120
19:07:50 52225 8192000000 10299262096 10736070656 1365520224 14.287 99 14 28 0 1376061488 7755488 2785776
19:09:48 63184 8192000000 10290710256 10742149120 1357878032 14.218 98 20 40 0 1372724000 10280464 4565504
19:11:54 50810 8192000000 10300858848 10742939648 1367241808 14.302 98 21 41 0 1380039232 8177184 4620240
19:13:50 81319 8192000000 10310991184 10768171008 1370577344 14.332 98 21 42 0 1393987568 18345712 5064512
19:16:13 72781 8192000000 10302471152 10752290816 1363771664 14.271 98 24 47 0 1382965184 14411632 4781888
19:18:26 160030 8192000000 10295854528 10763653120 1336878784 14.029 96 7 14 0 1364202416 26097024 1226608
19:22:18 45486 8192000000 10290355888 10747244544 1367427632 14.304 98 15 30 0 1379241616 8167888 3646096
19:24:05 41183 8192000000 10291697280 10755559424 1393332544 14.536 100 7 14 0 1403176368 8133792 1710032
19:26:19 65245 8192000000 10287926080 10759188480 1396234816 14.561 100 7 14 0 1405988336 8032480 1721040
19:28:40 47016 8192000000 10276074960 10733969408 1323433072 13.908 95 12 24 0 1334963984 8846944 2683968
19:30:48 55221 8192000000 10313369264 10767904768 1364920400 14.282 98 19 38 0 1384403296 14920464 4562432
19:32:47 57568 8192000000 10293430848 10744438784 1363686512 14.270 98 27 54 0 1384431920 14037408 6708000
19:34:45 89014 8192000000 10257852384 10714161152 1292636144 13.628 93 15 30 0 1308708640 12538384 3534112
19:37:14 64272 8192000000 10256674560 10709209088 1285760528 13.566 94 16 31 0 1303038400 14363344 2914528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-07-2022 19:39:29 to 27-07-2022 19:39:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 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:01, from 27-07-2022 19:39:45 to 27-07-2022 19:39:46.

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          239482
MON$OLDEST_ACTIVE               240349
MON$OLDEST_SNAPSHOT             240349
MON$NEXT_TRANSACTION            240349
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                       2003584
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:307: 
:308: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:31 2022
:309:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:310: 
:311: 
:312: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:32 2022
:313:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:314: 
:315: 
:316: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:35 2022
:317:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:318: 
:319: 
:320: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:40 2022
:321:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:322: 
:323: 
:324: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:43 2022
:325:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:326: 
:327: 
:328: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:43 2022
:329:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:330: 
:331: 
:332: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:43 2022
:333:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:334: 
:335: 
:336: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:43 2022
:337:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:338: 
:339: 
:340: mscs-test-161.budzdorov.ru    Wed Jul 27 19:38:43 2022
:341:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:342: 
--- end of diff output ---

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

27.07.22 19:39:48. 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
2022-07-27 19:39:50 No severe PSQL-related problems occured
27.07.22 19:39:51. End of report.