Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2022 04: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 15032664 kB
MemAvailable 30562736 kB
Buffers 20 kB
Cached 16639672 kB
SwapCached 2048 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.3
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 2022-10-01 04:37:20
DTS_END                         2022-10-01 04:37:20.5350
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(68:17)
IP                              ::1/45412
TRN_ID                          221960
ATT_ID                          577
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: 9299.39 24691 1115927
customer order: creation 173.41 465 20809
customer order: refuse 15.21 400 1825
order to supplier: creation 45.91 1358 5509
order to supplier: removal 3.46 170229 415
invoice (draft): creation 47.33 3166 5679
invoice (draft): removal 52.39 1097 6287
invoice accept: apply 42.68 24076 5121
invoice accept: cancel 47.36 1207 5683
customer reserve: creation 3922.83 231 470740
customer reserve: removal 4649.77 97 557973
realization accept: apply 83.16 287 9979
realization accept: cancel 86.03 158 10324
payment from customer: creation 58.51 164 7021
payment from customer: removal 5.41 114 649
payment to supplier: creation 49.86 198 5983
payment to supplier: removal 3.41 115 409
service: total inventory turnovers 4.37 3468 524
service: total monetary turnovers 7.31 347 877
service: aggregate perf. data 0.97 13536 116
service: refresh index statistics 0.03 273108 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-10-2022 04:38:58 to 01-10-2022 04:39:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8315
TEST_TIME 2 6195
TEST_TIME 3 5589
TEST_TIME 4 5945
TEST_TIME 5 5957
TEST_TIME 6 5850
TEST_TIME 7 5977
TEST_TIME 8 6520
TEST_TIME 9 7008
TEST_TIME 10 6778
TEST_TIME 11 7009
TEST_TIME 12 7056
TEST_TIME 13 7046
TEST_TIME 14 7101
TEST_TIME 15 7162
TEST_TIME 16 7331
TEST_TIME 17 7195
TEST_TIME 18 7295
TEST_TIME 19 7491
TEST_TIME 20 7599
TEST_TIME 21 7529
TEST_TIME 22 7607
TEST_TIME 23 7950
TEST_TIME 24 8122
TEST_TIME 25 8371
TEST_TIME 26 8488
TEST_TIME 27 8972
TEST_TIME 28 9191
TEST_TIME 29 9740
TEST_TIME 30 9981
TEST_TIME 31 10048
TEST_TIME 32 10968
TEST_TIME 33 10915
TEST_TIME 34 10862
TEST_TIME 35 11369
TEST_TIME 36 11435
TEST_TIME 37 11369
TEST_TIME 38 11188
TEST_TIME 39 10972
TEST_TIME 40 10746
TEST_TIME 41 10618
TEST_TIME 42 10639
TEST_TIME 43 10605
TEST_TIME 44 10439
TEST_TIME 45 10313
TEST_TIME 46 10254
TEST_TIME 47 10112
TEST_TIME 48 10316
TEST_TIME 49 10230
TEST_TIME 50 10165
TEST_TIME 51 10084
TEST_TIME 52 9977
TEST_TIME 53 9842
TEST_TIME 54 9782
TEST_TIME 55 9891
TEST_TIME 56 9858
TEST_TIME 57 9745
TEST_TIME 58 9840
TEST_TIME 59 9797
TEST_TIME 60 9859
TEST_TIME 61 9834
TEST_TIME 62 9825
TEST_TIME 63 9819
TEST_TIME 64 9835
TEST_TIME 65 9858
TEST_TIME 66 9877
TEST_TIME 67 9937
TEST_TIME 68 9883
TEST_TIME 69 10009
TEST_TIME 70 10002
TEST_TIME 71 9933
TEST_TIME 72 9849
TEST_TIME 73 9743
TEST_TIME 74 9742
TEST_TIME 75 9735
TEST_TIME 76 9684
TEST_TIME 77 9625
TEST_TIME 78 9569
TEST_TIME 79 9493
TEST_TIME 80 9448
TEST_TIME 81 9486
TEST_TIME 82 9473
TEST_TIME 83 9426
TEST_TIME 84 9487
TEST_TIME 85 9468
TEST_TIME 86 9494
TEST_TIME 87 9459
TEST_TIME 88 9451
TEST_TIME 89 9452
TEST_TIME 90 9431
TEST_TIME 91 9520
TEST_TIME 92 9473
TEST_TIME 93 9653
TEST_TIME 94 9654
TEST_TIME 95 9636
TEST_TIME 96 9599
TEST_TIME 97 9649
TEST_TIME 98 9617
TEST_TIME 99 9625
TEST_TIME 100 9609
TEST_TIME 101 9617
TEST_TIME 102 9614
TEST_TIME 103 9689
TEST_TIME 104 9752
TEST_TIME 105 9688
TEST_TIME 106 9617
TEST_TIME 107 9594
TEST_TIME 108 9537
TEST_TIME 109 9526
TEST_TIME 110 9483
TEST_TIME 111 9484
TEST_TIME 112 9458
TEST_TIME 113 9407
TEST_TIME 114 9356
TEST_TIME 115 9333
TEST_TIME 116 9428
TEST_TIME 117 9441
TEST_TIME 118 9413
TEST_TIME 119 9382
TEST_TIME 120 9327

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2022 04:39:02 to 01-10-2022 04:39:05.

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 1549843 1549843 0 0.00 0 25460 13 0 0
doc_list_biud 1549832 1549832 0 0.00 0 63484 41 0 0
sp_add_invoice_to_stock 5199 5121 78 1.50 39 182942 24076 0 78
sp_cancel_adding_invoice 5683 5683 0 0.00 7 72087 1208 0 0
sp_cancel_client_order 1825 1825 0 0.00 6 13110 400 0 0
sp_cancel_customer_reserve 557973 557973 0 0.00 1 32962 97 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 8251 114 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 1957 115 0 0
sp_cancel_supplier_invoice 6293 6287 6 0.09 3 56997 1098 0 6
sp_cancel_supplier_order 415 415 0 0.00 10 1504588 170229 0 0
sp_cancel_write_off 10324 10324 0 0.00 2 28236 159 0 0
sp_client_order 20809 20809 0 0.00 5 22474 465 0 0
sp_customer_reserve 470740 470740 0 0.00 0 92527 232 0 0
sp_fill_shopping_cart 31987 31987 0 0.00 1 18562 74 0 0
sp_fill_shopping_cart_clo_res 470706 470706 0 0.00 0 12353 15 0 0
sp_get_clo_for_invoice 5078 5078 0 0.00 0 14800 240 0 0
sp_kill_cost_storno 17065 17065 0 0.00 0 14452 71 0 0
sp_kill_qstorno_ret_qs2qd 1132972 1132972 0 0.00 0 56955 42 0 0
sp_kill_qty_storno 597562 597562 0 0.00 0 63482 98 0 0
sp_lock_selected_doc 20834 20834 0 0.00 0 15582 8 0 0
sp_make_cost_storno 28060 28060 0 0.00 0 22034 80 0 0
sp_multiply_rows_for_pdistr 28059 28059 0 0.00 0 9070 38 0 0
sp_multiply_rows_for_qdistr 20809 20809 0 0.00 0 10756 185 0 0
sp_pay_from_customer 7021 7021 0 0.00 5 11091 165 0 0
sp_pay_to_supplier 5983 5983 0 0.00 7 14363 199 0 0
sp_payment_common 13003 13003 0 0.00 2 10250 48 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 13048 312 0 0
sp_qd_handle_on_invoice_upd_sts 10760 10760 0 0.00 0 63478 496 0 0
sp_qd_handle_on_reserve_upd_sts 9978 9978 0 0.00 0 13168 63 0 0
sp_reserve_write_off 9979 9979 0 0.00 8 23227 288 0 0
sp_supplier_invoice 5679 5679 0 0.00 8 117371 3167 0 0
sp_supplier_order 5509 5509 0 0.00 8 36662 1359 0 0
srv_aggregate_perf_data 116 116 0 0.00 2243 128419 13536 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 723 179465 36299 0 0
srv_find_qd_qs_mism 1066679 1066679 0 0.00 0 25458 10 0 0
srv_make_invnt_saldo 524 524 0 0.00 38 66583 3468 0 0
srv_make_money_saldo 877 877 0 0.00 1 14971 347 0 0
srv_recalc_idx_stat 4 4 0 0.00 77689 517571 273108 0 0
t$perf-abend:sp_add_invoice_to_stock 78 78 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 5126 5126 0 0.00 0 15204 802 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 2 7041 444 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 4541 31 0 0
t$perf-norm:sp_cancel_customer_reserve 724 724 0 0.00 0 4141 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 657 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 4280 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 390 390 0 0.00 0 928 29 0 0
t$perf-norm:sp_cancel_supplier_order 415 415 0 0.00 0 80367 6598 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 2574 23 0 0
t$perf-norm:sp_client_order 20809 20809 0 0.00 0 14046 36 0 0
t$perf-norm:sp_customer_reserve 1043 1043 0 0.00 0 1206 16 0 0
t$perf-norm:sp_pay_from_customer 7022 7022 0 0.00 0 6523 19 0 0
t$perf-norm:sp_pay_to_supplier 5984 5984 0 0.00 0 12555 18 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 5288 22 0 0
t$perf-norm:sp_supplier_invoice 5679 5679 0 0.00 0 3670 26 0 0
t$perf-norm:sp_supplier_order 5509 5509 0 0.00 0 5907 28 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 627 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 257 6 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 3440 20 0 0
t$perf-norm:srv_make_money_saldo 877 877 0 0.00 0 848 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 191 68 0 0
v_add_invoice_to_stock 10303 10147 156 1.51 0 34796 26 0 156
v_all_customers 38333 38333 0 0.00 0 147 3 0 0
v_all_suppliers 31528 31528 0 0.00 0 145 4 0 0
v_all_wares 41606 41606 0 0.00 0 143 4 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 848 14 0 0
v_cancel_client_order 3650 3650 0 0.00 0 2467 11 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 2092 10 0 0
v_cancel_customer_reserve 1448 1448 0 0.00 1 3051 13 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 1710 13 0 0
v_cancel_supplier_invoice 792 780 12 1.51 0 1742 19 0 12
v_cancel_supplier_order 830 830 0 0.00 0 1338 13 0 0
v_cancel_write_off 1930 1930 0 0.00 0 2942 25 0 0
v_max_id_clo_ord 5507 5507 0 0.00 0 1228 5 0 0
v_max_id_clo_res 1043 1043 0 0.00 0 1387 16 0 0
v_max_id_ord_sup 5675 5675 0 0.00 0 4177 6 0 0
v_max_non_paid_invoice 1400 1400 0 0.00 0 312 7 0 0
v_max_non_paid_realizn 3481 3481 0 0.00 0 2616 8 0 0
v_min_id_clo_ord 5507 5507 0 0.00 0 1530 7 0 0
v_min_id_clo_res 1043 1043 0 0.00 0 6900 20 0 0
v_min_id_ord_sup 5675 5675 0 0.00 0 6079 14 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 9993 19 0 0
v_min_non_paid_realizn 7020 7020 0 0.00 0 9490 21 0 0
v_our_firm 10352 10352 0 0.00 0 62 4 0 0
v_reserve_write_off 19944 19944 0 0.00 0 23168 14 0 0
x_lock_depdocs_on_canc_invoice 11580 11580 0 0.00 0 14019 120 0 0
x_lock_depdocs_on_canc_sup_ord 415 415 0 0.00 0 9187 403 0 0
x_make_qstorno_1000_1200 5508 5508 0 0.00 2 36649 1209 0 0
x_make_qstorno_1200_2000 5679 5679 0 0.00 4 113685 2961 0 0
x_make_qstorno_2100_3300 470738 470738 0 0.00 0 92517 205 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-10-2022 04:39:06 to 01-10-2022 04:39:11.

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
02:07:24 3 8192000000 8798504736 8809730048 4525216 0.055 2 1 2 0 4624640 63648 35776
02:08:25 31403 8192000000 9716547648 10015051776 793369216 8.829 100 87 174 0 822487440 7764240 21353984
02:10:00 24750 8192000000 9771654912 10077683712 839463744 9.294 100 94 188 0 874684512 12948544 22272224
02:11:32 14135 8192000000 9818180768 10139893760 871596000 9.616 100 78 155 0 924323424 35000592 17726832
02:12:54 5145 8192000000 9882228352 10216275968 923070528 10.126 100 80 159 0 993023280 53399520 16553232
02:14:05 3733 8192000000 9935702688 10282713088 969563424 10.582 100 89 175 0 1052065152 63945456 18556272
02:15:14 1895 8192000000 9986874016 10342694912 1001850544 10.896 100 89 176 1 1109208416 89572912 17784960
02:16:18 3251 8192000000 9994220848 10358972416 1019475840 11.067 100 75 145 0 1107602144 73881664 14244640
02:17:23 862 8192000000 10030145184 10396332032 1046822496 11.330 100 96 187 0 1150252224 85506000 17923728
02:18:27 5437 8192000000 10031760688 10407079936 1061466528 11.471 100 83 157 1 1160120272 83309312 15344432
02:19:34 1063 8192000000 10039219136 10413072384 1066257296 11.516 100 95 182 2 1154721424 70981472 17482656
02:20:36 1070 8192000000 10060228448 10444328960 1071203776 11.564 100 93 180 0 1180411760 91188928 18019056
02:21:38 1141 8192000000 10041850064 10421993472 1079581280 11.643 100 96 184 0 1166897200 68057488 19258432
02:22:40 959 8192000000 10059534896 10447294464 1084332816 11.689 100 94 176 1 1183940800 82525776 17082208
02:23:42 1212 8192000000 10045635104 10434187264 1087279152 11.717 100 98 189 0 1166015296 60292256 18443888
02:24:45 851 8192000000 10054333296 10442989568 1094627840 11.787 100 86 157 0 1173370752 62913216 15829696
02:25:47 1775 8192000000 10048155056 10438975488 1092591328 11.767 100 81 157 1 1169704576 60304224 16809024
02:26:49 830 8192000000 10046334400 10433298432 1090721872 11.750 100 91 170 3 1169507824 61760048 17025904
02:27:52 1174 8192000000 10070215152 10458001408 1091678192 11.759 100 93 174 2 1190770240 81121408 17970640
02:28:54 1183 8192000000 10026505360 10411569152 1077790304 11.626 99 77 146 2 1150005936 58422896 13792736
02:29:56 2403 8192000000 10023860032 10405928960 1074502176 11.595 100 83 157 3 1145060544 55925248 14633120
02:30:59 9798 8192000000 10012125552 10409766912 1051212912 11.372 99 54 104 1 1123484224 61965456 10305856
02:32:11 1143 8192000000 10005546592 10380926976 1045735424 11.320 100 97 186 0 1125984192 60726656 19522112
02:33:12 903 8192000000 9977441552 10350280704 1038025168 11.246 100 93 177 1 1101092800 45044816 18022816
02:34:15 1208 8192000000 9989042592 10371391488 1034014288 11.207 99 74 139 1 1113539488 64525936 14999264
02:35:18 1056 8192000000 9985276768 10366152704 1033503296 11.202 100 81 152 0 1112477904 62811104 16163504
02:36:20 3507 8192000000 10017253760 10400980992 1048402224 11.345 100 69 136 1 1134751376 71852416 14496736
02:37:25 5681 8192000000 9995118064 10378715136 1046428832 11.326 100 67 134 0 1128093888 67885712 13779344
02:38:41 1027 8192000000 10023886016 10400923648 1074781472 11.598 100 83 149 3 1141359056 50078208 16499376
02:39:49 6681 8192000000 10043725680 10428649472 1091375552 11.756 100 75 133 2 1164779216 59122512 14281152
02:41:00 5183 8192000000 10091336496 10490363904 1107217536 11.906 100 70 138 1 1214569056 92129920 15221600
02:42:10 1649 8192000000 10144797072 10532139008 1117269232 12.001 100 83 162 0 1261942640 127526192 17147216
02:43:15 7746 8192000000 10155984448 10560282624 1123377392 12.059 100 60 116 1 1279156640 142446400 13332848
02:44:30 1150 8192000000 10199728160 10597277696 1139654112 12.212 100 89 162 3 1316635696 158284720 18696864
02:45:38 1721 8192000000 10200880288 10595999744 1146875184 12.280 100 90 165 3 1311473472 146982544 17615744
02:46:43 1399 8192000000 10210762576 10617913344 1155671424 12.363 100 91 171 0 1326822992 152598864 18552704
02:47:49 4241 8192000000 10256820128 10663202816 1159447040 12.398 100 80 154 1 1373309824 196850512 17012272
02:48:56 1721 8192000000 10289558464 10689732608 1179368800 12.584 100 87 169 1 1397235984 199239584 18627600
02:50:01 1607 8192000000 10337496048 10748317696 1199344272 12.770 100 95 185 0 1448526848 228424896 20757680
02:51:05 1128 8192000000 10393075472 10797580288 1205841504 12.831 100 94 179 0 1502277648 275493504 20942640
02:52:10 1238 8192000000 10406498112 10818846720 1212115392 12.889 100 86 169 2 1511566960 278860208 20591360
02:53:14 909 8192000000 10457464560 10879336448 1218142912 12.944 100 97 182 1 1562584864 322677840 21764112
02:54:17 2542 8192000000 10491632016 10922496000 1221213808 12.973 100 87 172 0 1595338096 353172976 20951312
02:55:24 999 8192000000 10535918336 10962272256 1212709488 12.894 100 90 179 1 1643213648 409480560 21023600
02:56:27 743 8192000000 10566302560 10989379584 1214860080 12.914 100 95 184 0 1672493024 435548752 22084192
02:57:30 4023 8192000000 10571593344 11013218304 1201073616 12.786 99 71 140 0 1676681056 457721824 17885616
02:58:38 712 8192000000 10653825856 11071401984 1206432512 12.836 100 96 188 1 1759184896 529795584 22956800
02:59:42 1727 8192000000 10652199392 11114532864 1202801088 12.802 100 70 139 0 1760795824 540199552 17795184
03:00:52 7391 8192000000 10639847344 11076792320 1192270880 12.704 100 49 98 0 1737259024 531807808 13180336
03:02:06 14963 8192000000 10585969136 11074609152 1179971936 12.590 99 55 110 1 1684346832 490001152 14373744
03:03:27 4995 8192000000 10580372128 11011137536 1173373216 12.528 100 47 94 0 1690358560 504448400 12536944
03:04:39 15048 8192000000 10514439072 10998890496 1163084720 12.432 100 42 84 0 1643919584 469361168 11473696
03:06:07 21364 8192000000 10432940288 10986999808 1155797728 12.364 100 35 70 0 1610359008 445359040 9202240
03:07:30 35892 8192000000 10311466416 10776834048 1094562416 11.786 96 26 51 0 1400913264 299829616 6521232
03:09:08 18967 8192000000 10181395184 10669899776 1136756192 12.185 100 34 68 0 1324816672 180007888 8052592
03:10:39 65819 8192000000 10196712192 10663370752 1141660176 12.231 99 24 48 0 1285326384 138431504 5234704
03:12:45 49112 8192000000 10101289824 10619891712 1151632192 12.325 100 13 26 1 1221641104 67593904 2415008
03:14:35 110354 8192000000 10068548144 10501767168 1111194544 11.944 96 19 35 0 1154179920 38966736 4018640
03:17:38 61464 8192000000 10127147104 10561507328 1127389424 12.097 99 49 96 0 1217087200 78572528 11125248
03:19:48 152624 8192000000 10092303808 10527698944 1105816256 11.893 96 34 61 1 1169403536 57724128 5863152
03:23:22 62667 8192000000 10112678320 10540728320 1124611632 12.071 99 31 54 0 1205000096 74547872 5840592
03:25:29 62914 8192000000 10041644176 10527866880 1115241440 11.982 99 9 17 0 1154822384 38087600 1493344
03:27:32 33651 8192000000 10060983632 10533072896 1104570704 11.881 98 11 22 0 1143169056 36410416 2187936
03:29:07 42699 8192000000 10117189360 10559930368 1128197296 12.104 99 34 67 0 1194208960 58857232 7154432
03:30:56 83761 8192000000 10150826704 10621427712 1138310432 12.200 100 32 63 0 1256808960 111900096 6598432
03:33:24 46625 8192000000 10083975280 10583138304 1118709760 12.015 98 25 45 0 1184023920 60879648 4434512
03:35:11 58283 8192000000 10094567200 10550853632 1119322016 12.021 98 14 28 0 1163843184 41219136 3302032
03:37:15 29085 8192000000 10111148896 10548490240 1133062144 12.150 99 39 76 1 1191603904 51107536 7434224
03:38:45 12837 8192000000 10094008496 10532503552 1147444448 12.286 100 39 79 0 1183271072 27497968 8328656
03:40:02 20275 8192000000 10119337568 10563383296 1140366496 12.219 99 45 90 0 1212836096 62548144 9921456
03:41:23 7079 8192000000 10149955696 10578665472 1155760608 12.364 100 60 116 2 1237929936 70436512 11732816
03:42:40 28470 8192000000 10143245840 10592149504 1156602480 12.371 100 46 87 0 1233749664 67253232 9893952
03:44:11 36869 8192000000 10093485280 10526097408 1110459120 11.937 97 26 50 0 1188756368 72446272 5850976
03:45:49 48962 8192000000 10129497520 10633297920 1130043248 12.122 99 42 84 0 1237709200 98813344 8852608
03:47:38 38653 8192000000 10099074384 10542313472 1133345728 12.153 99 30 61 1 1177788976 38321360 6121888
03:49:17 101039 8192000000 10091537344 10547445760 1147631472 12.287 100 49 96 1 1210561120 51860032 11069616
03:51:59 64076 8192000000 10067066752 10541744128 1126124592 12.085 98 8 16 0 1168126928 40460176 1542160
03:54:06 53675 8192000000 10057212144 10528346112 1112460112 11.956 97 9 17 0 1136773328 22575424 1737792
03:56:01 88247 8192000000 10083306960 10537754624 1116507680 11.994 98 11 22 1 1163563424 44300208 2755536
03:58:35 37594 8192000000 10084467744 10546843648 1118283808 12.011 99 23 46 0 1183287792 59510608 5493376
04:00:13 22578 8192000000 10078380320 10517966848 1097884800 11.818 97 23 46 0 1150317600 47425920 5006880
04:01:37 34441 8192000000 10135965248 10579050496 1116198000 11.991 98 39 78 0 1214179344 89631216 8350128
04:03:25 25202 8192000000 10141586304 10574417920 1103382640 11.870 97 35 69 1 1207776416 96842400 7551376
04:04:57 10923 8192000000 10164195072 10588778496 1117572896 12.004 99 55 109 1 1242959856 113202576 12184384
04:06:09 28831 8192000000 10203262896 10670948352 1123305760 12.058 99 41 76 0 1298899632 167406064 8187808
04:07:51 46527 8192000000 10210761296 10663817216 1106850752 11.903 98 24 48 0 1327498992 214935792 5712448
04:09:38 44470 8192000000 10141957216 10666774528 1101279664 11.850 98 25 50 1 1278854528 171850016 5724848
04:11:35 78012 8192000000 10057452048 10485817344 1102107232 11.858 98 27 53 0 1138863760 31099024 5657504
04:13:53 61976 8192000000 10066799392 10525941760 1109817744 11.931 98 37 72 0 1175394768 58279536 7297488
04:15:56 126411 8192000000 10108136816 10560958464 1113141856 11.962 98 20 38 0 1143617760 26415248 4060656
04:19:09 57935 8192000000 10137023104 10589143040 1108348384 11.917 97 25 50 0 1169408528 55617264 5442880
04:21:08 179465 8192000000 10061281984 10506440704 1104612304 11.881 97 13 26 0 1177898240 70109472 3176464
04:25:11 34267 8192000000 10105254048 10549055488 1136862336 12.186 100 45 89 0 1195221952 49162688 9196928
04:26:47 55938 8192000000 10131732704 10608508928 1135449120 12.173 99 17 34 0 1228959120 89331712 4178288
04:28:44 85395 8192000000 10139475072 10612199424 1142992768 12.244 100 21 41 0 1234306512 86952432 4361312
04:32:47 36517 8192000000 10147270304 10612006912 1115793984 11.987 98 49 94 4 1256809312 130257984 10757344
04:34:30 65175 8192000000 10065865952 10527420416 1080980128 11.657 95 15 30 0 1117996160 33631664 3384368
04:36:43 13680 8192000000 10140236768 10587615232 1141214208 12.227 100 60 112 1 1226337792 73496800 11626784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 01-10-2022 04:39:12 to 01-10-2022 04:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 78 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 01-10-2022 04:39:30 to 01-10-2022 04:39:32.

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          221450
MON$OLDEST_ACTIVE               224376
MON$OLDEST_SNAPSHOT             224376
MON$NEXT_TRANSACTION            224376
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                       2086176
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:169: 
:170: mscs-test-161.budzdorov.ru    Sat Oct  1 04:37:34 2022
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Sat Oct  1 04:37:35 2022
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Sat Oct  1 04:37:35 2022
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
:181: 
:182: mscs-test-161.budzdorov.ru    Sat Oct  1 04:37:35 2022
:183:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:184: 
--- end of diff output ---

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

01.10.22 04:39:33. 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
2022-10-01 04:39:35 No severe PSQL-related problems occured
01.10.22 04:39:36. End of report.