Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.07.2022 01:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14844132 kB
MemAvailable 31072468 kB
Buffers 0 kB
Cached 16889104 kB
SwapCached 2556 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-07-24 01:38:20
DTS_END                         2022-07-24 01:38:20.5440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59256
TRN_ID                          241357
ATT_ID                          643
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: 9669.38 23442 1160326
customer order: creation 170.08 347 20410
customer order: refuse 15.00 356 1800
order to supplier: creation 46.28 1180 5553
order to supplier: removal 3.78 106411 453
invoice (draft): creation 46.77 3226 5612
invoice (draft): removal 56.89 848 6827
invoice accept: apply 41.98 18017 5037
invoice accept: cancel 51.55 735 6186
customer reserve: creation 3905.23 178 468628
customer reserve: removal 5032.47 62 603896
realization accept: apply 84.00 191 10080
realization accept: cancel 87.12 99 10454
payment from customer: creation 58.86 112 7063
payment from customer: removal 5.81 100 697
payment to supplier: creation 48.39 136 5807
payment to supplier: removal 3.48 72 418
service: total inventory turnovers 4.45 1205 534
service: total monetary turnovers 6.53 29 783
service: aggregate perf. data 0.70 27268 84
service: refresh index statistics 0.03 308262 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-07-2022 01:40:13 to 24-07-2022 01:40:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2582
TEST_TIME 2 2872
TEST_TIME 3 2771
TEST_TIME 4 2986
TEST_TIME 5 3691
TEST_TIME 6 4508
TEST_TIME 7 5576
TEST_TIME 8 5904
TEST_TIME 9 6153
TEST_TIME 10 6105
TEST_TIME 11 6139
TEST_TIME 12 6114
TEST_TIME 13 6332
TEST_TIME 14 6452
TEST_TIME 15 6536
TEST_TIME 16 6998
TEST_TIME 17 7309
TEST_TIME 18 7125
TEST_TIME 19 7414
TEST_TIME 20 7317
TEST_TIME 21 7492
TEST_TIME 22 7650
TEST_TIME 23 8059
TEST_TIME 24 7943
TEST_TIME 25 8324
TEST_TIME 26 8417
TEST_TIME 27 8974
TEST_TIME 28 9454
TEST_TIME 29 9922
TEST_TIME 30 10377
TEST_TIME 31 11287
TEST_TIME 32 11295
TEST_TIME 33 11203
TEST_TIME 34 11083
TEST_TIME 35 11109
TEST_TIME 36 11127
TEST_TIME 37 11073
TEST_TIME 38 10940
TEST_TIME 39 10777
TEST_TIME 40 10660
TEST_TIME 41 10460
TEST_TIME 42 10336
TEST_TIME 43 10257
TEST_TIME 44 10132
TEST_TIME 45 10164
TEST_TIME 46 10136
TEST_TIME 47 10155
TEST_TIME 48 10085
TEST_TIME 49 9958
TEST_TIME 50 9900
TEST_TIME 51 10005
TEST_TIME 52 9936
TEST_TIME 53 9826
TEST_TIME 54 9773
TEST_TIME 55 9681
TEST_TIME 56 9613
TEST_TIME 57 9501
TEST_TIME 58 9540
TEST_TIME 59 9526
TEST_TIME 60 9587
TEST_TIME 61 9667
TEST_TIME 62 9714
TEST_TIME 63 9662
TEST_TIME 64 9752
TEST_TIME 65 9717
TEST_TIME 66 9720
TEST_TIME 67 9744
TEST_TIME 68 9943
TEST_TIME 69 9920
TEST_TIME 70 9926
TEST_TIME 71 9877
TEST_TIME 72 9881
TEST_TIME 73 9814
TEST_TIME 74 9777
TEST_TIME 75 9742
TEST_TIME 76 9673
TEST_TIME 77 9646
TEST_TIME 78 9657
TEST_TIME 79 9602
TEST_TIME 80 9580
TEST_TIME 81 9564
TEST_TIME 82 9596
TEST_TIME 83 9564
TEST_TIME 84 9552
TEST_TIME 85 9569
TEST_TIME 86 9583
TEST_TIME 87 9583
TEST_TIME 88 9674
TEST_TIME 89 9677
TEST_TIME 90 9632
TEST_TIME 91 9657
TEST_TIME 92 9698
TEST_TIME 93 9753
TEST_TIME 94 9729
TEST_TIME 95 9785
TEST_TIME 96 9774
TEST_TIME 97 9713
TEST_TIME 98 9654
TEST_TIME 99 9627
TEST_TIME 100 9599
TEST_TIME 101 9613
TEST_TIME 102 9577
TEST_TIME 103 9533
TEST_TIME 104 9496
TEST_TIME 105 9484
TEST_TIME 106 9432
TEST_TIME 107 9430
TEST_TIME 108 9437
TEST_TIME 109 9498
TEST_TIME 110 9464
TEST_TIME 111 9504
TEST_TIME 112 9548
TEST_TIME 113 9538
TEST_TIME 114 9559
TEST_TIME 115 9649
TEST_TIME 116 9702
TEST_TIME 117 9678
TEST_TIME 118 9713
TEST_TIME 119 9735
TEST_TIME 120 9670

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-07-2022 01:40:17 to 24-07-2022 01:40:20.

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 1596258 1596258 0 0.00 0 22167 6 0 0
doc_list_biud 1596255 1596255 0 0.00 0 34776 30 0 0
sp_add_invoice_to_stock 5163 5037 126 2.44 33 177737 18017 0 126
sp_cancel_adding_invoice 6186 6186 0 0.00 4 65883 735 0 0
sp_cancel_client_order 1800 1800 0 0.00 3 12980 357 0 0
sp_cancel_customer_reserve 603896 603896 0 0.00 0 25040 62 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 14253 100 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 3 1701 72 0 0
sp_cancel_supplier_invoice 6836 6827 9 0.13 1 31328 849 0 9
sp_cancel_supplier_order 453 453 0 0.00 11 1184835 106412 0 0
sp_cancel_write_off 10454 10454 0 0.00 1 7326 99 0 0
sp_client_order 20410 20410 0 0.00 3 29636 348 0 0
sp_customer_reserve 468628 468628 0 0.00 0 50371 179 0 0
sp_fill_shopping_cart 31562 31562 0 0.00 1 12388 35 0 0
sp_fill_shopping_cart_clo_res 468623 468623 0 0.00 0 16914 13 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 21071 270 0 0
sp_kill_cost_storno 17755 17755 0 0.00 0 14245 59 0 0
sp_kill_qstorno_ret_qs2qd 1225526 1225526 0 0.00 0 34773 34 0 0
sp_kill_qty_storno 644728 644728 0 0.00 0 34776 72 0 0
sp_lock_selected_doc 21034 21034 0 0.00 0 9823 6 0 0
sp_make_cost_storno 27982 27982 0 0.00 0 13810 56 0 0
sp_multiply_rows_for_pdistr 27982 27982 0 0.00 0 14239 25 0 0
sp_multiply_rows_for_qdistr 20410 20410 0 0.00 0 14874 151 0 0
sp_pay_from_customer 7063 7063 0 0.00 4 14410 112 0 0
sp_pay_to_supplier 5807 5807 0 0.00 4 14431 136 0 0
sp_payment_common 12870 12870 0 0.00 1 14235 34 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 12888 317 0 0
sp_qd_handle_on_invoice_upd_sts 11218 11218 0 0.00 0 19141 318 0 0
sp_qd_handle_on_reserve_upd_sts 10080 10080 0 0.00 0 15489 65 0 0
sp_reserve_write_off 10080 10080 0 0.00 6 16112 191 0 0
sp_supplier_invoice 5612 5612 0 0.00 6 78556 3226 0 0
sp_supplier_order 5553 5553 0 0.00 4 28895 1180 0 0
srv_aggregate_perf_data 84 84 0 0.00 2917 485453 27268 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1017 115269 44945 0 0
srv_find_qd_qs_mism 1113506 1113506 0 0.00 0 22167 5 0 0
srv_make_invnt_saldo 534 534 0 0.00 29 126891 1205 0 0
srv_make_money_saldo 783 783 0 0.00 1 1904 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 174078 501608 308262 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5037 5037 0 0.00 0 14967 182 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 0 5259 96 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 7241 20 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 667 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 737 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1806 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 253 8 0 0
t$perf-norm:sp_cancel_supplier_order 453 453 0 0.00 0 25354 1700 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 1108 5 0 0
t$perf-norm:sp_client_order 20410 20410 0 0.00 0 9003 18 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 4244 7 0 0
t$perf-norm:sp_pay_from_customer 7063 7063 0 0.00 0 8020 11 0 0
t$perf-norm:sp_pay_to_supplier 5807 5807 0 0.00 0 7083 13 0 0
t$perf-norm:sp_reserve_write_off 10080 10080 0 0.00 0 9800 18 0 0
t$perf-norm:sp_supplier_invoice 5612 5612 0 0.00 0 6623 16 0 0
t$perf-norm:sp_supplier_order 5553 5553 0 0.00 0 3537 15 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 420 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 582 11 0 0
t$perf-norm:srv_make_invnt_saldo 534 534 0 0.00 0 2135 26 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 1069 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10316 10064 252 2.44 0 5140 19 0 252
v_all_customers 37096 37096 0 0.00 0 111 1 0 0
v_all_suppliers 30658 30658 0 0.00 0 64 1 0 0
v_all_wares 40820 40820 0 0.00 0 145 1 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 2268 11 0 0
v_cancel_client_order 3600 3600 0 0.00 0 2356 6 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1762 5 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 4271 7 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 832 6 0 0
v_cancel_supplier_invoice 844 826 18 2.13 0 2676 20 0 18
v_cancel_supplier_order 906 906 0 0.00 0 2688 7 0 0
v_cancel_write_off 2016 2016 0 0.00 0 5196 17 0 0
v_max_id_clo_ord 5548 5548 0 0.00 0 611 1 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 3558 20 0 0
v_max_id_ord_sup 5604 5604 0 0.00 0 3172 4 0 0
v_max_non_paid_invoice 1627 1627 0 0.00 0 1732 4 0 0
v_max_non_paid_realizn 3897 3897 0 0.00 0 657 3 0 0
v_min_id_clo_ord 5548 5548 0 0.00 0 2182 5 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 1833 6 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 5246 5 0 0
v_min_non_paid_invoice 5807 5807 0 0.00 0 12895 24 0 0
v_min_non_paid_realizn 7063 7063 0 0.00 1 13836 29 0 0
v_our_firm 10054 10054 0 0.00 0 117 1 0 0
v_reserve_write_off 20160 20160 0 0.00 0 8218 8 0 0
x_lock_depdocs_on_canc_invoice 12600 12600 0 0.00 0 11840 107 0 0
x_lock_depdocs_on_canc_sup_ord 453 453 0 0.00 0 12487 348 0 0
x_make_qstorno_1000_1200 5553 5553 0 0.00 1 28881 1105 0 0
x_make_qstorno_1200_2000 5612 5612 0 0.00 3 78472 3104 0 0
x_make_qstorno_2100_3300 468627 468627 0 0.00 0 46553 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-07-2022 01:40:21 to 24-07-2022 01:40:25.

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:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:25 25087 8192000000 9916712752 10231033856 994545984 10.826 100 90 180 0 1023978096 6065840 23366272
23:10:53 17355 8192000000 9956038576 10281132032 1040278192 11.267 100 81 162 0 1069526080 8564432 20683456
23:12:22 15444 8192000000 9987097680 10319753216 1067720864 11.530 100 86 172 0 1099371328 9879744 21770720
23:13:47 5753 8192000000 10039850128 10387709952 1124163248 12.066 100 77 154 0 1153603440 10687216 18752976
23:14:59 3974 8192000000 10088395056 10448130048 1176124912 12.554 100 83 166 1 1207923088 12502112 19296064
23:16:06 5893 8192000000 10135526784 10503749632 1222460720 12.984 100 78 157 0 1252410896 13188080 16762096
23:17:13 1388 8192000000 10166854192 10540236800 1255672160 13.290 100 94 188 2 1289304640 12579824 21052656
23:18:16 1002 8192000000 10199737056 10579042304 1283222192 13.542 100 88 178 2 1316558544 13511552 19824800
23:19:19 812 8192000000 10217316576 10600972288 1302265600 13.716 100 88 174 0 1335353600 14056560 19031440
23:20:23 1148 8192000000 10226759072 10616860672 1312407552 13.808 100 82 164 0 1342657104 11909360 18340192
23:21:24 5748 8192000000 10202479024 10614546432 1316056944 13.841 100 26 52 0 1328126496 6402384 5667168
23:22:36 11430 8192000000 10212446736 10628075520 1327234416 13.942 100 15 31 0 1336555072 5815744 3504912
23:23:48 4181 8192000000 10224813216 10639863808 1334673904 14.009 100 29 58 0 1348717552 7406912 6636736
23:24:57 12870 8192000000 10219667360 10653896704 1340158528 14.059 100 29 57 0 1353665760 7399696 6107536
23:26:46 2972 8192000000 10241954496 10659102720 1342886992 14.083 100 38 76 0 1359269632 7999024 8383616
23:27:49 6387 8192000000 10241242096 10657722368 1340292720 14.060 100 27 54 1 1352800672 6192560 6315392
23:29:00 9057 8192000000 10189688752 10603360256 1294523264 13.645 100 18 36 0 1308672496 10552320 3596912
23:30:13 30065 8192000000 10153102272 10552733696 1228261440 13.038 95 44 88 1 1244305520 5421696 10622384
23:31:44 8459 8192000000 10143476208 10544951296 1216025744 12.925 96 29 60 1 1228539840 5321952 7192144
23:32:55 4612 8192000000 10122940848 10517045248 1200596560 12.782 97 23 41 0 1209649872 4538448 4514864
23:34:02 9310 8192000000 10167566384 10576117760 1277657552 13.492 100 23 44 0 1288374848 5552688 5164608
23:35:21 8686 8192000000 10166913600 10579464192 1279872000 13.512 100 20 40 1 1289601120 5061872 4667248
23:36:35 6686 8192000000 10193304464 10606387200 1300413344 13.699 100 35 68 0 1315301248 6493728 8394176
23:37:43 10382 8192000000 10196857728 10615939072 1310114464 13.787 100 16 30 1 1318828528 5132048 3582016
23:39:00 13892 8192000000 10245200000 10655281152 1341125520 14.068 100 37 72 0 1356347840 6836016 8386304
23:40:21 91927 8192000000 10254226288 10680872960 1359553888 14.233 100 34 65 0 1376528976 9378608 7596480
23:43:01 11279 8192000000 10276458752 10700144640 1370560112 14.332 100 34 68 1 1391081440 12770096 7751232
23:44:17 12656 8192000000 10290478272 10715283456 1381487136 14.430 100 47 94 1 1408759488 15462288 11810064
23:45:33 8413 8192000000 10295415088 10722807808 1379958752 14.416 100 43 86 0 1406141616 15898224 10284640
23:46:44 2289 8192000000 10316409040 10743631872 1397253168 14.571 100 62 124 1 1430320672 18910352 14157152
23:47:51 2144 8192000000 10317031200 10740469760 1392996608 14.533 100 59 118 0 1424407568 18447328 12963632
23:48:58 6154 8192000000 10325399568 10748379136 1396840208 14.567 100 68 135 4 1434607296 22109568 15657520
23:50:12 7744 8192000000 10339736208 10763415552 1408126496 14.667 100 55 107 0 1448645264 26443568 14075200
23:51:23 9432 8192000000 10346446576 10765471744 1401370592 14.607 99 68 134 1 1452020272 34337840 16311840
23:52:36 4440 8192000000 10345102160 10760548352 1397577376 14.573 100 62 123 0 1450990272 38347936 15064960
23:53:44 3536 8192000000 10366880144 10787577856 1406092448 14.649 99 63 124 0 1466622368 44939424 15590496
23:54:54 1371 8192000000 10357138272 10769178624 1403057392 14.622 100 78 149 0 1463489328 42139920 18292016
23:56:01 4229 8192000000 10342781648 10754727936 1387692352 14.485 99 66 131 0 1444733024 40140576 16900096
23:57:12 5058 8192000000 10374926032 10788372480 1415099056 14.729 100 73 144 0 1483972496 50423456 18449984
23:58:25 1017 8192000000 10385521376 10801168384 1418502448 14.759 100 82 161 2 1489247088 50178096 20566544
23:59:34 4229 8192000000 10372669008 10783531008 1399841808 14.594 100 71 135 0 1473216176 55691504 17682864
00:00:44 12345 8192000000 10379916592 10802143232 1405840496 14.647 100 49 98 0 1481784016 61807632 14135888
00:02:00 7333 8192000000 10387796384 10806951936 1411860896 14.700 100 54 107 1 1485439488 58911808 14666784
00:03:09 13927 8192000000 10371818288 10810183680 1403757712 14.628 100 40 80 0 1482911376 67799152 11354512
00:04:25 31039 8192000000 10364302800 10798850048 1374607888 14.368 99 36 72 1 1462049312 76479264 10962160
00:05:57 21954 8192000000 10367134800 10828787712 1386960640 14.479 100 31 62 0 1472207136 76250464 8996032
00:07:25 19923 8192000000 10315916832 10773479424 1347493472 14.125 99 20 40 0 1419131776 65338752 6299552
00:08:57 47058 8192000000 10297789568 10760790016 1367790640 14.307 100 12 25 0 1421723056 50674512 3257904
00:10:57 99413 8192000000 10285156800 10729160704 1313764192 13.820 95 9 18 1 1334604240 18997840 1842208
00:13:43 105133 8192000000 10295815952 10746404864 1341124928 14.068 97 13 25 0 1358178032 14629936 2423168
00:16:30 83487 8192000000 10283600624 10726723584 1352686544 14.172 97 27 57 0 1367670608 8385888 6598176
00:19:23 42509 8192000000 10289923008 10742841344 1382172432 14.436 100 36 70 0 1401945616 11580656 8192528
00:21:19 60845 8192000000 10311613648 10758238208 1383416176 14.447 99 24 48 0 1398431456 10147824 4867456
00:23:23 45911 8192000000 10302539328 10749403136 1386609504 14.476 99 38 76 0 1408667088 13327472 8730112
00:25:15 50736 8192000000 10292625696 10760294400 1395249952 14.553 100 10 20 0 1406626640 9170096 2206592
00:27:20 102682 8192000000 10318020960 10781454336 1399794240 14.593 99 13 26 0 1418057104 14918400 3344464
00:30:55 84834 8192000000 10310202240 10778886144 1410149248 14.685 100 20 39 0 1421839888 7391696 4298944
00:33:21 28305 8192000000 10312147024 10781700096 1410554912 14.689 100 27 51 1 1424933680 9163760 5215008
00:34:52 51486 8192000000 10299027552 10763452416 1403511440 14.626 100 16 32 0 1418199792 11110688 3577664
00:36:56 42797 8192000000 10309646656 10783264768 1391479680 14.519 99 12 20 0 1405710032 11928848 2301504
00:38:39 33602 8192000000 10291916720 10749616128 1360162864 14.239 97 26 52 0 1373852416 7920352 5769200
00:40:13 53128 8192000000 10298081824 10749579264 1326254272 13.933 95 9 18 0 1338859360 10655520 1949568
00:42:10 51159 8192000000 10291194816 10752696320 1377309232 14.392 99 22 44 0 1394136512 11998224 4829056
00:44:06 11938 8192000000 10297164512 10739347456 1367765680 14.307 99 44 89 0 1387030672 9131504 10133488
00:45:24 87188 8192000000 10288345408 10772885504 1357856800 14.218 98 16 32 0 1386629712 24992464 3780448
00:47:54 88068 8192000000 10275972560 10726322176 1332950192 13.994 97 11 22 0 1345206720 10091488 2165040
00:50:27 36379 8192000000 10270940144 10704384000 1326621456 13.937 97 47 94 0 1351170048 13985696 10562896
00:52:04 115269 8192000000 10253617072 10697863168 1302615232 13.719 96 22 44 0 1317038688 9629840 4793616
00:55:10 58481 8192000000 10265446848 10710253568 1311957392 13.804 96 32 65 0 1335793920 16891008 6945520
00:57:27 67311 8192000000 10257351184 10702393344 1322717376 13.901 97 15 30 0 1342334464 15995824 3621264
01:00:13 51773 8192000000 10248673504 10711339008 1340125232 14.059 99 14 29 0 1359546752 15738384 3683136
01:02:20 72856 8192000000 10276801840 10712576000 1333917216 14.003 98 43 85 0 1357408736 13246592 10244928
01:04:37 57758 8192000000 10272053664 10718552064 1347862928 14.128 98 23 46 0 1365779792 12390464 5526400
01:07:22 88194 8192000000 10273689840 10714959872 1287493248 13.581 94 12 22 1 1301047712 11015760 2538704
01:09:51 40930 8192000000 10288988752 10731208704 1360358176 14.241 99 18 36 0 1373438672 9150896 3929600
01:11:32 91380 8192000000 10295258320 10743455744 1335851008 14.020 97 25 50 0 1352819616 11801520 5167088
01:14:05 83147 8192000000 10299484144 10750496768 1383434304 14.447 99 17 34 0 1397044320 9994512 3615504
01:17:09 53287 8192000000 10277773424 10730196992 1358110480 14.220 98 19 36 0 1369050192 6961328 3978384
01:19:04 65304 8192000000 10280851440 10743549952 1369105904 14.319 99 21 42 0 1386700752 12650192 4944656
01:21:12 70390 8192000000 10303067104 10759876608 1395647664 14.556 100 20 37 1 1412440928 12238752 4554512
01:23:22 67382 8192000000 10285135216 10742992896 1353108048 14.175 97 12 24 0 1369751984 13741264 2902672
01:25:37 58833 8192000000 10298097312 10763767808 1378653408 14.405 99 15 30 0 1398250688 15963792 3633488
01:27:42 25592 8192000000 10272331408 10726113280 1344950832 14.102 98 16 32 0 1361545184 12566480 4027872
01:29:11 54887 8192000000 10278690736 10736701440 1336676400 14.027 97 23 46 1 1357416800 14684736 6055664
01:31:10 70409 8192000000 10313002016 10764976128 1357427472 14.214 98 27 54 0 1385125808 22078208 5620128
01:33:26 81860 8192000000 10289951984 10745806848 1326512496 13.936 96 11 22 0 1344203280 15336112 2354672
01:35:49 45468 8192000000 10284343808 10733031424 1352125536 14.167 99 24 48 0 1369839120 12534256 5179328
01:37:41 52901 8192000000 9641924144 9998041088 760607008 8.495 56 4 8 0 770852400 9547696 697696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-07-2022 01:40:27 to 24-07-2022 01:40:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-07-2022 01:40:43 to 24-07-2022 01:40:45.

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          241589
MON$OLDEST_ACTIVE               241590
MON$OLDEST_SNAPSHOT             241590
MON$NEXT_TRANSACTION            241590
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                       2065504
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Jul 24 01:38:37 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Jul 24 01:38:37 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sun Jul 24 01:38:37 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Sun Jul 24 01:38:37 2022
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Sun Jul 24 01:38:37 2022
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:80: 
--- end of diff output ---

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

24.07.22 01:40:46. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-07-24 01:40:48 No severe PSQL-related problems occured
24.07.22 01:40:49. End of report.