Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.02.2023 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 15172236 kB
MemAvailable 30410724 kB
Buffers 0 kB
Cached 16499868 kB
SwapCached 3564 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 2023-02-08 01:38:33
DTS_END                         2023-02-08 01:38:33.5610
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50584
TRN_ID                          239625
ATT_ID                          648
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: 9648.20 17876 1157784
customer order: creation 175.28 352 21033
customer order: refuse 15.23 375 1828
order to supplier: creation 47.36 1219 5683
order to supplier: removal 3.78 101351 454
invoice (draft): creation 46.17 3152 5540
invoice (draft): removal 56.48 860 6778
invoice accept: apply 42.46 17082 5095
invoice accept: cancel 50.44 758 6053
customer reserve: creation 3935.30 168 472236
customer reserve: removal 4972.77 59 596733
realization accept: apply 87.02 170 10442
realization accept: cancel 85.25 118 10230
payment from customer: creation 59.68 114 7162
payment from customer: removal 5.74 102 689
payment to supplier: creation 49.60 121 5952
payment to supplier: removal 3.39 98 407
service: total inventory turnovers 5.07 1048 608
service: total monetary turnovers 6.43 49 772
service: aggregate perf. data 0.71 27837 85
service: refresh index statistics 0.03 202481 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-02-2023 01:39:36 to 08-02-2023 01:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2365
TEST_TIME 2 4058
TEST_TIME 3 3638
TEST_TIME 4 3559
TEST_TIME 5 3667
TEST_TIME 6 3820
TEST_TIME 7 4087
TEST_TIME 8 4433
TEST_TIME 9 4411
TEST_TIME 10 4710
TEST_TIME 11 4744
TEST_TIME 12 5194
TEST_TIME 13 5888
TEST_TIME 14 6227
TEST_TIME 15 6299
TEST_TIME 16 6411
TEST_TIME 17 6770
TEST_TIME 18 7390
TEST_TIME 19 7512
TEST_TIME 20 7824
TEST_TIME 21 8490
TEST_TIME 22 8445
TEST_TIME 23 8765
TEST_TIME 24 8790
TEST_TIME 25 8707
TEST_TIME 26 8631
TEST_TIME 27 8829
TEST_TIME 28 9283
TEST_TIME 29 10098
TEST_TIME 30 10507
TEST_TIME 31 10394
TEST_TIME 32 10510
TEST_TIME 33 10991
TEST_TIME 34 10911
TEST_TIME 35 10943
TEST_TIME 36 10902
TEST_TIME 37 10653
TEST_TIME 38 10483
TEST_TIME 39 10357
TEST_TIME 40 10444
TEST_TIME 41 10279
TEST_TIME 42 10194
TEST_TIME 43 10107
TEST_TIME 44 10134
TEST_TIME 45 9959
TEST_TIME 46 9943
TEST_TIME 47 10363
TEST_TIME 48 10251
TEST_TIME 49 10177
TEST_TIME 50 10079
TEST_TIME 51 10038
TEST_TIME 52 9918
TEST_TIME 53 9818
TEST_TIME 54 9954
TEST_TIME 55 9870
TEST_TIME 56 9810
TEST_TIME 57 9833
TEST_TIME 58 9721
TEST_TIME 59 9685
TEST_TIME 60 9633
TEST_TIME 61 9738
TEST_TIME 62 9753
TEST_TIME 63 9822
TEST_TIME 64 9766
TEST_TIME 65 9907
TEST_TIME 66 9945
TEST_TIME 67 9975
TEST_TIME 68 9995
TEST_TIME 69 10127
TEST_TIME 70 10123
TEST_TIME 71 10094
TEST_TIME 72 10086
TEST_TIME 73 10013
TEST_TIME 74 9993
TEST_TIME 75 9932
TEST_TIME 76 9898
TEST_TIME 77 9890
TEST_TIME 78 9815
TEST_TIME 79 9743
TEST_TIME 80 9705
TEST_TIME 81 9744
TEST_TIME 82 9728
TEST_TIME 83 9706
TEST_TIME 84 9696
TEST_TIME 85 9737
TEST_TIME 86 9678
TEST_TIME 87 9806
TEST_TIME 88 9848
TEST_TIME 89 9812
TEST_TIME 90 9827
TEST_TIME 91 9876
TEST_TIME 92 9868
TEST_TIME 93 9934
TEST_TIME 94 9926
TEST_TIME 95 9947
TEST_TIME 96 9866
TEST_TIME 97 9819
TEST_TIME 98 9782
TEST_TIME 99 9797
TEST_TIME 100 9751
TEST_TIME 101 9708
TEST_TIME 102 9692
TEST_TIME 103 9676
TEST_TIME 104 9647
TEST_TIME 105 9622
TEST_TIME 106 9654
TEST_TIME 107 9683
TEST_TIME 108 9650
TEST_TIME 109 9618
TEST_TIME 110 9631
TEST_TIME 111 9686
TEST_TIME 112 9702
TEST_TIME 113 9686
TEST_TIME 114 9729
TEST_TIME 115 9701
TEST_TIME 116 9750
TEST_TIME 117 9759
TEST_TIME 118 9762
TEST_TIME 119 9711
TEST_TIME 120 9650

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-02-2023 01:39:40 to 08-02-2023 01:39:44.

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 1596686 1596686 0 0.00 0 26044 6 0 0
doc_list_biud 1596686 1596686 0 0.00 0 50541 28 0 0
sp_add_invoice_to_stock 5166 5095 71 1.37 39 166354 17082 0 71
sp_cancel_adding_invoice 6053 6053 0 0.00 4 65439 759 0 0
sp_cancel_client_order 1828 1828 0 0.00 4 16844 376 0 0
sp_cancel_customer_reserve 596733 596733 0 0.00 0 50542 60 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 2 8641 102 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 3 7128 99 0 0
sp_cancel_supplier_invoice 6784 6778 6 0.08 1 18838 860 0 6
sp_cancel_supplier_order 454 454 0 0.00 10 1036580 101352 0 0
sp_cancel_write_off 10230 10230 0 0.00 1 18487 119 0 0
sp_client_order 21033 21033 0 0.00 3 49186 352 0 0
sp_customer_reserve 472236 472236 0 0.00 0 46263 169 0 0
sp_fill_shopping_cart 32249 32249 0 0.00 1 18105 35 0 0
sp_fill_shopping_cart_clo_res 472235 472235 0 0.00 0 16843 14 0 0
sp_get_clo_for_invoice 5094 5094 0 0.00 0 21639 319 0 0
sp_kill_cost_storno 17379 17379 0 0.00 0 8828 57 0 0
sp_kill_qstorno_ret_qs2qd 1210928 1210928 0 0.00 0 50535 33 0 0
sp_kill_qty_storno 637612 637612 0 0.00 0 50541 70 0 0
sp_lock_selected_doc 21473 21473 0 0.00 0 8683 10 0 0
sp_make_cost_storno 28650 28650 0 0.00 0 24171 53 0 0
sp_multiply_rows_for_pdistr 28650 28650 0 0.00 0 7497 27 0 0
sp_multiply_rows_for_qdistr 21033 21033 0 0.00 0 49180 161 0 0
sp_pay_from_customer 7162 7162 0 0.00 4 12904 115 0 0
sp_pay_to_supplier 5952 5952 0 0.00 5 15833 121 0 0
sp_payment_common 13114 13114 0 0.00 1 8604 30 0 0
sp_qd_handle_on_cancel_clo 1828 1828 0 0.00 0 16838 338 0 0
sp_qd_handle_on_invoice_upd_sts 11147 11147 0 0.00 0 32537 321 0 0
sp_qd_handle_on_reserve_upd_sts 10442 10442 0 0.00 0 8286 47 0 0
sp_reserve_write_off 10442 10442 0 0.00 6 24182 170 0 0
sp_supplier_invoice 5540 5540 0 0.00 5 78634 3153 0 0
sp_supplier_order 5683 5683 0 0.00 5 63095 1220 0 0
srv_aggregate_perf_data 86 85 1 1.16 2590 357982 27838 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1252 122126 39896 0 0
srv_find_qd_qs_mism 1110047 1110047 0 0.00 0 26043 5 0 0
srv_make_invnt_saldo 608 608 0 0.00 29 75078 1049 0 0
srv_make_money_saldo 772 772 0 0.00 0 18085 50 0 0
srv_recalc_idx_stat 4 4 0 0.00 147862 331446 202482 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5096 5096 0 0.00 0 10425 140 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 0 2938 79 0 0
t$perf-norm:sp_cancel_client_order 1828 1828 0 0.00 0 4737 17 0 0
t$perf-norm:sp_cancel_customer_reserve 760 760 0 0.00 0 2673 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 17945 38 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 401 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 1420 10 0 0
t$perf-norm:sp_cancel_supplier_order 454 454 0 0.00 0 26174 1587 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 2389 7 0 0
t$perf-norm:sp_client_order 21033 21033 0 0.00 0 16575 18 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 2858 7 0 0
t$perf-norm:sp_pay_from_customer 7162 7162 0 0.00 0 11467 16 0 0
t$perf-norm:sp_pay_to_supplier 5952 5952 0 0.00 0 9290 13 0 0
t$perf-norm:sp_reserve_write_off 10442 10442 0 0.00 0 11317 11 0 0
t$perf-norm:sp_supplier_invoice 5540 5540 0 0.00 0 21625 19 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 26808 21 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 160 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 758 16 0 0
t$perf-norm:srv_make_invnt_saldo 608 608 0 0.00 0 385 14 0 0
t$perf-norm:srv_make_money_saldo 772 772 0 0.00 0 1268 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 61 18 0 0
v_add_invoice_to_stock 10328 10186 142 1.37 0 5984 17 0 142
v_all_customers 38346 38346 0 0.00 0 28 1 0 0
v_all_suppliers 31264 31264 0 0.00 0 21 1 0 0
v_all_wares 42066 42066 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 270 6 0 0
v_cancel_client_order 3656 3656 0 0.00 0 4674 6 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 4810 9 0 0
v_cancel_customer_reserve 1520 1520 0 0.00 0 1931 6 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 2381 10 0 0
v_cancel_supplier_invoice 778 766 12 1.54 0 11265 31 0 12
v_cancel_supplier_order 908 908 0 0.00 0 1188 6 0 0
v_cancel_write_off 2070 2070 0 0.00 0 4302 19 0 0
v_max_id_clo_ord 5683 5683 0 0.00 0 1715 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 3874 12 0 0
v_max_id_ord_sup 5533 5533 0 0.00 0 4953 4 0 0
v_max_non_paid_invoice 1536 1536 0 0.00 0 627 4 0 0
v_max_non_paid_realizn 3789 3789 0 0.00 0 2421 4 0 0
v_min_id_clo_ord 5683 5683 0 0.00 0 3076 7 0 0
v_min_id_clo_res 998 998 0 0.00 0 2505 9 0 0
v_min_id_ord_sup 5533 5533 0 0.00 0 18102 11 0 0
v_min_non_paid_invoice 5952 5952 0 0.00 0 6290 22 0 0
v_min_non_paid_realizn 7162 7162 0 0.00 0 8596 22 0 0
v_our_firm 10466 10466 0 0.00 0 17 1 0 0
v_reserve_write_off 20884 20884 0 0.00 0 4806 7 0 0
x_lock_depdocs_on_canc_invoice 12448 12448 0 0.00 0 16116 111 0 0
x_lock_depdocs_on_canc_sup_ord 454 454 0 0.00 0 7127 314 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 63086 1147 0 0
x_make_qstorno_1200_2000 5540 5540 0 0.00 2 76057 3023 0 0
x_make_qstorno_2100_3300 472236 472236 0 0.00 0 37389 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-02-2023 01:39:45 to 08-02-2023 01:39:49.

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:37 4 8192000000 8801230096 8813510656 7325328 0.089 3 1 2 0 7424928 56816 42784
23:09:38 26966 8192000000 9893342816 10201169920 970632576 10.593 100 87 175 0 1000096016 6069600 23393840
23:11:10 25158 8192000000 9932669280 10250534912 1015334800 11.027 100 93 185 0 1048189904 8055216 24799888
23:12:47 18035 8192000000 9984158352 10313261056 1059671696 11.453 100 86 172 0 1089375680 8316272 21387712
23:14:17 10815 8192000000 10023484288 10369388544 1114406752 11.974 100 77 154 0 1143196416 10528160 18261504
23:15:32 6578 8192000000 10081090800 10435694592 1169041456 12.488 100 82 165 0 1199694816 12254496 18398864
23:16:41 2025 8192000000 10130441664 10499940352 1218853376 12.951 100 77 154 0 1249320784 12880768 17586640
23:17:45 1254 8192000000 10168335776 10542731264 1255619200 13.290 100 85 168 1 1287849200 13730656 18499344
23:18:48 2711 8192000000 10179265808 10560106496 1274940208 13.467 100 86 171 0 1307406368 13500880 18965280
23:19:53 1606 8192000000 10212620688 10604953600 1304349616 13.735 100 67 134 1 1330885232 11352928 15182688
23:20:55 11020 8192000000 10197527312 10611843072 1312487568 13.809 100 35 69 0 1327498992 7155808 7855616
23:22:07 9583 8192000000 10201414224 10621116416 1321642160 13.892 100 11 22 1 1330088272 6247744 2198368
23:23:21 11568 8192000000 10217401792 10631303168 1328559680 13.954 100 26 51 0 1340943296 6546688 5836928
23:24:33 11060 8192000000 10219984448 10637189120 1332835760 13.993 100 14 28 0 1341321952 5813984 2672208
23:25:46 28305 8192000000 10227793328 10650918912 1336848544 14.029 100 21 42 0 1346593440 4947424 4797472
23:27:19 5457 8192000000 10234969712 10656186368 1342313568 14.078 100 18 36 0 1352123728 5612608 4197552
23:28:26 8547 8192000000 10224480112 10645692416 1323881552 13.912 99 21 42 0 1335242336 6359184 5001600
23:29:37 60786 8192000000 10192341680 10596028416 1247353280 13.214 94 29 59 0 1258930448 4060704 7516464
23:31:39 4410 8192000000 10155813280 10546446336 1217371808 12.937 95 37 73 0 1230813872 4744832 8697232
23:32:46 11597 8192000000 10142649808 10545754112 1249584144 13.234 100 18 36 0 1259030224 5284576 4161504
23:33:58 8224 8192000000 10143079856 10539778048 1227786352 13.034 98 16 32 0 1236860368 5274912 3799104
23:35:06 40987 8192000000 10160215520 10573824000 1273870832 13.457 100 26 52 0 1289060144 9445152 5744160
23:36:47 9286 8192000000 10187344112 10599145472 1294247936 13.643 100 28 56 3 1306868688 5066448 7554304
23:37:58 11124 8192000000 10210770256 10624380928 1311821200 13.803 100 19 38 0 1321434240 5510304 4102736
23:39:14 25685 8192000000 10244666416 10652741632 1337007312 14.030 100 45 90 0 1354741552 7303792 10430448
23:40:50 95515 8192000000 10263238720 10687606784 1361119216 14.247 100 30 60 1 1378724976 10732000 6873760
23:43:33 14322 8192000000 10287182576 10708803584 1374565360 14.368 100 45 89 0 1400436368 15681424 10189584
23:44:50 6851 8192000000 10308492944 10732277760 1392785376 14.531 100 45 90 0 1421803296 18635632 10382288
23:46:01 22005 8192000000 10318462144 10745110528 1400888976 14.603 100 52 102 2 1435003840 21958336 12156528
23:47:34 13921 8192000000 10323939312 10750218240 1405496336 14.644 100 44 88 0 1438076432 21911472 10668624
23:48:57 2436 8192000000 10347899984 10771931136 1414958384 14.728 100 55 109 0 1452790176 24067600 13764192
23:50:07 1251 8192000000 10374702080 10796634112 1432492256 14.883 100 63 122 1 1479911360 32253296 15165808
23:51:13 10157 8192000000 10339053856 10770038784 1406657088 14.654 99 46 92 0 1448867856 29599248 12611520
23:52:25 2694 8192000000 10348912368 10773925888 1404080608 14.631 99 57 113 0 1448664080 30469536 14113936
23:53:43 5926 8192000000 10349458640 10774482944 1413681040 14.717 100 65 127 1 1461481856 30972608 16828208
23:54:55 10077 8192000000 10361335200 10776879104 1399877360 14.594 99 67 134 0 1462921440 45718592 17325488
23:56:12 3673 8192000000 10392676448 10811473920 1419742576 14.770 100 80 158 0 1491516576 51627152 20146848
23:57:23 2552 8192000000 10363936256 10782593024 1398706080 14.583 100 68 136 2 1468084736 51230416 18148240
23:58:31 5100 8192000000 10366352512 10788421632 1403621776 14.627 100 72 142 1 1473814400 51973168 18219456
23:59:45 7794 8192000000 10341272784 10766618624 1383879632 14.451 99 47 97 1 1434029280 38209056 11940592
00:00:58 5422 8192000000 10363230048 10796019712 1408164960 14.668 100 37 74 2 1467897136 48903952 10828224
00:02:08 10111 8192000000 10353695024 10790694912 1405812384 14.647 100 35 70 0 1459916016 44591072 9512560
00:03:20 6554 8192000000 10341868544 10775654400 1385809360 14.468 99 28 56 0 1437369088 43786048 7773680
00:04:29 32628 8192000000 10336035248 10769440768 1384851920 14.460 100 28 56 0 1446495280 53504592 8138768
00:06:05 22822 8192000000 10325044976 10765983744 1352706176 14.172 99 24 48 0 1420455328 59979072 7770080
00:07:48 22590 8192000000 10313314032 10756448256 1363281872 14.267 99 15 30 0 1406332992 39021744 4029376
00:10:01 33564 8192000000 10293635856 10734039040 1358738816 14.226 99 32 66 0 1387320992 20487520 8094656
00:11:51 73262 8192000000 10298615728 10744614912 1350921968 14.156 98 15 29 0 1364926656 11066560 2938128
00:14:05 117003 8192000000 10295735504 10755133440 1384709040 14.459 100 17 34 1 1411291040 22898384 3683616
00:17:03 41748 8192000000 10292077328 10738270208 1347337920 14.124 97 21 42 1 1360061536 8043024 4680592
00:18:53 61245 8192000000 10305376800 10755178496 1357907312 14.219 98 33 66 0 1380006208 14628032 7470864
00:20:56 70444 8192000000 10331031056 10781814784 1402862720 14.620 100 25 49 0 1422818560 14726080 5229760
00:23:29 15806 8192000000 10339754736 10787880960 1417333472 14.749 100 53 104 1 1447627072 16802544 13491056
00:24:54 51393 8192000000 10314726304 10797101056 1408302400 14.669 100 15 30 0 1429245488 17203232 3739856
00:26:49 35131 8192000000 10295569712 10762440704 1374064944 14.363 98 13 26 0 1384123840 6909936 3148960
00:28:24 76554 8192000000 10298286800 10774396928 1400042320 14.595 100 14 29 0 1413411232 9838608 3530304
00:30:41 66937 8192000000 10312447232 10771927040 1374608240 14.368 98 18 36 0 1391631088 13212800 3810048
00:33:01 28967 8192000000 10287687936 10741981184 1365354720 14.285 98 18 37 1 1382935120 12817440 4762960
00:34:31 40626 8192000000 10282211408 10743918592 1363675312 14.270 98 18 36 0 1375222304 7782544 3764448
00:36:24 56421 8192000000 10286178928 10752393216 1379761648 14.414 99 21 42 1 1391546032 6843472 4940912
00:38:33 53523 8192000000 10283219568 10754605056 1350270752 14.150 97 15 31 0 1367281296 13636720 3373824
00:40:36 32909 8192000000 10311630128 10766794752 1388504800 14.493 99 29 58 0 1404017488 8950224 6562464
00:42:10 29282 8192000000 10307825120 10759888896 1372968800 14.354 98 38 76 0 1393005152 11202096 8834256
00:43:41 81313 8192000000 10287764320 10755567616 1323322080 13.907 96 5 10 0 1343365568 18356560 1686928
00:46:04 81805 8192000000 10247899760 10707906560 1252006144 13.257 91 11 22 0 1275036352 20389696 2640512
00:48:30 42378 8192000000 10264621424 10712293376 1324312128 13.916 98 15 30 1 1335813216 8246528 3254560
00:50:16 37080 8192000000 10277353136 10718478336 1310513568 13.791 96 27 52 0 1327617840 11499296 5604976
00:51:54 106445 8192000000 10251168448 10706591744 1332561024 13.990 98 25 50 0 1348554816 10186048 5807744
00:54:58 51203 8192000000 10206989600 10664960000 1253178864 13.267 94 6 12 1 1264019712 9437920 1402928
00:56:49 73910 8192000000 10220809840 10674380800 1294254608 13.643 97 8 16 0 1306556496 10280720 2021168
00:59:06 74058 8192000000 10223041824 10682662912 1285667680 13.565 96 12 24 0 1297902448 9386240 2848528
01:01:32 23419 8192000000 10257964240 10702389248 1344272048 14.096 100 40 81 1 1361266432 7756672 9237712
01:03:17 45669 8192000000 10277262112 10733670400 1354131984 14.185 99 27 54 0 1373752320 13084560 6535776
01:06:05 26450 8192000000 10277708240 10722832384 1344780352 14.100 98 37 73 1 1360073744 7399856 7893536
01:07:38 15577 8192000000 10299240864 10737827840 1373121440 14.355 100 40 80 1 1390597872 8938144 8538288
01:08:54 38567 8192000000 10295754368 10748432384 1378191824 14.400 99 15 30 0 1391261344 10089552 2979968
01:10:35 29207 8192000000 10294464496 10740805632 1341675088 14.073 97 50 99 1 1366525392 13720928 11129376
01:12:05 84178 8192000000 10302500752 10766372864 1362896368 14.263 98 12 24 1 1377610688 12451312 2263008
01:14:30 92109 8192000000 10295149744 10750140416 1354239776 14.186 97 25 51 0 1368333568 7869856 6223936
01:17:04 44162 8192000000 10263763136 10721513472 1303819344 13.730 94 11 22 0 1316323680 10348800 2155536
01:18:49 49001 8192000000 10265092688 10729607168 1356395136 14.205 99 10 20 0 1367242992 8689872 2157984
01:20:38 67590 8192000000 10285649328 10754215936 1391309552 14.518 100 13 27 0 1403493744 8916944 3267248
01:22:48 50353 8192000000 10298212192 10761146368 1391730416 14.521 100 6 12 0 1401204736 8106016 1368304
01:24:45 51769 8192000000 10293933824 10758000640 1355196160 14.194 97 17 34 0 1373843728 15003472 3644096
01:26:50 25678 8192000000 10297645312 10753327104 1364583600 14.278 98 34 69 0 1379711376 7861264 7266512
01:28:16 76509 8192000000 10280306624 10742882304 1330763104 13.974 96 20 40 0 1348064032 12363312 4937616
01:30:46 33130 8192000000 10297422352 10752016384 1380028816 14.417 100 12 23 0 1394054352 11594096 2431440
01:32:23 24626 8192000000 10293065392 10739425280 1367108048 14.301 99 29 58 0 1390498464 16864624 6525792
01:33:56 7121 8192000000 10301160544 10737397760 1373037232 14.354 100 63 126 1 1397929616 10379152 14513232
01:35:10 122126 8192000000 10256022368 10718662656 1306148176 13.751 95 11 22 0 1324957712 15812496 2997040
01:38:33 2625 8192000000 8973647440 9157554176 146130112 1.752 11 3 6 0 147758656 1049056 579488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-02-2023 01:39:51 to 08-02-2023 01:40:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 71 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 08-02-2023 01:40:07 to 08-02-2023 01:40:09.

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          239939
MON$OLDEST_ACTIVE               239940
MON$OLDEST_SNAPSHOT             239940
MON$NEXT_TRANSACTION            239940
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                       2043296
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.33659 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33659 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33659 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33659 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 ---
--- end of diff output ---

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

08.02.23 01:40:10. 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
2023-02-08 01:40:13 No severe PSQL-related problems occured
08.02.23 01:40:14. End of report.