Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.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 14974260 kB
MemAvailable 30392676 kB
Buffers 0 kB
Cached 16722244 kB
SwapCached 9236 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-01-02 01:38:36
DTS_END                         2023-01-02 01:38:36.8270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48062
TRN_ID                          245605
ATT_ID                          714
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: 9468.28 22824 1136193
customer order: creation 179.26 352 21511
customer order: refuse 15.07 393 1808
order to supplier: creation 47.33 1254 5679
order to supplier: removal 3.54 106752 425
invoice (draft): creation 48.23 3137 5787
invoice (draft): removal 54.70 787 6564
invoice accept: apply 43.24 19680 5189
invoice accept: cancel 49.45 867 5934
customer reserve: creation 3958.12 197 474974
customer reserve: removal 4768.49 63 572219
realization accept: apply 86.63 200 10396
realization accept: cancel 77.72 112 9327
payment from customer: creation 62.33 113 7480
payment from customer: removal 6.03 86 723
payment to supplier: creation 51.41 139 6169
payment to supplier: removal 3.50 94 420
service: total inventory turnovers 4.77 1293 572
service: total monetary turnovers 7.58 30 909
service: aggregate perf. data 0.86 18607 103
service: refresh index statistics 0.03 302315 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4661
TEST_TIME 2 5523
TEST_TIME 3 6382
TEST_TIME 4 6205
TEST_TIME 5 6429
TEST_TIME 6 6057
TEST_TIME 7 6862
TEST_TIME 8 7152
TEST_TIME 9 7290
TEST_TIME 10 7377
TEST_TIME 11 7663
TEST_TIME 12 7800
TEST_TIME 13 7760
TEST_TIME 14 7602
TEST_TIME 15 7465
TEST_TIME 16 7685
TEST_TIME 17 7781
TEST_TIME 18 7776
TEST_TIME 19 7939
TEST_TIME 20 8295
TEST_TIME 21 8210
TEST_TIME 22 8489
TEST_TIME 23 9280
TEST_TIME 24 9356
TEST_TIME 25 9578
TEST_TIME 26 9483
TEST_TIME 27 9548
TEST_TIME 28 9502
TEST_TIME 29 9902
TEST_TIME 30 9703
TEST_TIME 31 9731
TEST_TIME 32 9781
TEST_TIME 33 9943
TEST_TIME 34 10390
TEST_TIME 35 11205
TEST_TIME 36 11232
TEST_TIME 37 11117
TEST_TIME 38 10888
TEST_TIME 39 11032
TEST_TIME 40 10850
TEST_TIME 41 10789
TEST_TIME 42 10610
TEST_TIME 43 10608
TEST_TIME 44 10560
TEST_TIME 45 10379
TEST_TIME 46 10196
TEST_TIME 47 10139
TEST_TIME 48 10321
TEST_TIME 49 10239
TEST_TIME 50 10098
TEST_TIME 51 10067
TEST_TIME 52 9988
TEST_TIME 53 9887
TEST_TIME 54 9918
TEST_TIME 55 10083
TEST_TIME 56 10035
TEST_TIME 57 9974
TEST_TIME 58 9935
TEST_TIME 59 9880
TEST_TIME 60 9823
TEST_TIME 61 9793
TEST_TIME 62 9740
TEST_TIME 63 9695
TEST_TIME 64 9646
TEST_TIME 65 9642
TEST_TIME 66 9645
TEST_TIME 67 9646
TEST_TIME 68 9631
TEST_TIME 69 9706
TEST_TIME 70 9742
TEST_TIME 71 9730
TEST_TIME 72 9856
TEST_TIME 73 9825
TEST_TIME 74 9875
TEST_TIME 75 9842
TEST_TIME 76 9871
TEST_TIME 77 9856
TEST_TIME 78 9760
TEST_TIME 79 9797
TEST_TIME 80 9713
TEST_TIME 81 9667
TEST_TIME 82 9579
TEST_TIME 83 9610
TEST_TIME 84 9537
TEST_TIME 85 9550
TEST_TIME 86 9567
TEST_TIME 87 9528
TEST_TIME 88 9529
TEST_TIME 89 9505
TEST_TIME 90 9554
TEST_TIME 91 9609
TEST_TIME 92 9585
TEST_TIME 93 9606
TEST_TIME 94 9631
TEST_TIME 95 9593
TEST_TIME 96 9647
TEST_TIME 97 9655
TEST_TIME 98 9619
TEST_TIME 99 9567
TEST_TIME 100 9570
TEST_TIME 101 9516
TEST_TIME 102 9516
TEST_TIME 103 9481
TEST_TIME 104 9452
TEST_TIME 105 9395
TEST_TIME 106 9456
TEST_TIME 107 9435
TEST_TIME 108 9453
TEST_TIME 109 9471
TEST_TIME 110 9493
TEST_TIME 111 9474
TEST_TIME 112 9444
TEST_TIME 113 9484
TEST_TIME 114 9467
TEST_TIME 115 9501
TEST_TIME 116 9486
TEST_TIME 117 9546
TEST_TIME 118 9554
TEST_TIME 119 9539
TEST_TIME 120 9471

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-01-2023 01:40:14 to 02-01-2023 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 1576314 1576314 0 0.00 0 23579 7 0 0
doc_list_biud 1576314 1576314 0 0.00 0 41327 29 0 0
sp_add_invoice_to_stock 5359 5189 170 3.17 40 176540 19680 0 170
sp_cancel_adding_invoice 5934 5934 0 0.00 4 75336 868 0 0
sp_cancel_client_order 1808 1808 0 0.00 4 10429 393 0 0
sp_cancel_customer_reserve 572219 572219 0 0.00 0 41329 64 0 0
sp_cancel_pay_from_customer 723 723 0 0.00 2 7432 87 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 5061 94 0 0
sp_cancel_supplier_invoice 6578 6564 14 0.21 2 14431 788 0 14
sp_cancel_supplier_order 425 425 0 0.00 6 1133596 106752 0 0
sp_cancel_write_off 9327 9327 0 0.00 1 9462 113 0 0
sp_client_order 21511 21511 0 0.00 3 16627 352 0 0
sp_customer_reserve 474974 474974 0 0.00 0 91212 198 0 0
sp_fill_shopping_cart 32977 32977 0 0.00 1 7056 36 0 0
sp_fill_shopping_cart_clo_res 474971 474971 0 0.00 0 22065 14 0 0
sp_get_clo_for_invoice 5185 5185 0 0.00 0 39466 279 0 0
sp_kill_cost_storno 16404 16404 0 0.00 0 5642 52 0 0
sp_kill_qstorno_ret_qs2qd 1160754 1160754 0 0.00 0 41325 34 0 0
sp_kill_qty_storno 611858 611858 0 0.00 0 41327 74 0 0
sp_lock_selected_doc 21609 21609 0 0.00 0 10125 7 0 0
sp_make_cost_storno 29230 29230 0 0.00 0 23460 55 0 0
sp_multiply_rows_for_pdistr 29230 29230 0 0.00 0 7685 29 0 0
sp_multiply_rows_for_qdistr 21511 21511 0 0.00 0 11066 149 0 0
sp_pay_from_customer 7480 7480 0 0.00 4 21924 114 0 0
sp_pay_to_supplier 6169 6169 0 0.00 4 19768 139 0 0
sp_payment_common 13649 13649 0 0.00 1 15847 26 0 0
sp_qd_handle_on_cancel_clo 1808 1808 0 0.00 0 10424 348 0 0
sp_qd_handle_on_invoice_upd_sts 11119 11119 0 0.00 0 18550 352 0 0
sp_qd_handle_on_reserve_upd_sts 10396 10396 0 0.00 0 15018 73 0 0
sp_reserve_write_off 10396 10396 0 0.00 6 23416 200 0 0
sp_supplier_invoice 5787 5787 0 0.00 6 74588 3138 0 0
sp_supplier_order 5679 5679 0 0.00 5 30266 1255 0 0
srv_aggregate_perf_data 103 103 0 0.00 2351 214538 18607 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 792 111931 41011 0 0
srv_find_qd_qs_mism 1086691 1086691 0 0.00 0 15972 6 0 0
srv_make_invnt_saldo 572 572 0 0.00 38 134984 1294 0 0
srv_make_money_saldo 909 909 0 0.00 1 1017 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 129503 513585 302316 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5189 5189 0 0.00 0 10184 200 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 4821 111 0 0
t$perf-norm:sp_cancel_client_order 1808 1808 0 0.00 0 4605 16 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 3152 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 723 723 0 0.00 0 582 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 5211 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 447 447 0 0.00 0 5393 27 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 20337 1721 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 5641 10 0 0
t$perf-norm:sp_client_order 21511 21511 0 0.00 0 8461 19 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 1922 8 0 0
t$perf-norm:sp_pay_from_customer 7480 7480 0 0.00 0 5822 10 0 0
t$perf-norm:sp_pay_to_supplier 6169 6169 0 0.00 0 7351 9 0 0
t$perf-norm:sp_reserve_write_off 10396 10396 0 0.00 0 9609 13 0 0
t$perf-norm:sp_supplier_invoice 5787 5787 0 0.00 0 7356 24 0 0
t$perf-norm:sp_supplier_order 5679 5679 0 0.00 0 8052 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 1255 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 596 11 0 0
t$perf-norm:srv_make_invnt_saldo 572 572 0 0.00 0 836 22 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 3488 30 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 20 7 0 0
v_add_invoice_to_stock 10706 10366 340 3.17 0 7090 22 0 340
v_all_customers 39086 39086 0 0.00 0 77 1 0 0
v_all_suppliers 31944 31944 0 0.00 0 76 1 0 0
v_all_wares 43022 43022 0 0.00 0 90 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 586 6 0 0
v_cancel_client_order 3616 3616 0 0.00 0 4980 8 0 0
v_cancel_customer_prepayment 1446 1446 0 0.00 0 5092 7 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 6642 9 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4982 13 0 0
v_cancel_supplier_invoice 922 894 28 3.03 0 5785 22 0 28
v_cancel_supplier_order 850 850 0 0.00 0 2211 11 0 0
v_cancel_write_off 2102 2102 0 0.00 0 6460 19 0 0
v_max_id_clo_ord 5679 5679 0 0.00 0 509 2 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 5020 22 0 0
v_max_id_ord_sup 5787 5787 0 0.00 0 5071 4 0 0
v_max_non_paid_invoice 1663 1663 0 0.00 0 3717 6 0 0
v_max_non_paid_realizn 4080 4080 0 0.00 0 5093 7 0 0
v_min_id_clo_ord 5679 5679 0 0.00 0 3866 8 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 7623 16 0 0
v_min_id_ord_sup 5787 5787 0 0.00 0 3243 5 0 0
v_min_non_paid_invoice 6169 6169 0 0.00 0 15844 18 0 0
v_min_non_paid_realizn 7480 7480 0 0.00 0 7298 16 0 0
v_our_firm 10736 10736 0 0.00 0 77 1 0 0
v_reserve_write_off 20792 20792 0 0.00 0 6634 8 0 0
x_lock_depdocs_on_canc_invoice 12051 12051 0 0.00 0 11838 122 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 3666 346 0 0
x_make_qstorno_1000_1200 5679 5679 0 0.00 1 30235 1171 0 0
x_make_qstorno_1200_2000 5787 5787 0 0.00 3 74578 3015 0 0
x_make_qstorno_2100_3300 474974 474974 0 0.00 0 91200 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2023 01:40:19 to 02-01-2023 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:40 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:41 9434 8192000000 9894061536 10202431488 984865456 10.732 100 86 173 0 1012419392 5736384 21817552
23:10:55 12102 8192000000 9930870896 10248474624 1016185248 11.035 100 95 190 0 1048780464 8251760 24343456
23:12:23 15824 8192000000 9975262512 10303131648 1055520768 11.414 100 82 164 0 1085114768 9112912 20481088
23:13:52 11533 8192000000 10022078880 10366767104 1110869456 11.941 100 81 161 0 1141046320 11148848 19028016
23:15:08 5501 8192000000 10070214464 10425663488 1157439408 12.379 100 82 164 0 1189736080 13515216 18781456
23:16:16 3703 8192000000 10109603584 10472869888 1196399904 12.743 100 81 160 0 1227844528 13402672 18041952
23:17:22 2865 8192000000 10141256992 10512916480 1231466928 13.068 100 83 166 2 1263553776 13245872 18840976
23:18:25 1797 8192000000 10176093392 10551599104 1260846208 13.338 100 89 174 1 1293288624 12946384 19496032
23:19:28 1097 8192000000 10199407840 10582634496 1289581840 13.600 100 85 170 1 1321046608 13292512 18172256
23:20:30 8097 8192000000 10210027840 10603991040 1301972848 13.713 100 66 132 2 1325648112 9234544 14440720
23:21:41 3817 8192000000 10210918496 10618048512 1315456960 13.836 100 35 71 0 1332365392 9323088 7585344
23:22:50 8477 8192000000 10218045376 10629693440 1324073664 13.914 100 29 58 1 1336278160 6197232 6007264
23:23:59 40098 8192000000 10233387328 10641678336 1328473120 13.953 100 54 109 1 1347247872 6103552 12671200
23:25:40 5986 8192000000 10225387184 10644221952 1334684400 14.009 100 29 58 0 1347955584 6809712 6461472
23:26:46 13398 8192000000 10213753760 10639278080 1320200448 13.879 99 23 44 0 1331276528 6013168 5062912
23:28:01 71461 8192000000 10206653584 10627239936 1290344912 13.607 97 21 35 2 1298870464 4496624 4028928
23:30:15 4574 8192000000 10172367440 10580762624 1268622896 13.409 98 23 46 1 1279085072 4937712 5524464
23:31:20 6597 8192000000 10137080448 10539499520 1229388720 13.048 98 25 51 1 1240487200 4770800 6327680
23:32:28 5824 8192000000 10155088640 10550345728 1236542016 13.114 98 45 91 0 1253754448 7142720 10069712
23:33:35 11286 8192000000 10127834736 10530660352 1215999456 12.925 97 16 28 0 1224650208 5461280 3189472
23:34:49 6721 8192000000 10165604080 10576347136 1277815552 13.493 100 28 54 1 1290837824 5818064 7204208
23:36:06 11648 8192000000 10185312560 10601504768 1298604272 13.683 100 15 30 0 1307661552 5652528 3404752
23:37:20 61445 8192000000 10201950032 10618556416 1309383280 13.780 100 29 58 0 1326312304 10100400 6828624
23:39:25 17925 8192000000 10240071920 10653958144 1338775696 14.046 100 42 84 0 1356400496 8547552 9077248
23:40:54 17095 8192000000 10267666736 10685046784 1362277376 14.258 100 49 95 0 1383721024 10763936 10679712
23:42:16 14066 8192000000 10291446304 10709139456 1379486288 14.412 100 46 92 0 1402939328 12826544 10626496
23:43:31 9834 8192000000 10294056880 10710917120 1375471472 14.376 100 54 106 0 1401584720 13923712 12189536
23:44:48 11026 8192000000 10296478848 10715836416 1378050080 14.399 100 46 90 1 1405552528 17002368 10500080
23:46:02 7573 8192000000 10316160512 10730876928 1387092432 14.480 100 72 139 4 1428013632 23361072 17560128
23:47:13 3027 8192000000 10315806960 10740539392 1397559808 14.573 100 54 106 1 1426023824 15776384 12687632
23:48:21 8583 8192000000 10313990288 10735222784 1391397408 14.518 100 62 120 1 1426578016 21346032 13834576
23:49:44 4719 8192000000 10330058528 10746650624 1393951968 14.541 100 65 129 0 1437753456 28383184 15418304
23:50:55 10712 8192000000 10328215088 10751524864 1401351904 14.607 100 47 93 0 1435684592 23109648 11223040
23:52:11 7068 8192000000 10322265456 10747609088 1385936800 14.470 98 47 92 2 1419678496 21835424 11906272
23:53:32 14358 8192000000 10313288288 10734809088 1386891584 14.478 100 44 85 1 1423439872 25882832 10665456
23:54:59 2149 8192000000 10325610400 10736558080 1385127760 14.462 99 75 149 0 1427588208 24533776 17926672
23:56:06 791 8192000000 10300789184 10707533824 1360932144 14.246 99 71 143 0 1404534192 26561296 17040752
23:57:10 1992 8192000000 10328627856 10732396544 1366479680 14.295 99 83 166 0 1423863952 37187344 20196928
23:58:15 9434 8192000000 10291241776 10708127744 1341343152 14.070 98 39 76 0 1386029712 34604736 10081824
23:59:28 1523 8192000000 10327038192 10724044800 1357829888 14.218 100 72 136 2 1419343984 43668400 17845696
00:00:34 2876 8192000000 10322701552 10736168960 1367924544 14.308 100 54 105 0 1424886032 43800704 13160784
00:01:49 17343 8192000000 10305647312 10730352640 1367963552 14.309 99 33 66 0 1417538384 40532464 9042368
00:03:24 5044 8192000000 10371223952 10787655680 1407822352 14.665 100 76 152 2 1471360304 43437456 20100496
00:04:32 3812 8192000000 10363979120 10778066944 1396958304 14.568 99 62 120 2 1453308352 40613760 15736288
00:05:39 10738 8192000000 10379454032 10811097088 1423136080 14.800 100 39 78 0 1480058800 45846704 11076016
00:07:00 4640 8192000000 10381247072 10816978944 1426352032 14.829 100 42 84 1 1489238896 50874496 12012368
00:08:11 38585 8192000000 10373954480 10801483776 1412310736 14.704 100 40 78 2 1470226496 47018160 10897600
00:09:52 12778 8192000000 10363262000 10802827264 1407210576 14.659 100 30 61 0 1468649280 53101568 8337136
00:11:15 37499 8192000000 10341447600 10797228032 1388225216 14.490 100 17 34 0 1447303664 53898048 5180400
00:12:55 60606 8192000000 10309611504 10774396928 1378792976 14.406 99 19 38 0 1424717440 40948624 4975840
00:14:57 76938 8192000000 10318099072 10760454144 1379728544 14.414 99 11 20 0 1392922848 11194736 1999568
00:17:29 83933 8192000000 10301439856 10754695168 1394466576 14.546 100 5 10 0 1408832928 13252336 1114016
00:19:54 55682 8192000000 10323942000 10765705216 1385576720 14.466 99 37 71 0 1410425040 16351984 8496336
00:21:56 110675 8192000000 10310305984 10772299776 1381439392 14.429 99 21 42 0 1401621600 15822192 4360016
00:25:33 100360 8192000000 10303369696 10763235328 1365051568 14.283 98 14 28 0 1388985568 20372096 3561904
00:28:21 53644 8192000000 10318695920 10758025216 1371482208 14.340 98 62 124 0 1395596416 9690912 14423296
00:30:15 52916 8192000000 10321901568 10778750976 1408879296 14.674 100 18 36 0 1425082256 11974960 4228000
00:32:37 12977 8192000000 10311790144 10765750272 1386192384 14.472 99 34 68 1 1414262928 19981696 8088848
00:33:52 25995 8192000000 10279796848 10744913920 1362320272 14.258 98 14 26 2 1376271824 10455088 3496464
00:35:19 70435 8192000000 10281973952 10744897536 1375365696 14.375 99 14 28 0 1390267104 11666928 3234480
00:37:33 95278 8192000000 10286009904 10749894656 1361772880 14.253 98 19 40 1 1374483008 9008384 3701744
00:40:13 30343 8192000000 10259443488 10720129024 1345038768 14.103 98 23 43 3 1358760256 8551872 5169616
00:41:44 69965 8192000000 10262645216 10726019072 1339583040 14.054 98 12 23 0 1348310112 6667376 2059696
00:43:56 42119 8192000000 10244801968 10707197952 1301592752 13.710 95 13 24 0 1315408576 11075168 2740656
00:45:39 39621 8192000000 10270690144 10719776768 1327714992 13.947 97 26 52 0 1346757360 12629696 6412672
00:47:21 45456 8192000000 10253591440 10711154688 1327320976 13.943 98 11 22 0 1347327728 17094112 2912640
00:49:40 80022 8192000000 10261376448 10726047744 1320910288 13.885 96 11 22 0 1343557680 19857584 2789808
00:52:01 84014 8192000000 10283039232 10714378240 1336520416 14.026 97 25 49 0 1350675296 9254832 4900048
00:54:29 20527 8192000000 10298348800 10748346368 1377493568 14.394 100 29 58 0 1395461264 11786368 6181328
00:55:54 111930 8192000000 10285413648 10736304128 1350232096 14.150 98 38 76 0 1370950544 12730480 7987968
00:58:49 35475 8192000000 10274903648 10747162624 1375214880 14.374 100 12 24 0 1394329968 16501120 2613968
01:00:26 30091 8192000000 10281307280 10736316416 1372042912 14.345 99 26 51 1 1386085088 7917056 6125120
01:01:57 46638 8192000000 10258433024 10720923648 1345304832 14.105 98 9 18 0 1354727312 7426864 1995616
01:04:25 59347 8192000000 10279898112 10734632960 1342435584 14.079 97 24 48 0 1358618416 10621232 5561600
01:06:26 50779 8192000000 10290650672 10738155520 1352949504 14.174 98 26 52 0 1371703360 12395616 6358240
01:08:18 79108 8192000000 10302032912 10760314880 1378921824 14.407 99 17 34 0 1396719440 13948592 3849024
01:10:40 32080 8192000000 10299042912 10752008192 1390045616 14.506 99 22 44 0 1402821920 7912016 4864288
01:12:13 69115 8192000000 10327377776 10776723456 1394200400 14.543 99 15 30 0 1407297552 9921648 3175504
01:14:23 72682 8192000000 10318545680 10787713024 1410192656 14.686 100 17 35 0 1428146400 14262688 3691056
01:16:36 86237 8192000000 10295030176 10746724352 1362053872 14.256 97 35 70 0 1378825968 9259856 7512240
01:19:02 42626 8192000000 10307363632 10766753792 1384857456 14.460 99 24 48 0 1399314864 9459504 4997904
01:20:49 54733 8192000000 10296412864 10768547840 1388447872 14.492 99 20 40 0 1403436496 10277136 4711488
01:22:44 73303 8192000000 10307363424 10778128384 1386773984 14.477 98 15 30 0 1408301984 17956000 3572000
01:25:54 47792 8192000000 10288601056 10752462848 1344008800 14.094 96 18 36 0 1364600032 16203648 4387584
01:27:42 77988 8192000000 10283820832 10748313600 1339815744 14.056 97 28 52 0 1356132256 10870480 5446032
01:30:01 24107 8192000000 10276811552 10717913088 1328283968 13.952 98 45 90 0 1346818096 8552048 9982080
01:31:27 31195 8192000000 10281687728 10720653312 1354530608 14.188 100 34 69 1 1373100960 10807888 7762464
01:32:59 103810 8192000000 10273560032 10726883328 1285556304 13.564 94 18 36 0 1305610752 16215456 3838992
01:35:44 53686 8192000000 10264223360 10714484736 1314817008 13.830 97 17 34 0 1331451344 12759056 3875280
01:37:45 78229 8192000000 9350636208 9647304704 499848400 5.750 37 5 10 0 506766848 5827216 1091232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 170 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-01-2023 01:40:41 to 02-01-2023 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          245873
MON$OLDEST_ACTIVE               245874
MON$OLDEST_SNAPSHOT             245874
MON$NEXT_TRANSACTION            245874
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                       2072352
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.33650 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33650 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33650 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33650 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 ---
:1555: 
:1556: mscs-test-161.budzdorov.ru    Mon Jan  2 01:39:08 2023
:1557:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1558: 
--- end of diff output ---

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

02.01.23 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
2023-01-02 01:40:47 No severe PSQL-related problems occured
02.01.23 01:40:48. End of report.