Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.01.2023 16:40
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 15160728 kB
MemAvailable 30408300 kB
Buffers 0 kB
Cached 16559644 kB
SwapCached 4476 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-27 16:38:40
DTS_END                         2023-01-27 16:38:40.4220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/45730
TRN_ID                          242237
ATT_ID                          708
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: 9677.31 20170 1161277
customer order: creation 173.28 353 20794
customer order: refuse 14.58 379 1750
order to supplier: creation 47.82 1212 5738
order to supplier: removal 3.59 92738 431
invoice (draft): creation 47.78 3204 5733
invoice (draft): removal 55.90 722 6708
invoice accept: apply 42.39 17671 5087
invoice accept: cancel 51.01 779 6121
customer reserve: creation 3931.11 174 471733
customer reserve: removal 5003.32 52 600398
realization accept: apply 86.51 167 10381
realization accept: cancel 88.40 104 10608
payment from customer: creation 59.93 120 7192
payment from customer: removal 6.03 71 724
payment to supplier: creation 49.95 115 5994
payment to supplier: removal 3.73 50 448
service: total inventory turnovers 4.72 1054 566
service: total monetary turnovers 6.48 27 778
service: aggregate perf. data 0.74 28352 89
service: refresh index statistics 0.03 256051 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-01-2023 16:40:41 to 27-01-2023 16:40:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3226
TEST_TIME 2 4535
TEST_TIME 3 4517
TEST_TIME 4 5423
TEST_TIME 5 5749
TEST_TIME 6 5714
TEST_TIME 7 5752
TEST_TIME 8 5663
TEST_TIME 9 5841
TEST_TIME 10 6063
TEST_TIME 11 6449
TEST_TIME 12 6290
TEST_TIME 13 6612
TEST_TIME 14 6836
TEST_TIME 15 6955
TEST_TIME 16 7339
TEST_TIME 17 7636
TEST_TIME 18 7815
TEST_TIME 19 8173
TEST_TIME 20 8616
TEST_TIME 21 8562
TEST_TIME 22 8975
TEST_TIME 23 9078
TEST_TIME 24 9103
TEST_TIME 25 9099
TEST_TIME 26 9341
TEST_TIME 27 9334
TEST_TIME 28 9630
TEST_TIME 29 10200
TEST_TIME 30 10600
TEST_TIME 31 10660
TEST_TIME 32 10714
TEST_TIME 33 10637
TEST_TIME 34 10646
TEST_TIME 35 10638
TEST_TIME 36 10373
TEST_TIME 37 10190
TEST_TIME 38 10231
TEST_TIME 39 10429
TEST_TIME 40 10308
TEST_TIME 41 10161
TEST_TIME 42 10078
TEST_TIME 43 9994
TEST_TIME 44 9898
TEST_TIME 45 10105
TEST_TIME 46 10036
TEST_TIME 47 9877
TEST_TIME 48 9875
TEST_TIME 49 9824
TEST_TIME 50 9732
TEST_TIME 51 9651
TEST_TIME 52 9674
TEST_TIME 53 9598
TEST_TIME 54 9509
TEST_TIME 55 9419
TEST_TIME 56 9523
TEST_TIME 57 9476
TEST_TIME 58 9427
TEST_TIME 59 9522
TEST_TIME 60 9520
TEST_TIME 61 9486
TEST_TIME 62 9509
TEST_TIME 63 9592
TEST_TIME 64 9684
TEST_TIME 65 9640
TEST_TIME 66 9802
TEST_TIME 67 9778
TEST_TIME 68 9791
TEST_TIME 69 9852
TEST_TIME 70 9849
TEST_TIME 71 9958
TEST_TIME 72 9972
TEST_TIME 73 9838
TEST_TIME 74 9829
TEST_TIME 75 9801
TEST_TIME 76 9739
TEST_TIME 77 9727
TEST_TIME 78 9696
TEST_TIME 79 9683
TEST_TIME 80 9615
TEST_TIME 81 9515
TEST_TIME 82 9573
TEST_TIME 83 9516
TEST_TIME 84 9701
TEST_TIME 85 9689
TEST_TIME 86 9745
TEST_TIME 87 9713
TEST_TIME 88 9682
TEST_TIME 89 9795
TEST_TIME 90 9809
TEST_TIME 91 9867
TEST_TIME 92 9832
TEST_TIME 93 9835
TEST_TIME 94 9807
TEST_TIME 95 9831
TEST_TIME 96 9847
TEST_TIME 97 9815
TEST_TIME 98 9738
TEST_TIME 99 9713
TEST_TIME 100 9704
TEST_TIME 101 9666
TEST_TIME 102 9653
TEST_TIME 103 9614
TEST_TIME 104 9594
TEST_TIME 105 9587
TEST_TIME 106 9523
TEST_TIME 107 9527
TEST_TIME 108 9534
TEST_TIME 109 9550
TEST_TIME 110 9531
TEST_TIME 111 9570
TEST_TIME 112 9590
TEST_TIME 113 9626
TEST_TIME 114 9625
TEST_TIME 115 9630
TEST_TIME 116 9611
TEST_TIME 117 9650
TEST_TIME 118 9680
TEST_TIME 119 9698
TEST_TIME 120 9678

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-01-2023 16:40:45 to 27-01-2023 16:40:48.

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 1597952 1597952 0 0.00 0 24213 6 0 0
doc_list_biud 1597952 1597952 0 0.00 0 43599 25 0 0
sp_add_invoice_to_stock 5192 5087 105 2.02 36 176972 17672 0 105
sp_cancel_adding_invoice 6121 6121 0 0.00 3 67870 780 0 0
sp_cancel_client_order 1750 1750 0 0.00 4 17735 379 0 0
sp_cancel_customer_reserve 600398 600398 0 0.00 0 18347 53 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 3 3759 72 0 0
sp_cancel_pay_to_supplier 448 448 0 0.00 3 1305 51 0 0
sp_cancel_supplier_invoice 6718 6708 10 0.14 1 33093 722 0 10
sp_cancel_supplier_order 431 431 0 0.00 6 855364 92739 0 0
sp_cancel_write_off 10608 10608 0 0.00 1 21719 104 0 0
sp_client_order 20794 20794 0 0.00 3 36973 353 0 0
sp_customer_reserve 471733 471733 0 0.00 0 36987 175 0 0
sp_fill_shopping_cart 32242 32242 0 0.00 1 19123 39 0 0
sp_fill_shopping_cart_clo_res 471729 471729 0 0.00 0 12931 13 0 0
sp_get_clo_for_invoice 5083 5083 0 0.00 0 24489 296 0 0
sp_kill_cost_storno 17901 17901 0 0.00 0 6512 52 0 0
sp_kill_qstorno_ret_qs2qd 1218543 1218543 0 0.00 0 33023 29 0 0
sp_kill_qty_storno 641480 641480 0 0.00 0 43599 62 0 0
sp_lock_selected_doc 21403 21403 0 0.00 0 7329 5 0 0
sp_make_cost_storno 28650 28650 0 0.00 0 15150 48 0 0
sp_multiply_rows_for_pdistr 28650 28650 0 0.00 0 24193 28 0 0
sp_multiply_rows_for_qdistr 20794 20794 0 0.00 0 25692 145 0 0
sp_pay_from_customer 7192 7192 0 0.00 4 24219 121 0 0
sp_pay_to_supplier 5994 5994 0 0.00 5 12825 115 0 0
sp_payment_common 13186 13186 0 0.00 1 7806 24 0 0
sp_qd_handle_on_cancel_clo 1750 1750 0 0.00 0 17725 345 0 0
sp_qd_handle_on_invoice_upd_sts 11204 11204 0 0.00 0 43598 300 0 0
sp_qd_handle_on_reserve_upd_sts 10381 10381 0 0.00 0 22713 59 0 0
sp_reserve_write_off 10381 10381 0 0.00 6 22724 168 0 0
sp_supplier_invoice 5733 5733 0 0.00 6 70904 3205 0 0
sp_supplier_order 5738 5738 0 0.00 6 26486 1213 0 0
srv_aggregate_perf_data 89 89 0 0.00 2977 664382 28353 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1419 109433 42917 0 0
srv_find_qd_qs_mism 1112546 1112546 0 0.00 0 19735 5 0 0
srv_make_invnt_saldo 566 566 0 0.00 41 36566 1054 0 0
srv_make_money_saldo 778 778 0 0.00 1 820 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 157453 349137 256051 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 20436 167 0 0
t$perf-norm:sp_cancel_adding_invoice 461 461 0 0.00 0 6508 156 0 0
t$perf-norm:sp_cancel_client_order 1750 1750 0 0.00 0 9042 25 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 4567 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 2382 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 448 448 0 0.00 0 1896 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 435 435 0 0.00 0 5973 37 0 0
t$perf-norm:sp_cancel_supplier_order 431 431 0 0.00 0 36415 1829 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 6576 26 0 0
t$perf-norm:sp_client_order 20794 20794 0 0.00 0 8849 17 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 704 4 0 0
t$perf-norm:sp_pay_from_customer 7192 7192 0 0.00 0 6131 9 0 0
t$perf-norm:sp_pay_to_supplier 5994 5994 0 0.00 0 9025 15 0 0
t$perf-norm:sp_reserve_write_off 10381 10381 0 0.00 0 9761 11 0 0
t$perf-norm:sp_supplier_invoice 5733 5733 0 0.00 0 4608 13 0 0
t$perf-norm:sp_supplier_order 5738 5738 0 0.00 0 7833 18 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 164 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 82 2 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 7055 30 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 7898 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10376 10166 210 2.02 0 6268 16 0 210
v_all_customers 37424 37424 0 0.00 0 26 1 0 0
v_all_suppliers 31374 31374 0 0.00 0 25 1 0 0
v_all_wares 41588 41588 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 922 922 0 0.00 0 1065 8 0 0
v_cancel_client_order 3500 3500 0 0.00 0 4436 9 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 668 4 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1741 4 0 0
v_cancel_payment_to_supplier 896 896 0 0.00 0 339 3 0 0
v_cancel_supplier_invoice 890 870 20 2.24 0 2569 15 0 20
v_cancel_supplier_order 862 862 0 0.00 0 1775 7 0 0
v_cancel_write_off 2038 2038 0 0.00 0 6372 14 0 0
v_max_id_clo_ord 5738 5738 0 0.00 0 594 1 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 3388 15 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 3509 3 0 0
v_max_non_paid_invoice 1755 1755 0 0.00 0 1095 3 0 0
v_max_non_paid_realizn 3971 3971 0 0.00 0 895 3 0 0
v_min_id_clo_ord 5738 5738 0 0.00 0 3231 6 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 1484 3 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 4997 5 0 0
v_min_non_paid_invoice 5994 5994 0 0.00 0 5735 16 0 0
v_min_non_paid_realizn 7192 7192 0 0.00 0 7803 19 0 0
v_our_firm 10606 10606 0 0.00 0 23 1 0 0
v_reserve_write_off 20762 20762 0 0.00 0 8860 7 0 0
x_lock_depdocs_on_canc_invoice 12394 12394 0 0.00 0 23644 114 0 0
x_lock_depdocs_on_canc_sup_ord 431 431 0 0.00 0 4007 285 0 0
x_make_qstorno_1000_1200 5738 5738 0 0.00 1 26471 1134 0 0
x_make_qstorno_1200_2000 5733 5733 0 0.00 3 69167 3066 0 0
x_make_qstorno_2100_3300 471733 471733 0 0.00 0 36986 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-01-2023 16:40:49 to 27-01-2023 16:40:53.

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:43 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:43 32052 8192000000 9901550896 10208792576 975294368 10.638 100 90 180 0 1004829776 6016128 23519280
14:11:19 25149 8192000000 9951503232 10275606528 1024448496 11.115 100 78 156 0 1053083520 8218272 20416752
14:12:50 14165 8192000000 9989706976 10320662528 1071879744 11.570 100 87 174 0 1104087712 10695968 21512000
14:14:06 9131 8192000000 10042005456 10387075072 1124759216 12.072 100 81 158 1 1155444528 12040496 18644816
14:15:19 11840 8192000000 10083071472 10443702272 1165857216 12.458 100 77 154 0 1196662576 13275952 17529408
14:16:34 2950 8192000000 10126452240 10491248640 1213596688 12.902 100 77 153 0 1243329648 12323280 17409680
14:17:39 1725 8192000000 10160168256 10535100416 1244754832 13.190 100 78 155 0 1273287376 11295152 17237392
14:18:42 4309 8192000000 10185763904 10566455296 1274439184 13.462 100 82 164 0 1305160848 13109856 17611808
14:19:48 1946 8192000000 10210989856 10602455040 1298346416 13.680 100 76 152 1 1327984672 12062272 17575984
14:20:51 20219 8192000000 10188336080 10602577920 1301975168 13.713 100 27 54 1 1315767616 7558368 6234080
14:22:12 5622 8192000000 10197233984 10613567488 1316143936 13.842 100 32 62 0 1329955968 5930256 7881776
14:23:18 5922 8192000000 10217333120 10632323072 1327151264 13.941 100 34 66 1 1341929616 7166128 7612224
14:24:29 9928 8192000000 10222897312 10639552512 1337344960 14.033 100 16 32 0 1346353760 5548544 3460256
14:25:39 21721 8192000000 10225180816 10653900800 1340991344 14.066 100 22 42 0 1352182480 6301040 4890096
14:27:02 8326 8192000000 10241135952 10659401728 1337749216 14.037 99 16 32 0 1347239600 5808288 3682096
14:28:14 6799 8192000000 10230694368 10646978560 1325364528 13.925 99 20 38 0 1335588176 5867024 4356624
14:29:21 35973 8192000000 10198862912 10618736640 1258493536 13.316 94 34 68 0 1271668304 5303120 7871648
14:30:57 6380 8192000000 10161240288 10567835648 1262834848 13.356 99 29 58 0 1275913040 6418672 6659520
14:32:05 5752 8192000000 10139045264 10538582016 1249726176 13.236 100 26 52 0 1266217088 11118992 5371920
14:33:16 62361 8192000000 10146122560 10551115776 1240582880 13.152 98 32 64 0 1253226640 4962704 7681056
14:35:19 8290 8192000000 10179161488 10582167552 1280326160 13.516 100 33 66 1 1294027376 6792768 6908448
14:36:30 10332 8192000000 10193804832 10604154880 1297852848 13.676 100 19 38 0 1307369952 5336368 4180736
14:37:41 67297 8192000000 10217891968 10628304896 1314725408 13.829 100 31 60 0 1331118432 9007840 7385184
14:39:55 6832 8192000000 10247550944 10659176448 1343793264 14.092 100 45 92 0 1363016208 9848624 9374320
14:41:07 15494 8192000000 10268713504 10685427712 1360467232 14.242 100 48 95 1 1382662304 11860224 10334848
14:42:40 18284 8192000000 10283775328 10704453632 1372960656 14.354 100 50 98 0 1396421440 12036016 11424768
14:44:04 10132 8192000000 10305532000 10724323328 1387431344 14.483 100 53 102 0 1411818800 12465584 11921872
14:45:25 2691 8192000000 10328680592 10746761216 1406274912 14.651 100 67 133 0 1435831904 14049872 15507120
14:46:31 13720 8192000000 10329671520 10754547712 1411976608 14.701 100 54 108 0 1441333056 16741712 12614736
14:47:53 8328 8192000000 10330191504 10758111232 1415229600 14.730 100 54 108 3 1447603072 18743344 13630128
14:49:07 8569 8192000000 10345869088 10772746240 1425413648 14.821 100 59 118 1 1458675824 18478336 14783840
14:50:21 8315 8192000000 10352066000 10783944704 1426694352 14.832 100 46 87 2 1461959424 23453568 11811504
14:51:32 4362 8192000000 10358980656 10784346112 1428172784 14.845 100 55 110 0 1467435408 25418192 13844432
14:52:43 3807 8192000000 10330386288 10755497984 1400392944 14.598 100 55 106 0 1440887712 27106512 13388256
14:53:53 5912 8192000000 10331536368 10745352192 1387626048 14.485 100 64 128 0 1437959568 33733760 16599760
14:55:05 4822 8192000000 10313645616 10733133824 1374107712 14.364 100 59 114 0 1420736016 32107936 14520368
14:56:18 1417 8192000000 10318151200 10726359040 1363818640 14.272 99 79 153 0 1417134784 33613920 19702224
14:57:27 2103 8192000000 10329645968 10740842496 1375560224 14.377 100 83 167 0 1433404096 37030432 20813440
14:58:34 2635 8192000000 10332780480 10744705024 1376974928 14.389 100 67 134 0 1433588704 39784320 16829456
14:59:40 5365 8192000000 10319285536 10730291200 1362884400 14.263 100 59 117 0 1422456032 44434352 15137280
15:00:55 14227 8192000000 10319338496 10741252096 1373325952 14.357 100 34 68 0 1427310608 45128816 8855840
15:02:12 8519 8192000000 10318345472 10746056704 1371724832 14.342 100 34 66 0 1424553776 44501664 8327280
15:03:25 19540 8192000000 10319072688 10743353344 1359030944 14.229 99 51 98 3 1415231632 43362416 12838272
15:04:47 23243 8192000000 10314727024 10752585728 1377057600 14.390 100 20 40 1 1419574784 36867136 5650048
15:06:19 29781 8192000000 10288583024 10726813696 1338021264 14.040 99 21 41 1 1388783488 44656528 6105696
15:08:13 70621 8192000000 10273178832 10711883776 1343407936 14.088 99 18 36 1 1361831264 14031552 4391776
15:10:33 56077 8192000000 10241169744 10674184192 1265106528 13.377 93 8 16 0 1277469856 11035808 1327520
15:12:40 108121 8192000000 10292073152 10729140224 1372428496 14.349 100 16 32 0 1392500720 16862304 3209920
15:15:32 27456 8192000000 10312393344 10738384896 1381422704 14.429 100 44 87 0 1406193984 13997456 10773824
15:17:08 82414 8192000000 10299305360 10734252032 1367475200 14.304 99 11 22 0 1379271760 9556016 2240544
15:19:34 31932 8192000000 10303867200 10740363264 1388548592 14.493 100 43 86 1 1405123136 7038528 9536016
15:21:07 40884 8192000000 10303330128 10755031040 1394980544 14.550 100 14 28 0 1410452544 12169392 3302608
15:22:49 43586 8192000000 10299800208 10768625664 1388671200 14.494 99 39 74 4 1414897904 17222144 9004560
15:24:33 22107 8192000000 10298747552 10764341248 1393641728 14.538 99 17 34 1 1406578128 8641456 4294944
15:26:21 44918 8192000000 10314763936 10775465984 1412811920 14.709 100 19 38 0 1427673904 10064528 4797456
15:28:13 91500 8192000000 10294137232 10768445440 1384134448 14.453 98 15 30 0 1399521520 11766592 3620480
15:31:04 25045 8192000000 10298966304 10750300160 1391961536 14.523 99 27 54 1 1406370064 8724592 5683936
15:32:32 68635 8192000000 10298736832 10773557248 1405808832 14.647 100 10 20 0 1419753456 11707632 2236992
15:34:44 27390 8192000000 10290645232 10775941120 1384984272 14.461 99 6 10 0 1398656736 12669680 1002784
15:36:21 64375 8192000000 10294335168 10758692864 1382554704 14.439 98 22 44 0 1395941872 8438192 4948976
15:38:36 56665 8192000000 10303549536 10766938112 1374508720 14.367 97 15 29 0 1389932864 12513856 2910288
15:40:33 55842 8192000000 10292460512 10760761344 1358626400 14.225 97 16 32 0 1385137744 22259728 4251616
15:42:31 56516 8192000000 10272632752 10710114304 1281921456 13.531 93 25 50 0 1297869632 10046320 5901856
15:44:34 109430 8192000000 10255455504 10709725184 1297470976 13.672 95 10 20 0 1314116240 14706384 1938880
15:47:23 21950 8192000000 10270094656 10708533248 1334916048 14.012 98 37 74 0 1355233680 12086144 8231488
15:48:46 73019 8192000000 10266460624 10726866944 1343320464 14.087 99 11 22 0 1363733600 17516448 2896688
15:51:09 73499 8192000000 10260787360 10704461824 1335941632 14.021 98 36 72 0 1356228016 12118048 8168336
15:53:24 73330 8192000000 10227703520 10666332160 1299643552 13.692 96 37 74 0 1321551568 12721296 9186720
15:55:38 41430 8192000000 10244782800 10706386944 1344904720 14.102 100 7 14 1 1360265824 13712736 1648368
15:57:21 80025 8192000000 10280543008 10731876352 1365031872 14.283 100 12 24 0 1388139728 20096352 3011504
15:59:42 63149 8192000000 10269625504 10724044800 1340733296 14.064 98 20 40 0 1371677328 25581456 5362576
16:01:49 68240 8192000000 10255007088 10708353024 1324939696 13.921 98 20 40 0 1351417728 21462128 5015904
16:04:02 70125 8192000000 10246221104 10703683584 1316391760 13.844 97 3 6 0 1328737760 11858704 487296
16:06:15 70891 8192000000 10265892704 10726465536 1346916976 14.120 99 24 48 0 1368422576 15702080 5803520
16:08:31 69051 8192000000 10286153536 10737147904 1354770208 14.190 99 18 36 0 1369560032 10649776 4140048
16:10:40 90836 8192000000 10287732528 10732318720 1365715984 14.289 99 16 30 0 1377243392 8536800 2990608
16:13:11 15151 8192000000 10310834912 10742677504 1379777216 14.415 100 47 94 0 1404669456 14816064 10076176
16:14:28 54958 8192000000 10311219280 10747682816 1363949840 14.273 98 35 70 1 1388807568 17123712 7734016
16:16:24 75010 8192000000 10298092000 10754101248 1395967504 14.559 100 14 28 0 1407247120 8447120 2832496
16:18:40 41906 8192000000 10296246592 10756055040 1379604352 14.413 99 13 26 0 1394482208 12185664 2692192
16:20:32 64317 8192000000 10284412576 10739298304 1373958608 14.362 99 8 16 0 1384592848 8555872 2078368
16:22:38 68199 8192000000 10288913840 10753183744 1390589664 14.511 100 8 16 0 1403506496 11106192 1810640
16:25:21 50057 8192000000 10284432928 10754445312 1368785280 14.316 98 14 28 0 1381807824 9802336 3220208
16:27:43 55930 8192000000 10302893952 10765623296 1396408080 14.563 100 14 28 0 1409787920 10435440 2944400
16:30:01 99250 8192000000 10300864832 10763423744 1348747920 14.136 96 21 42 0 1366782000 13406416 4627664
16:32:41 35503 8192000000 10313798192 10750013440 1354550256 14.188 97 42 83 2 1373683616 9370512 9762848
16:34:21 20369 8192000000 10269030832 10705485824 1307720720 13.765 95 38 74 1 1329754976 13868416 8165840
16:35:42 73822 8192000000 10256735984 10708242432 1318142160 13.860 97 16 32 0 1337720976 16310320 3268496
16:38:10 56352 8192000000 9678441504 10038751232 788480512 8.779 59 9 18 0 802054400 11862560 1711328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-01-2023 16:41:11 to 27-01-2023 16:41:13.

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          241287
MON$OLDEST_ACTIVE               242492
MON$OLDEST_SNAPSHOT             242492
MON$NEXT_TRANSACTION            242492
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                       2049056
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 ---
:788: 
:789: mscs-test-161.budzdorov.ru    Fri Jan 27 16:39:23 2023
:790:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:791: 
:792: 
:793: mscs-test-161.budzdorov.ru    Fri Jan 27 16:39:25 2023
:794:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:795: 
:796: 
:797: mscs-test-161.budzdorov.ru    Fri Jan 27 16:39:26 2023
:798:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:799: 
--- end of diff output ---

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

27.01.23 16:41:15. 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-27 16:41:17 No severe PSQL-related problems occured
27.01.23 16:41:18. End of report.