Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2022 01:39
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 15533340 kB
MemAvailable 30620824 kB
Buffers 0 kB
Cached 16209180 kB
SwapCached 2032 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-09-27 01:38:28
DTS_END                         2022-09-27 01:38:28.0330
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/52002
TRN_ID                          237360
ATT_ID                          635
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: 9498.12 24461 1139774
customer order: creation 170.49 351 20459
customer order: refuse 14.83 363 1779
order to supplier: creation 44.96 1316 5395
order to supplier: removal 3.60 101709 432
invoice (draft): creation 45.86 3647 5503
invoice (draft): removal 55.28 801 6634
invoice accept: apply 41.53 16671 4983
invoice accept: cancel 50.44 833 6053
customer reserve: creation 3804.48 166 456537
customer reserve: removal 4978.61 58 597433
realization accept: apply 83.64 201 10037
realization accept: cancel 79.40 117 9528
payment from customer: creation 57.58 109 6909
payment from customer: removal 5.59 76 671
payment to supplier: creation 47.05 125 5646
payment to supplier: removal 3.67 83 440
service: total inventory turnovers 4.42 1178 530
service: total monetary turnovers 5.87 43 704
service: aggregate perf. data 0.81 22958 97
service: refresh index statistics 0.03 338422 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2022 01:40:11 to 27-09-2022 01:40:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3545
TEST_TIME 2 3891
TEST_TIME 3 4510
TEST_TIME 4 5007
TEST_TIME 5 4868
TEST_TIME 6 4930
TEST_TIME 7 4589
TEST_TIME 8 5059
TEST_TIME 9 5290
TEST_TIME 10 5518
TEST_TIME 11 5744
TEST_TIME 12 5781
TEST_TIME 13 6175
TEST_TIME 14 6423
TEST_TIME 15 6989
TEST_TIME 16 7606
TEST_TIME 17 7633
TEST_TIME 18 8260
TEST_TIME 19 8744
TEST_TIME 20 9254
TEST_TIME 21 9328
TEST_TIME 22 9334
TEST_TIME 23 9327
TEST_TIME 24 9713
TEST_TIME 25 9889
TEST_TIME 26 10094
TEST_TIME 27 10779
TEST_TIME 28 11239
TEST_TIME 29 11035
TEST_TIME 30 10830
TEST_TIME 31 10705
TEST_TIME 32 10484
TEST_TIME 33 10580
TEST_TIME 34 10546
TEST_TIME 35 10472
TEST_TIME 36 10498
TEST_TIME 37 10345
TEST_TIME 38 10328
TEST_TIME 39 10254
TEST_TIME 40 10299
TEST_TIME 41 10242
TEST_TIME 42 10252
TEST_TIME 43 10146
TEST_TIME 44 10041
TEST_TIME 45 10165
TEST_TIME 46 10134
TEST_TIME 47 10073
TEST_TIME 48 10204
TEST_TIME 49 10121
TEST_TIME 50 10140
TEST_TIME 51 10130
TEST_TIME 52 10160
TEST_TIME 53 10105
TEST_TIME 54 10119
TEST_TIME 55 10109
TEST_TIME 56 10035
TEST_TIME 57 10125
TEST_TIME 58 10112
TEST_TIME 59 10190
TEST_TIME 60 10217
TEST_TIME 61 10189
TEST_TIME 62 10035
TEST_TIME 63 10090
TEST_TIME 64 9977
TEST_TIME 65 9929
TEST_TIME 66 9940
TEST_TIME 67 9876
TEST_TIME 68 9823
TEST_TIME 69 9746
TEST_TIME 70 9739
TEST_TIME 71 9726
TEST_TIME 72 9722
TEST_TIME 73 9804
TEST_TIME 74 9740
TEST_TIME 75 9742
TEST_TIME 76 9796
TEST_TIME 77 9724
TEST_TIME 78 9708
TEST_TIME 79 9793
TEST_TIME 80 9890
TEST_TIME 81 9883
TEST_TIME 82 9847
TEST_TIME 83 9898
TEST_TIME 84 9902
TEST_TIME 85 9938
TEST_TIME 86 9982
TEST_TIME 87 9924
TEST_TIME 88 9824
TEST_TIME 89 9822
TEST_TIME 90 9811
TEST_TIME 91 9806
TEST_TIME 92 9798
TEST_TIME 93 9769
TEST_TIME 94 9716
TEST_TIME 95 9676
TEST_TIME 96 9628
TEST_TIME 97 9655
TEST_TIME 98 9724
TEST_TIME 99 9703
TEST_TIME 100 9740
TEST_TIME 101 9822
TEST_TIME 102 9808
TEST_TIME 103 9768
TEST_TIME 104 9798
TEST_TIME 105 9818
TEST_TIME 106 9807
TEST_TIME 107 9842
TEST_TIME 108 9848
TEST_TIME 109 9813
TEST_TIME 110 9741
TEST_TIME 111 9742
TEST_TIME 112 9726
TEST_TIME 113 9724
TEST_TIME 114 9671
TEST_TIME 115 9642
TEST_TIME 116 9605
TEST_TIME 117 9594
TEST_TIME 118 9529
TEST_TIME 119 9516
TEST_TIME 120 9499

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-09-2022 01:40:14 to 27-09-2022 01:40:18.

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 1563649 1563649 0 0.00 0 19639 6 0 0
doc_list_biud 1563649 1563649 0 0.00 0 55633 28 0 0
sp_add_invoice_to_stock 5104 4983 121 2.37 37 150624 16672 0 121
sp_cancel_adding_invoice 6053 6053 0 0.00 3 51423 833 0 0
sp_cancel_client_order 1779 1779 0 0.00 4 11605 363 0 0
sp_cancel_customer_reserve 597433 597433 0 0.00 0 49605 58 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 4528 76 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 2 4981 84 0 0
sp_cancel_supplier_invoice 6641 6634 7 0.10 1 55642 801 0 7
sp_cancel_supplier_order 432 432 0 0.00 10 1078681 101710 0 0
sp_cancel_write_off 9528 9528 0 0.00 1 16304 117 0 0
sp_client_order 20459 20459 0 0.00 3 14308 351 0 0
sp_customer_reserve 456537 456537 0 0.00 0 39157 166 0 0
sp_fill_shopping_cart 31333 31333 0 0.00 1 10362 37 0 0
sp_fill_shopping_cart_clo_res 456531 456531 0 0.00 0 15612 14 0 0
sp_get_clo_for_invoice 4976 4976 0 0.00 0 21987 292 0 0
sp_kill_cost_storno 16692 16692 0 0.00 0 15867 57 0 0
sp_kill_qstorno_ret_qs2qd 1211460 1211460 0 0.00 0 55631 32 0 0
sp_kill_qty_storno 636870 636870 0 0.00 0 55632 69 0 0
sp_lock_selected_doc 20877 20877 0 0.00 0 7791 6 0 0
sp_make_cost_storno 27566 27566 0 0.00 0 19639 54 0 0
sp_multiply_rows_for_pdistr 27566 27566 0 0.00 0 9842 31 0 0
sp_multiply_rows_for_qdistr 20459 20459 0 0.00 0 14299 172 0 0
sp_pay_from_customer 6909 6909 0 0.00 4 14809 110 0 0
sp_pay_to_supplier 5646 5646 0 0.00 5 19643 125 0 0
sp_payment_common 12555 12555 0 0.00 1 14555 34 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 11598 332 0 0
sp_qd_handle_on_invoice_upd_sts 11027 11027 0 0.00 0 38704 336 0 0
sp_qd_handle_on_reserve_upd_sts 10037 10037 0 0.00 0 17401 64 0 0
sp_reserve_write_off 10037 10037 0 0.00 6 17417 201 0 0
sp_supplier_invoice 5503 5503 0 0.00 6 108573 3648 0 0
sp_supplier_order 5395 5395 0 0.00 5 77305 1317 0 0
srv_aggregate_perf_data 97 97 0 0.00 2835 599058 22958 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1510 105064 43432 0 0
srv_find_qd_qs_mism 1093440 1093440 0 0.00 0 17670 5 0 0
srv_make_invnt_saldo 530 530 0 0.00 44 84809 1178 0 0
srv_make_money_saldo 704 704 0 0.00 1 7055 43 0 0
srv_recalc_idx_stat 4 4 0 0.00 204659 477052 338423 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4983 4983 0 0.00 0 15059 158 0 0
t$perf-norm:sp_cancel_adding_invoice 462 462 0 0.00 0 7673 126 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 6341 13 0 0
t$perf-norm:sp_cancel_customer_reserve 717 717 0 0.00 0 3698 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 667 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 247 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 4621 30 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 35228 1875 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 5117 22 0 0
t$perf-norm:sp_client_order 20459 20459 0 0.00 0 28419 22 0 0
t$perf-norm:sp_customer_reserve 1005 1005 0 0.00 0 18157 22 0 0
t$perf-norm:sp_pay_from_customer 6909 6909 0 0.00 0 5761 14 0 0
t$perf-norm:sp_pay_to_supplier 5646 5646 0 0.00 0 9326 17 0 0
t$perf-norm:sp_reserve_write_off 10037 10037 0 0.00 0 7110 12 0 0
t$perf-norm:sp_supplier_invoice 5503 5503 0 0.00 0 5179 16 0 0
t$perf-norm:sp_supplier_order 5395 5395 0 0.00 0 8773 20 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 616 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5438 99 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 1803 18 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 8738 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 55 16 0 0
v_add_invoice_to_stock 10184 9942 242 2.37 0 5572 18 0 242
v_all_customers 36808 36808 0 0.00 0 19 1 0 0
v_all_suppliers 29956 29956 0 0.00 0 31 1 0 0
v_all_wares 40918 40918 0 0.00 0 93 1 0 0
v_cancel_adding_invoice 924 924 0 0.00 0 3383 17 0 0
v_cancel_client_order 3558 3558 0 0.00 0 4447 6 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 741 5 0 0
v_cancel_customer_reserve 1434 1434 0 0.00 0 3829 7 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 2562 8 0 0
v_cancel_supplier_invoice 830 816 14 1.68 0 2006 19 0 14
v_cancel_supplier_order 864 864 0 0.00 0 3764 8 0 0
v_cancel_write_off 1920 1920 0 0.00 0 8384 20 0 0
v_max_id_clo_ord 5395 5395 0 0.00 0 1046 2 0 0
v_max_id_clo_res 1005 1005 0 0.00 0 2718 19 0 0
v_max_id_ord_sup 5479 5479 0 0.00 0 2901 4 0 0
v_max_non_paid_invoice 1542 1542 0 0.00 0 1249 3 0 0
v_max_non_paid_realizn 3770 3770 0 0.00 0 4461 7 0 0
v_min_id_clo_ord 5395 5395 0 0.00 0 4207 8 0 0
v_min_id_clo_res 1005 1005 0 0.00 0 9660 18 0 0
v_min_id_ord_sup 5479 5479 0 0.00 0 6822 9 0 0
v_min_non_paid_invoice 5646 5646 0 0.00 0 9643 23 0 0
v_min_non_paid_realizn 6909 6909 0 0.00 0 14547 29 0 0
v_our_firm 10388 10388 0 0.00 0 99 1 0 0
v_reserve_write_off 20074 20074 0 0.00 0 5612 7 0 0
x_lock_depdocs_on_canc_invoice 12279 12279 0 0.00 0 14512 117 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 16979 451 0 0
x_make_qstorno_1000_1200 5395 5395 0 0.00 2 73918 1238 0 0
x_make_qstorno_1200_2000 5503 5503 0 0.00 3 108344 3511 0 0
x_make_qstorno_2100_3300 456537 456537 0 0.00 0 39133 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2022 01:40:19 to 27-09-2022 01:40:23.

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:08:31 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:32 31239 8192000000 9909397984 10222039040 988118672 10.763 100 92 184 0 1018425408 6006512 24300224
23:11:11 30165 8192000000 9953021760 10276433920 1034301664 11.210 100 90 180 0 1066329104 8742560 23284880
23:12:47 6836 8192000000 9978372048 10313113600 1068716928 11.540 100 78 156 0 1098036800 9561376 19758496
23:14:06 10868 8192000000 10037975872 10393071616 1129585552 12.117 100 67 134 0 1155798304 9829664 16383088
23:15:20 6122 8192000000 10086476528 10449268736 1177255440 12.565 100 78 156 0 1207732352 12045776 18431136
23:16:30 2725 8192000000 10131902496 10500972544 1226167904 13.019 100 80 159 0 1255278976 12010272 17100800
23:17:35 2026 8192000000 10171123776 10548146176 1257669776 13.309 100 93 184 0 1291122480 12908448 20544256
23:18:38 1298 8192000000 10197359008 10579619840 1282632480 13.537 100 84 169 0 1313208400 12051392 18524528
23:19:40 1703 8192000000 10198247648 10600701952 1300967216 13.704 100 65 130 0 1327271568 11964848 14339504
23:20:47 19566 8192000000 10191450656 10610102272 1305279280 13.743 100 27 52 0 1321436176 10008848 6148048
23:22:07 10455 8192000000 10196882064 10614407168 1314814528 13.830 100 19 38 0 1325161904 6211888 4135488
23:23:20 13329 8192000000 10212280992 10629124096 1324159680 13.914 100 10 18 1 1331162416 5265968 1736768
23:24:34 23611 8192000000 10218804496 10644701184 1332897328 13.993 100 25 48 0 1345320496 6412784 6010384
23:26:16 7739 8192000000 10233742528 10655531008 1341708528 14.073 100 29 58 0 1355448416 7205840 6534048
23:27:24 6697 8192000000 10221924496 10648657920 1329695040 13.964 99 22 45 0 1340292688 5668480 4929168
23:28:31 25404 8192000000 10188160576 10608193536 1287826048 13.584 98 21 42 0 1297518384 4843568 4848768
23:30:16 3715 8192000000 10199555552 10611195904 1283992976 13.549 98 37 74 0 1299025344 6184320 8848048
23:31:20 7966 8192000000 10162580576 10561056768 1255076384 13.285 99 29 58 3 1267494672 5704736 6713552
23:32:29 7118 8192000000 10123149088 10524016640 1237047216 13.119 100 20 39 0 1246871216 5358704 4465296
23:33:37 8779 8192000000 10159233792 10553860096 1257093056 13.303 100 46 91 0 1274585312 7759360 9732896
23:34:49 5588 8192000000 10182172816 10589011968 1288545728 13.591 100 30 58 2 1301207312 5730608 6930976
23:35:56 8000 8192000000 10186006272 10601963520 1299124688 13.687 100 17 34 1 1307946288 5391392 3430208
23:37:06 12704 8192000000 10209717488 10619658240 1311025392 13.795 100 27 55 0 1323573328 6019216 6528720
23:38:21 33629 8192000000 10216852624 10632400896 1324589280 13.918 100 28 55 0 1336290768 5131024 6570464
23:40:07 17357 8192000000 10256952704 10673991680 1354694112 14.190 100 48 95 0 1375681264 9774624 11212528
23:41:31 14320 8192000000 10294246576 10709770240 1377355360 14.393 100 55 110 1 1404843616 15345152 12143104
23:42:54 10153 8192000000 10301167424 10723385344 1391158448 14.516 100 53 104 1 1419314816 15365984 12790384
23:44:07 5216 8192000000 10318984048 10740862976 1401951760 14.612 100 51 99 0 1431383952 16820528 12611664
23:45:17 23101 8192000000 10327828128 10755837952 1410887728 14.692 100 42 85 1 1443654512 22395712 10371072
23:46:45 11421 8192000000 10336712992 10766303232 1417558976 14.751 100 44 88 0 1450755200 22041488 11154736
23:48:12 6443 8192000000 10345860336 10772443136 1420078336 14.773 100 56 111 1 1458348032 25230176 13039520
23:49:26 5405 8192000000 10350523088 10763407360 1409504192 14.680 100 71 141 3 1456735376 29955424 17275760
23:50:34 2858 8192000000 10363429392 10780790784 1414829008 14.727 100 69 137 2 1465536336 33890000 16817328
23:51:42 8655 8192000000 10356260496 10777747456 1405027472 14.640 99 58 116 1 1454572784 34585040 14960272
23:52:54 5971 8192000000 10353565376 10773143552 1394296368 14.544 99 74 148 2 1453488032 39739920 19451744
23:54:04 1508 8192000000 10368865456 10787295232 1411303136 14.696 100 73 146 0 1473053872 42984624 18766112
23:55:11 7878 8192000000 10347868768 10775605248 1403079728 14.622 100 45 90 1 1453978880 38699968 12199184
23:56:26 8004 8192000000 10331062192 10750271488 1369950848 14.327 99 56 111 0 1431426960 46786784 14689328
23:57:43 7908 8192000000 10300096208 10722983936 1352233616 14.168 99 46 89 2 1403334448 38831488 12269344
23:58:55 10366 8192000000 10286890544 10709753856 1353844528 14.182 99 33 66 0 1397804000 35020240 8939232
00:00:13 9696 8192000000 10312916720 10733977600 1380390032 14.420 100 42 82 0 1419881568 28850320 10641216
00:01:29 10558 8192000000 10326509488 10753626112 1376079264 14.381 99 35 68 0 1418468016 32310064 10078688
00:02:44 7593 8192000000 10312160768 10738016256 1370100960 14.328 100 39 79 0 1415704400 34591296 11012144
00:04:04 33023 8192000000 10311353232 10744745984 1358729264 14.226 99 21 43 0 1406698016 41718736 6250016
00:05:39 47637 8192000000 10258681088 10689142784 1294333376 13.644 95 10 20 0 1320214736 23484336 2397024
00:07:31 69514 8192000000 10281440592 10708344832 1321155808 13.887 97 32 65 0 1334290128 6187488 6946832
00:09:42 90869 8192000000 10284118576 10722344960 1359409632 14.232 99 27 52 1 1377597344 12236752 5950960
00:12:31 30296 8192000000 10285788032 10724511744 1352237552 14.168 98 22 43 0 1365338224 8842272 4258400
00:14:03 62631 8192000000 10270754928 10724831232 1348646976 14.135 98 8 16 0 1367673376 17292880 1733520
00:16:06 29993 8192000000 10280445424 10720514048 1357898944 14.218 99 28 55 1 1373755696 9921552 5935200
00:17:39 14727 8192000000 10304859200 10741477376 1383913344 14.452 100 38 71 0 1407518912 14706368 8899200
00:18:56 45694 8192000000 10303602944 10756591616 1393235600 14.535 100 31 60 0 1418505184 18493648 6775936
00:20:45 50856 8192000000 10316024720 10776170496 1403790848 14.629 100 16 32 0 1428816768 21094400 3931520
00:22:38 55044 8192000000 10320296256 10779463680 1403798224 14.629 100 22 44 1 1434141920 24672832 5670864
00:24:42 40655 8192000000 10315245584 10765246464 1402452512 14.617 100 34 67 1 1425950912 16131504 7366896
00:26:22 78957 8192000000 10306848048 10758979584 1400252368 14.597 100 12 24 0 1416072512 13211120 2609024
00:28:42 35368 8192000000 10317573664 10763243520 1386263936 14.473 99 28 56 0 1406681248 14245088 6172224
00:30:25 70567 8192000000 10308443664 10767159296 1388515424 14.493 99 9 16 0 1401580896 11705648 1359824
00:32:42 31879 8192000000 10311667984 10764455936 1386814912 14.477 99 28 56 1 1403928080 10067232 7045936
00:34:15 44297 8192000000 10325127888 10778103808 1407284368 14.660 100 28 56 0 1428134496 14920208 5929920
00:36:02 100439 8192000000 10281868720 10742046720 1303405776 13.726 93 12 24 0 1321082816 15421392 2255648
00:38:47 88604 8192000000 10263416800 10716549120 1308466944 13.772 94 18 36 0 1326998608 14690192 3841472
00:41:40 66085 8192000000 10266226480 10717454336 1321327808 13.889 96 20 40 0 1336409232 10844656 4236768
00:43:50 20373 8192000000 10280823136 10727063552 1369473408 14.322 100 30 60 1 1387440112 11475456 6491248
00:45:14 78553 8192000000 10275680432 10729992192 1336804256 14.029 97 19 38 0 1350594576 9534720 4255600
00:47:33 71128 8192000000 10263587088 10714693632 1323685232 13.910 96 13 26 0 1345319888 18552448 3082208
00:49:56 75095 8192000000 10265143120 10716684288 1330090288 13.968 97 21 42 0 1348924464 13700320 5133856
00:52:31 67544 8192000000 10260463600 10711375872 1308768224 13.775 96 12 24 0 1324098384 12289168 3040992
00:54:39 39196 8192000000 10286853152 10722361344 1354301616 14.186 99 36 72 0 1369535600 6543856 8690128
00:56:20 56702 8192000000 10255864256 10708054016 1323390096 13.907 97 32 64 1 1345867424 15003744 7473584
00:58:19 82726 8192000000 10268573600 10731704320 1336277440 14.024 98 7 14 0 1351193296 13014592 1901264
01:01:02 32813 8192000000 10262728656 10705887232 1309502240 13.782 96 29 58 0 1333773760 17732496 6539024
01:03:50 48633 8192000000 10278533696 10731761664 1363680704 14.270 100 18 35 1 1381375456 14062016 3632736
01:06:08 89156 8192000000 10288540672 10736791552 1370862960 14.335 100 33 66 0 1390823760 12317376 7643424
01:08:47 16731 8192000000 10283829392 10742378496 1380555872 14.422 100 22 43 1 1400445568 15165840 4723856
01:10:38 36971 8192000000 10275026544 10743181312 1378762304 14.405 100 8 16 0 1390493232 10121280 1609648
01:12:15 41763 8192000000 10271127328 10730180608 1368474944 14.313 99 27 53 0 1380044816 5491200 6078672
01:13:58 60270 8192000000 10307578016 10762436608 1394114800 14.543 100 22 42 1 1410167904 10437312 5615792
01:16:02 55613 8192000000 10302241120 10767814656 1391945536 14.523 100 11 22 0 1405980448 11394752 2640160
01:17:58 64311 8192000000 10319931504 10784251904 1383484288 14.448 99 14 28 0 1408440432 22103136 2853008
01:20:09 63507 8192000000 10320430752 10775625728 1402795232 14.620 100 12 24 0 1419405840 13882368 2728240
01:22:13 85757 8192000000 10308827728 10761056256 1361977040 14.255 97 19 38 0 1379277008 13010272 4289696
01:24:39 37934 8192000000 10319453952 10768793600 1399758048 14.593 100 30 60 0 1418735520 12610528 6366944
01:26:35 91244 8192000000 10266005776 10714484736 1318856176 13.866 95 23 46 0 1335880000 12291072 4732752
01:29:20 89817 8192000000 10284589824 10751033344 1365113152 14.283 99 18 36 0 1386076704 16995824 3967728
01:31:51 54328 8192000000 10268146432 10736738304 1356200112 14.203 98 14 28 0 1366534448 7335728 2998608
01:33:46 105064 8192000000 10268304720 10727686144 1348117584 14.131 98 14 28 0 1360471296 8966208 3387504
01:36:33 62551 8192000000 10258563136 10725126144 1345283200 14.105 98 14 28 0 1362163744 13780960 3099584

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-09-2022 01:40:25 to 27-09-2022 01:40:40.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 121 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2022 01:40:41 to 27-09-2022 01:40:43.

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          237359
MON$OLDEST_ACTIVE               238110
MON$OLDEST_SNAPSHOT             238110
MON$NEXT_TRANSACTION            238110
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                       2039136
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.33631 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33631 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33631 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33631 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 ---
:97: 
:98: mscs-test-161.budzdorov.ru    Tue Sep 27 01:38:39 2022
:99:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:100: 
:101: 
:102: mscs-test-161.budzdorov.ru    Tue Sep 27 01:38:41 2022
:103:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:104: 
:105: 
:106: mscs-test-161.budzdorov.ru    Tue Sep 27 01:38:42 2022
:107:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:108: 
--- end of diff output ---

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

27.09.22 01:40:44. 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-09-27 01:40:47 No severe PSQL-related problems occured
27.09.22 01:40:48. End of report.