Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.01.2023 16: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 15591604 kB
MemAvailable 30452952 kB
Buffers 0 kB
Cached 16150108 kB
SwapCached 3736 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-31 16:38:35
DTS_END                         2023-01-31 16:38:35.9280
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/49842
TRN_ID                          232790
ATT_ID                          609
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: 9111.38 27034 1093365
customer order: creation 160.98 376 19317
customer order: refuse 14.55 350 1746
order to supplier: creation 43.25 1244 5190
order to supplier: removal 3.13 113784 375
invoice (draft): creation 44.84 3598 5381
invoice (draft): removal 51.23 873 6148
invoice accept: apply 40.82 18686 4898
invoice accept: cancel 46.11 839 5533
customer reserve: creation 3859.20 181 463104
customer reserve: removal 4563.79 60 547655
realization accept: apply 81.17 179 9740
realization accept: cancel 77.63 114 9315
payment from customer: creation 56.91 115 6829
payment from customer: removal 5.57 83 668
payment to supplier: creation 46.60 138 5592
payment to supplier: removal 3.49 67 419
service: total inventory turnovers 4.95 926 594
service: total monetary turnovers 6.36 23 763
service: aggregate perf. data 0.78 21649 94
service: refresh index statistics 0.03 377404 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 31-01-2023 16:40:11 to 31-01-2023 16:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6219
TEST_TIME 2 4991
TEST_TIME 3 5594
TEST_TIME 4 5308
TEST_TIME 5 4852
TEST_TIME 6 4810
TEST_TIME 7 4589
TEST_TIME 8 4757
TEST_TIME 9 5179
TEST_TIME 10 5627
TEST_TIME 11 5769
TEST_TIME 12 6126
TEST_TIME 13 6098
TEST_TIME 14 6260
TEST_TIME 15 6445
TEST_TIME 16 6427
TEST_TIME 17 6817
TEST_TIME 18 6978
TEST_TIME 19 7308
TEST_TIME 20 7992
TEST_TIME 21 7995
TEST_TIME 22 8268
TEST_TIME 23 8275
TEST_TIME 24 8395
TEST_TIME 25 8874
TEST_TIME 26 9316
TEST_TIME 27 10066
TEST_TIME 28 10699
TEST_TIME 29 10588
TEST_TIME 30 10518
TEST_TIME 31 10471
TEST_TIME 32 10236
TEST_TIME 33 10180
TEST_TIME 34 10163
TEST_TIME 35 10018
TEST_TIME 36 9940
TEST_TIME 37 9879
TEST_TIME 38 9663
TEST_TIME 39 9664
TEST_TIME 40 9517
TEST_TIME 41 9400
TEST_TIME 42 9302
TEST_TIME 43 9282
TEST_TIME 44 9292
TEST_TIME 45 9270
TEST_TIME 46 9153
TEST_TIME 47 9037
TEST_TIME 48 9057
TEST_TIME 49 9069
TEST_TIME 50 9153
TEST_TIME 51 9146
TEST_TIME 52 9093
TEST_TIME 53 9167
TEST_TIME 54 9266
TEST_TIME 55 9338
TEST_TIME 56 9277
TEST_TIME 57 9256
TEST_TIME 58 9355
TEST_TIME 59 9463
TEST_TIME 60 9503
TEST_TIME 61 9443
TEST_TIME 62 9463
TEST_TIME 63 9409
TEST_TIME 64 9342
TEST_TIME 65 9273
TEST_TIME 66 9181
TEST_TIME 67 9243
TEST_TIME 68 9260
TEST_TIME 69 9275
TEST_TIME 70 9308
TEST_TIME 71 9256
TEST_TIME 72 9221
TEST_TIME 73 9283
TEST_TIME 74 9324
TEST_TIME 75 9280
TEST_TIME 76 9352
TEST_TIME 77 9341
TEST_TIME 78 9309
TEST_TIME 79 9432
TEST_TIME 80 9417
TEST_TIME 81 9477
TEST_TIME 82 9441
TEST_TIME 83 9342
TEST_TIME 84 9344
TEST_TIME 85 9286
TEST_TIME 86 9278
TEST_TIME 87 9286
TEST_TIME 88 9263
TEST_TIME 89 9228
TEST_TIME 90 9186
TEST_TIME 91 9119
TEST_TIME 92 9114
TEST_TIME 93 9099
TEST_TIME 94 9187
TEST_TIME 95 9166
TEST_TIME 96 9190
TEST_TIME 97 9219
TEST_TIME 98 9266
TEST_TIME 99 9247
TEST_TIME 100 9251
TEST_TIME 101 9330
TEST_TIME 102 9316
TEST_TIME 103 9311
TEST_TIME 104 9359
TEST_TIME 105 9336
TEST_TIME 106 9379
TEST_TIME 107 9354
TEST_TIME 108 9430
TEST_TIME 109 9371
TEST_TIME 110 9300
TEST_TIME 111 9295
TEST_TIME 112 9257
TEST_TIME 113 9235
TEST_TIME 114 9227
TEST_TIME 115 9223
TEST_TIME 116 9194
TEST_TIME 117 9182
TEST_TIME 118 9167
TEST_TIME 119 9164
TEST_TIME 120 9119

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-01-2023 16:40:15 to 31-01-2023 16: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 1519257 1519257 0 0.00 0 14594 6 0 0
doc_list_biud 1519255 1519255 0 0.00 0 58338 28 0 0
sp_add_invoice_to_stock 4981 4898 83 1.66 38 176965 18686 0 83
sp_cancel_adding_invoice 5533 5533 0 0.00 4 86008 839 0 0
sp_cancel_client_order 1746 1746 0 0.00 4 11894 351 0 0
sp_cancel_customer_reserve 547655 547655 0 0.00 0 58339 61 0 0
sp_cancel_pay_from_customer 668 668 0 0.00 2 9978 83 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 1702 67 0 0
sp_cancel_supplier_invoice 6156 6148 8 0.12 1 16853 873 0 8
sp_cancel_supplier_order 375 375 0 0.00 6 1066326 113784 0 0
sp_cancel_write_off 9315 9315 0 0.00 1 11387 115 0 0
sp_client_order 19317 19317 0 0.00 3 23837 377 0 0
sp_customer_reserve 463104 463104 0 0.00 0 42606 182 0 0
sp_fill_shopping_cart 29877 29877 0 0.00 1 14138 43 0 0
sp_fill_shopping_cart_clo_res 463095 463095 0 0.00 0 21444 15 0 0
sp_get_clo_for_invoice 4886 4886 0 0.00 0 43976 314 0 0
sp_kill_cost_storno 15935 15935 0 0.00 0 9974 56 0 0
sp_kill_qstorno_ret_qs2qd 1111148 1111148 0 0.00 0 58334 33 0 0
sp_kill_qty_storno 585398 585398 0 0.00 0 58338 72 0 0
sp_lock_selected_doc 20157 20157 0 0.00 0 7151 6 0 0
sp_make_cost_storno 27047 27047 0 0.00 0 14570 60 0 0
sp_multiply_rows_for_pdistr 27047 27047 0 0.00 0 9175 27 0 0
sp_multiply_rows_for_qdistr 19317 19317 0 0.00 0 11702 171 0 0
sp_pay_from_customer 6829 6829 0 0.00 4 15475 116 0 0
sp_pay_to_supplier 5592 5592 0 0.00 4 19997 139 0 0
sp_payment_common 12421 12421 0 0.00 1 15680 32 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 8152 310 0 0
sp_qd_handle_on_invoice_upd_sts 10419 10419 0 0.00 0 31909 343 0 0
sp_qd_handle_on_reserve_upd_sts 9740 9740 0 0.00 0 8364 48 0 0
sp_reserve_write_off 9740 9740 0 0.00 6 14121 180 0 0
sp_supplier_invoice 5381 5381 0 0.00 5 109729 3598 0 0
sp_supplier_order 5190 5190 0 0.00 6 31956 1244 0 0
srv_aggregate_perf_data 94 94 0 0.00 2724 393111 21649 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1615 121678 45642 0 0
srv_find_qd_qs_mism 1045581 1045581 0 0.00 0 12912 5 0 0
srv_make_invnt_saldo 594 594 0 0.00 31 25981 927 0 0
srv_make_money_saldo 763 763 0 0.00 1 555 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 68670 601072 377405 0 0
t$perf-abend:sp_add_invoice_to_stock 83 83 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4900 4900 0 0.00 0 10666 150 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 0 2267 78 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 12993 24 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 2227 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 668 668 0 0.00 0 759 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2190 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 798 21 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 25018 1669 0 0
t$perf-norm:sp_cancel_write_off 916 916 0 0.00 0 4977 13 0 0
t$perf-norm:sp_client_order 19317 19317 0 0.00 0 13322 15 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 6494 12 0 0
t$perf-norm:sp_pay_from_customer 6829 6829 0 0.00 0 4389 9 0 0
t$perf-norm:sp_pay_to_supplier 5592 5592 0 0.00 0 5193 9 0 0
t$perf-norm:sp_reserve_write_off 9740 9740 0 0.00 0 16221 15 0 0
t$perf-norm:sp_supplier_invoice 5381 5381 0 0.00 0 6567 21 0 0
t$perf-norm:sp_supplier_order 5190 5190 0 0.00 0 9506 16 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 123 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 594 594 0 0.00 0 1395 23 0 0
t$perf-norm:srv_make_money_saldo 763 763 0 0.00 0 7708 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 33 10 0 0
v_add_invoice_to_stock 9938 9772 166 1.67 0 11190 19 0 166
v_all_customers 35452 35452 0 0.00 0 16 0 0 0
v_all_suppliers 29176 29176 0 0.00 0 25 1 0 0
v_all_wares 38632 38632 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 1625 12 0 0
v_cancel_client_order 3492 3492 0 0.00 0 11197 10 0 0
v_cancel_customer_prepayment 1336 1336 0 0.00 0 3054 6 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 3079 6 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1670 7 0 0
v_cancel_supplier_invoice 776 760 16 2.06 0 1913 15 0 16
v_cancel_supplier_order 750 750 0 0.00 0 2588 11 0 0
v_cancel_write_off 1832 1832 0 0.00 0 7393 20 0 0
v_max_id_clo_ord 5190 5190 0 0.00 0 2586 2 0 0
v_max_id_clo_res 965 965 0 0.00 0 2362 12 0 0
v_max_id_ord_sup 5371 5371 0 0.00 0 2040 2 0 0
v_max_non_paid_invoice 1564 1564 0 0.00 0 227 2 0 0
v_max_non_paid_realizn 3587 3587 0 0.00 0 3568 5 0 0
v_min_id_clo_ord 5190 5190 0 0.00 0 7149 11 0 0
v_min_id_clo_res 965 965 0 0.00 0 4765 11 0 0
v_min_id_ord_sup 5371 5371 0 0.00 0 5308 10 0 0
v_min_non_paid_invoice 5592 5592 0 0.00 0 15673 25 0 0
v_min_non_paid_realizn 6829 6829 0 0.00 0 9946 22 0 0
v_our_firm 9664 9664 0 0.00 0 23 1 0 0
v_reserve_write_off 19480 19480 0 0.00 0 11101 8 0 0
x_lock_depdocs_on_canc_invoice 11301 11301 0 0.00 0 35028 131 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 8244 375 0 0
x_make_qstorno_1000_1200 5190 5190 0 0.00 1 31947 1167 0 0
x_make_qstorno_1200_2000 5381 5381 0 0.00 3 109425 3443 0 0
x_make_qstorno_2100_3300 463104 463104 0 0.00 0 35910 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-01-2023 16:40:19 to 31-01-2023 16: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
14:08:39 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:41 27553 8192000000 9915934080 10228293632 997005680 10.849 100 85 170 0 1024320928 5730816 21584432
14:11:14 12057 8192000000 9954808736 10278694912 1038843552 11.254 100 89 178 0 1070016416 8479808 22693056
14:12:38 16142 8192000000 9976086624 10306596864 1059705920 11.454 100 86 172 0 1090984448 9820176 21458352
14:14:09 8453 8192000000 10052637280 10402263040 1137592816 12.193 100 81 162 0 1167988832 11131232 19264784
14:15:20 8256 8192000000 10084570720 10449162240 1169302480 12.490 100 70 139 0 1197549680 12503280 15743920
14:16:31 4963 8192000000 10135279680 10510639104 1227600928 13.032 100 63 126 1 1252362112 10549040 14212144
14:17:40 1529 8192000000 10173755584 10547642368 1254029808 13.275 100 87 175 0 1288396800 15685600 18681392
14:18:44 1112 8192000000 10189271056 10570088448 1282341616 13.534 100 83 166 1 1314532064 14024368 18166080
14:19:46 991 8192000000 10214289472 10598531072 1298541424 13.682 100 86 168 0 1330883696 13671552 18670720
14:20:48 1196 8192000000 10201211600 10599227392 1298824672 13.685 100 69 138 0 1324723728 10393616 15505440
14:21:51 4495 8192000000 10198030832 10606653440 1306882704 13.758 100 38 77 0 1322894016 7584496 8426816
14:23:00 3374 8192000000 10207870880 10619731968 1317102656 13.850 100 28 54 0 1329678000 6248640 6326704
14:24:06 7676 8192000000 10211632720 10628272128 1325652896 13.928 100 17 34 1 1335073600 5652256 3768448
14:25:16 40403 8192000000 10226002000 10647023616 1330381584 13.971 100 28 55 0 1345248032 9157264 5709184
14:27:01 3351 8192000000 10238281680 10656206848 1340071056 14.058 100 35 69 1 1354939088 7018928 7849104
14:28:05 8328 8192000000 10215578608 10639310848 1309544032 13.782 98 18 36 0 1319634992 6058000 4032960
14:29:14 9718 8192000000 10204497520 10623537152 1297222880 13.670 98 26 52 2 1309384816 6064128 6097808
14:30:26 5677 8192000000 10174889344 10585083904 1276007248 13.477 99 30 60 0 1289274352 6330752 6936352
14:31:32 3069 8192000000 10147258848 10544529408 1234498848 13.096 98 30 61 1 1247428784 5351376 7578560
14:32:36 50574 8192000000 10140602464 10550886400 1191823888 12.700 93 25 47 1 1202547264 5316512 5406864
14:34:27 8591 8192000000 10144582816 10545717248 1237837728 13.126 97 18 36 0 1247291856 5312080 4142048
14:35:37 5329 8192000000 10181747856 10592903168 1289647584 13.601 100 27 54 1 1301593760 5617296 6328880
14:36:43 7400 8192000000 10193267568 10608254976 1302765536 13.720 100 15 30 0 1311534528 5592720 3176272
14:37:51 13443 8192000000 10207377712 10617659392 1307603072 13.764 100 30 61 0 1323819488 9768384 6448032
14:39:11 36812 8192000000 10251744640 10660990976 1343170272 14.086 100 60 118 1 1370223488 14046048 13007168
14:40:57 19429 8192000000 10279845200 10689789952 1362175840 14.257 100 56 111 0 1391295808 17128272 11991696
14:42:20 36578 8192000000 10298128608 10720436224 1384669456 14.458 100 40 80 0 1413097328 18934656 9493216
14:44:04 15040 8192000000 10303797232 10728505344 1391983328 14.524 100 42 84 0 1423312912 20897040 10432544
14:45:23 11226 8192000000 10320013568 10740330496 1397727792 14.575 100 53 104 1 1437512768 27175152 12609824
14:46:38 17590 8192000000 10319081232 10734321664 1389314080 14.500 99 43 82 2 1425603744 25870160 10419504
14:48:08 27152 8192000000 10339472112 10770198528 1413446848 14.715 100 43 86 0 1450132624 25469968 11215808
14:49:51 4278 8192000000 10360060976 10774528000 1415647424 14.734 100 81 161 0 1467804944 32357504 19800016
14:51:00 7734 8192000000 10368809488 10800619520 1431462960 14.874 100 51 102 0 1481856128 37180992 13212176
14:52:26 3464 8192000000 10383065136 10806849536 1429354832 14.856 100 66 132 1 1489884976 44033152 16496992
14:53:34 7690 8192000000 10380979760 10804924416 1418717888 14.761 99 62 124 1 1478937408 44886720 15332800
14:54:53 1610 8192000000 10405133264 10817822720 1433411744 14.891 100 85 167 1 1506868496 52113664 21343088
14:56:02 4616 8192000000 10409025088 10824675328 1436888560 14.922 100 80 157 1 1511765760 54849392 20027808
14:57:16 5705 8192000000 10389275168 10804154368 1414406960 14.723 100 81 162 1 1494155760 59902336 19846464
14:58:29 14185 8192000000 10349509520 10792677376 1396477520 14.564 100 36 72 1 1464168784 57642448 10048816
14:59:49 10424 8192000000 10356939440 10785026048 1401791312 14.611 100 39 78 2 1461458064 48892400 10774352
15:01:03 10949 8192000000 10361674160 10796003328 1403404736 14.625 100 30 60 1 1468774128 57000528 8368864
15:02:19 43834 8192000000 10360542752 10797891584 1385542496 14.466 99 23 46 0 1448072720 56065360 6464864
15:04:05 20105 8192000000 10329122976 10770366464 1352653152 14.171 98 22 42 0 1417272416 58125488 6493776
15:05:38 35025 8192000000 10294177008 10751684608 1360109984 14.238 100 16 32 0 1415414784 50655424 4649376
15:07:16 32234 8192000000 10255392128 10685288448 1324534208 13.918 99 43 86 1 1352258848 16685120 11039520
15:09:04 26591 8192000000 10241962272 10690379776 1324961584 13.922 98 11 22 0 1337979648 10933520 2084544
15:10:46 83090 8192000000 10248570272 10696355840 1312185440 13.806 97 13 26 0 1329502032 14850048 2466544
15:13:45 42428 8192000000 10242586208 10679296000 1312321184 13.807 97 15 30 0 1328489328 12489920 3678224
15:15:29 91307 8192000000 10257639008 10703372288 1350323888 14.150 100 30 61 0 1367984816 10099152 7561776
15:18:04 27343 8192000000 10275200800 10707226624 1357681792 14.217 100 44 84 1 1372957824 5172976 10103056
15:19:37 9980 8192000000 10284892576 10725707776 1372833536 14.352 100 48 93 0 1394478880 10393456 11251888
15:20:50 31274 8192000000 10281701200 10726354944 1364161520 14.275 99 32 64 1 1383092800 12291056 6640224
15:22:25 57955 8192000000 10282453808 10741161984 1366822368 14.299 99 18 35 0 1389680944 18769776 4088800
15:24:24 82924 8192000000 10287565744 10742235136 1376967872 14.389 100 18 36 2 1394266544 12921056 4377616
15:27:10 35112 8192000000 10288646656 10744594432 1370122320 14.328 99 21 39 0 1383738112 9320240 4295552
15:28:46 93254 8192000000 10301413360 10755047424 1386449872 14.474 100 12 24 0 1402562288 13564416 2548000
15:31:22 71446 8192000000 10305836112 10771726336 1394469440 14.546 100 13 26 0 1412274928 14392848 3412640
15:34:15 34288 8192000000 10329522416 10774175744 1402680304 14.619 100 37 72 3 1422908128 11668208 8559616
15:35:54 121678 8192000000 10307975664 10767863808 1320568032 13.882 94 18 36 0 1343499392 18824048 4107312
15:39:02 57735 8192000000 10325781280 10770841600 1385678176 14.467 99 34 66 1 1403178144 10408752 7091216
15:41:10 96761 8192000000 10313331472 10774687744 1390745056 14.513 99 33 65 0 1411311328 13507872 7058400
15:43:48 67445 8192000000 10299226544 10765791232 1390858144 14.514 99 12 24 0 1406157936 12682240 2617552
15:45:56 33559 8192000000 10284981472 10734936064 1345592112 14.108 97 18 36 1 1359654928 9712128 4350688
15:47:31 95288 8192000000 10282079200 10732339200 1297772960 13.675 93 19 38 0 1313182336 10794608 4614768
15:50:06 16468 8192000000 10296959632 10724814848 1360239088 14.240 99 61 122 1 1386929360 11855984 14834288
15:51:28 18105 8192000000 10272314976 10715574272 1343034624 14.085 98 25 50 1 1356880224 8419984 5425616
15:52:47 56611 8192000000 10256938160 10693578752 1274832320 13.466 94 15 30 0 1290421008 11782176 3806512
15:54:43 76287 8192000000 10270203056 10705051648 1317763856 13.856 97 20 40 0 1333623456 11394224 4465376
15:57:04 26644 8192000000 10259386528 10713260032 1334170832 14.005 99 27 52 0 1356555488 16442320 5942336
15:58:38 106598 8192000000 10266586512 10718294016 1336628960 14.027 98 12 24 0 1358408400 19308800 2470640
16:02:02 82986 8192000000 10264636432 10717319168 1312668656 13.810 96 26 50 1 1327188352 8805136 5714560
16:05:45 56063 8192000000 10243908224 10702180352 1318822912 13.866 97 10 19 0 1328761504 8086080 1852512
16:07:49 88303 8192000000 10280179056 10731347968 1351426768 14.160 98 20 40 0 1367251600 10964320 4860512
16:10:22 80537 8192000000 10273704592 10728566784 1355686848 14.199 98 18 36 0 1372703744 12847168 4169728
16:12:44 100339 8192000000 10271274832 10731802624 1337139104 14.032 97 10 20 0 1347250352 8518176 1593072
16:15:28 34833 8192000000 10275160096 10733731840 1365485296 14.287 99 25 50 0 1386353280 15117584 5750400
16:17:03 53940 8192000000 10312898640 10762768384 1387955632 14.488 100 24 46 1 1413488688 20433616 5099440
16:19:08 60182 8192000000 10311116224 10779197440 1392571312 14.529 100 20 40 0 1415543632 18460240 4512080
16:22:09 45652 8192000000 10312808288 10772865024 1398624336 14.583 100 26 52 0 1419084464 14888512 5571616
16:23:55 29946 8192000000 10317749440 10772344832 1402843472 14.620 100 29 58 0 1422510096 13226288 6440336
16:25:25 57868 8192000000 10304554576 10761322496 1353703008 14.181 96 35 70 1 1373463424 11301840 8458576
16:27:29 112502 8192000000 10321080688 10775236608 1406776016 14.655 100 20 40 0 1420277280 8686960 4814304
16:30:23 17944 8192000000 10320636144 10777690112 1409108656 14.676 100 32 64 1 1429417008 13117328 7191024
16:31:42 41407 8192000000 10288861840 10750779392 1370529696 14.332 98 5 10 0 1384641120 13006752 1104672
16:33:23 52652 8192000000 10273769120 10732048384 1356828528 14.209 97 13 26 0 1369650208 9414736 3406944
16:35:21 84347 8192000000 10279088864 10751037440 1386479216 14.474 100 15 30 0 1401874320 11859712 3535392
16:37:48 49961 8192000000 9511247728 9835827200 599205584 6.815 43 8 16 0 608481376 7355264 1920528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 83 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-01-2023 16:40:42 to 31-01-2023 16:40:44.

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          229910
MON$OLDEST_ACTIVE               233021
MON$OLDEST_SNAPSHOT             233021
MON$NEXT_TRANSACTION            233021
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                       2002688
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.33657 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33657 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33657 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33657 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 ---
:1052: 
:1053: mscs-test-161.budzdorov.ru    Tue Jan 31 16:38:44 2023
:1054:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1055: 
--- end of diff output ---

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

31.01.23 16:40:45. 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-31 16:40:47 No severe PSQL-related problems occured
31.01.23 16:40:49. End of report.