Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.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 15489060 kB
MemAvailable 30614860 kB
Buffers 0 kB
Cached 16233000 kB
SwapCached 2028 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-09-25 01:38:31
DTS_END                         2022-09-25 01:38:31.8730
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/49644
TRN_ID                          246007
ATT_ID                          676
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: 9414.33 24700 1129720
customer order: creation 175.58 356 21069
customer order: refuse 16.26 348 1951
order to supplier: creation 47.01 1259 5641
order to supplier: removal 3.61 97339 433
invoice (draft): creation 47.83 3264 5740
invoice (draft): removal 52.88 881 6346
invoice accept: apply 42.66 18310 5119
invoice accept: cancel 47.08 830 5649
customer reserve: creation 4017.74 178 482129
customer reserve: removal 4677.21 58 561265
realization accept: apply 85.42 177 10250
realization accept: cancel 69.03 122 8284
payment from customer: creation 60.24 127 7229
payment from customer: removal 5.69 81 683
payment to supplier: creation 50.13 136 6016
payment to supplier: removal 3.59 89 431
service: total inventory turnovers 5.18 1181 621
service: total monetary turnovers 6.38 20 766
service: aggregate perf. data 0.78 25618 94
service: refresh index statistics 0.03 343620 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2022 01:39:52 to 25-09-2022 01:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5496
TEST_TIME 2 3630
TEST_TIME 3 4372
TEST_TIME 4 4187
TEST_TIME 5 3629
TEST_TIME 6 4097
TEST_TIME 7 4411
TEST_TIME 8 4950
TEST_TIME 9 5173
TEST_TIME 10 5229
TEST_TIME 11 5265
TEST_TIME 12 5286
TEST_TIME 13 5302
TEST_TIME 14 5347
TEST_TIME 15 5478
TEST_TIME 16 5789
TEST_TIME 17 5961
TEST_TIME 18 6299
TEST_TIME 19 6467
TEST_TIME 20 6837
TEST_TIME 21 7725
TEST_TIME 22 7818
TEST_TIME 23 8388
TEST_TIME 24 8496
TEST_TIME 25 9127
TEST_TIME 26 9614
TEST_TIME 27 10346
TEST_TIME 28 10581
TEST_TIME 29 10590
TEST_TIME 30 10447
TEST_TIME 31 10267
TEST_TIME 32 10135
TEST_TIME 33 10312
TEST_TIME 34 10163
TEST_TIME 35 10109
TEST_TIME 36 10032
TEST_TIME 37 9913
TEST_TIME 38 9832
TEST_TIME 39 9804
TEST_TIME 40 9864
TEST_TIME 41 9997
TEST_TIME 42 9886
TEST_TIME 43 9745
TEST_TIME 44 9727
TEST_TIME 45 9617
TEST_TIME 46 9559
TEST_TIME 47 9530
TEST_TIME 48 9699
TEST_TIME 49 9564
TEST_TIME 50 9612
TEST_TIME 51 9678
TEST_TIME 52 9639
TEST_TIME 53 9598
TEST_TIME 54 9657
TEST_TIME 55 9626
TEST_TIME 56 9859
TEST_TIME 57 9812
TEST_TIME 58 9906
TEST_TIME 59 9875
TEST_TIME 60 9935
TEST_TIME 61 9960
TEST_TIME 62 10043
TEST_TIME 63 9930
TEST_TIME 64 9834
TEST_TIME 65 9761
TEST_TIME 66 9774
TEST_TIME 67 9735
TEST_TIME 68 9664
TEST_TIME 69 9652
TEST_TIME 70 9639
TEST_TIME 71 9671
TEST_TIME 72 9616
TEST_TIME 73 9666
TEST_TIME 74 9723
TEST_TIME 75 9708
TEST_TIME 76 9727
TEST_TIME 77 9702
TEST_TIME 78 9745
TEST_TIME 79 9857
TEST_TIME 80 9822
TEST_TIME 81 9790
TEST_TIME 82 9739
TEST_TIME 83 9771
TEST_TIME 84 9718
TEST_TIME 85 9670
TEST_TIME 86 9657
TEST_TIME 87 9633
TEST_TIME 88 9615
TEST_TIME 89 9559
TEST_TIME 90 9533
TEST_TIME 91 9504
TEST_TIME 92 9500
TEST_TIME 93 9523
TEST_TIME 94 9566
TEST_TIME 95 9585
TEST_TIME 96 9608
TEST_TIME 97 9593
TEST_TIME 98 9612
TEST_TIME 99 9596
TEST_TIME 100 9643
TEST_TIME 101 9658
TEST_TIME 102 9628
TEST_TIME 103 9623
TEST_TIME 104 9631
TEST_TIME 105 9661
TEST_TIME 106 9715
TEST_TIME 107 9710
TEST_TIME 108 9625
TEST_TIME 109 9644
TEST_TIME 110 9635
TEST_TIME 111 9587
TEST_TIME 112 9531
TEST_TIME 113 9550
TEST_TIME 114 9521
TEST_TIME 115 9498
TEST_TIME 116 9457
TEST_TIME 117 9487
TEST_TIME 118 9477
TEST_TIME 119 9467
TEST_TIME 120 9414

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-09-2022 01:39:56 to 25-09-2022 01:39:59.

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 1574153 1574153 0 0.00 0 27535 6 0 0
doc_list_biud 1574153 1574153 0 0.00 0 40228 27 0 0
sp_add_invoice_to_stock 5246 5119 127 2.42 22 176514 18311 0 127
sp_cancel_adding_invoice 5649 5649 0 0.00 4 77956 831 0 0
sp_cancel_client_order 1951 1951 0 0.00 4 13256 348 0 0
sp_cancel_customer_reserve 561265 561265 0 0.00 0 40232 58 0 0
sp_cancel_pay_from_customer 683 683 0 0.00 2 7532 82 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 6930 89 0 0
sp_cancel_supplier_invoice 6357 6346 11 0.17 1 40044 881 0 11
sp_cancel_supplier_order 433 433 0 0.00 7 1297215 97340 0 0
sp_cancel_write_off 8284 8284 0 0.00 1 22108 123 0 0
sp_client_order 21069 21069 0 0.00 3 31521 356 0 0
sp_customer_reserve 482129 482129 0 0.00 0 77611 179 0 0
sp_fill_shopping_cart 32449 32449 0 0.00 1 23320 37 0 0
sp_fill_shopping_cart_clo_res 482129 482129 0 0.00 0 21212 14 0 0
sp_get_clo_for_invoice 5119 5119 0 0.00 0 23665 301 0 0
sp_kill_cost_storno 15047 15047 0 0.00 0 27525 64 0 0
sp_kill_qstorno_ret_qs2qd 1137593 1137593 0 0.00 0 40222 32 0 0
sp_kill_qty_storno 599297 599297 0 0.00 0 40228 69 0 0
sp_lock_selected_doc 21365 21365 0 0.00 0 8728 6 0 0
sp_make_cost_storno 28614 28614 0 0.00 0 26131 59 0 0
sp_multiply_rows_for_pdistr 28614 28614 0 0.00 0 8296 27 0 0
sp_multiply_rows_for_qdistr 21069 21069 0 0.00 0 23624 162 0 0
sp_pay_from_customer 7229 7229 0 0.00 4 36255 127 0 0
sp_pay_to_supplier 6016 6016 0 0.00 4 30813 136 0 0
sp_payment_common 13245 13245 0 0.00 1 9741 31 0 0
sp_qd_handle_on_cancel_clo 1951 1951 0 0.00 0 13235 318 0 0
sp_qd_handle_on_invoice_upd_sts 10768 10768 0 0.00 0 26514 320 0 0
sp_qd_handle_on_reserve_upd_sts 10250 10250 0 0.00 0 9570 53 0 0
sp_reserve_write_off 10250 10250 0 0.00 6 14685 178 0 0
sp_supplier_invoice 5740 5740 0 0.00 7 77935 3265 0 0
sp_supplier_order 5641 5641 0 0.00 4 49214 1260 0 0
srv_aggregate_perf_data 94 94 0 0.00 2252 424532 25619 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 2046 111315 39448 0 0
srv_find_qd_qs_mism 1079546 1079546 0 0.00 0 17837 5 0 0
srv_make_invnt_saldo 621 621 0 0.00 29 146512 1181 0 0
srv_make_money_saldo 766 766 0 0.00 1 397 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 116503 523028 343620 0 0
t$perf-abend:sp_add_invoice_to_stock 127 127 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5119 5119 0 0.00 0 8450 156 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 4922 112 0 0
t$perf-norm:sp_cancel_client_order 1951 1951 0 0.00 0 2116 14 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 4112 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 683 683 0 0.00 0 830 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 1181 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 549 11 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 19345 1490 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 6578 14 0 0
t$perf-norm:sp_client_order 21069 21069 0 0.00 0 6577 15 0 0
t$perf-norm:sp_customer_reserve 999 999 0 0.00 0 5096 26 0 0
t$perf-norm:sp_pay_from_customer 7229 7229 0 0.00 0 9170 13 0 0
t$perf-norm:sp_pay_to_supplier 6016 6016 0 0.00 0 3392 9 0 0
t$perf-norm:sp_reserve_write_off 10250 10250 0 0.00 0 5857 15 0 0
t$perf-norm:sp_supplier_invoice 5740 5740 0 0.00 0 9629 21 0 0
t$perf-norm:sp_supplier_order 5641 5641 0 0.00 0 5739 17 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 128 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 210 4 0 0
t$perf-norm:srv_make_invnt_saldo 621 621 0 0.00 0 6291 28 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 5865 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 20 7 0 0
v_add_invoice_to_stock 10492 10238 254 2.42 0 4175 18 0 254
v_all_customers 38074 38074 0 0.00 0 32 1 0 0
v_all_suppliers 31312 31312 0 0.00 0 19 1 0 0
v_all_wares 42138 42138 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 727 7 0 0
v_cancel_client_order 3902 3902 0 0.00 0 2180 5 0 0
v_cancel_customer_prepayment 1366 1366 0 0.00 0 1467 5 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 569 4 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 2158 6 0 0
v_cancel_supplier_invoice 852 830 22 2.58 0 2465 17 0 22
v_cancel_supplier_order 866 866 0 0.00 0 4314 12 0 0
v_cancel_write_off 1984 1984 0 0.00 0 1643 16 0 0
v_max_id_clo_ord 5641 5641 0 0.00 0 3427 2 0 0
v_max_id_clo_res 999 999 0 0.00 0 5741 21 0 0
v_max_id_ord_sup 5739 5739 0 0.00 0 8052 5 0 0
v_max_non_paid_invoice 1740 1740 0 0.00 0 267 3 0 0
v_max_non_paid_realizn 3966 3966 0 0.00 0 2375 4 0 0
v_min_id_clo_ord 5641 5641 0 0.00 0 4437 7 0 0
v_min_id_clo_res 999 999 0 0.00 0 5998 20 0 0
v_min_id_ord_sup 5739 5739 0 0.00 0 6034 7 0 0
v_min_non_paid_invoice 6016 6016 0 0.00 0 8375 20 0 0
v_min_non_paid_realizn 7229 7229 0 0.00 0 9736 25 0 0
v_our_firm 10590 10590 0 0.00 0 18 1 0 0
v_reserve_write_off 20500 20500 0 0.00 0 3933 6 0 0
x_lock_depdocs_on_canc_invoice 11580 11580 0 0.00 0 21145 124 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 8339 351 0 0
x_make_qstorno_1000_1200 5641 5641 0 0.00 1 49196 1183 0 0
x_make_qstorno_1200_2000 5740 5740 0 0.00 3 71569 3135 0 0
x_make_qstorno_2100_3300 482129 482129 0 0.00 0 77608 162 0 0

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

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:35 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:36 19124 8192000000 9902818032 10209325056 981417712 10.698 100 91 182 0 1011734336 6250640 24065984
23:11:03 9882 8192000000 9935132016 10251796480 1017402112 11.047 100 93 186 0 1049652288 7993328 24256848
23:12:19 14326 8192000000 9972895744 10301022208 1053575360 11.395 100 79 158 0 1082497712 8861920 20060432
23:13:37 12905 8192000000 10040868096 10386272256 1119685472 12.024 100 78 154 1 1147811984 9489216 18637296
23:14:58 4245 8192000000 10080234784 10438942720 1168177792 12.480 100 80 159 0 1198358096 12156304 18024000
23:16:04 3049 8192000000 10127912464 10498179072 1214101184 12.907 100 80 160 1 1245307280 13474912 17731184
23:17:09 2260 8192000000 10158153920 10534748160 1247466288 13.215 100 86 173 0 1279763360 12958240 19338832
23:18:12 1224 8192000000 10190937936 10570080256 1274273344 13.461 100 88 173 0 1307714144 14152320 19288480
23:19:16 1136 8192000000 10209502000 10594779136 1298142352 13.678 100 81 159 0 1327977552 12285728 17549472
23:20:18 8540 8192000000 10216347568 10609152000 1309839248 13.785 100 81 159 1 1340441408 12528096 18074064
23:21:28 6005 8192000000 10202483536 10616107008 1316991552 13.849 100 27 54 1 1334545264 11747472 5806240
23:22:37 5858 8192000000 10206759344 10626355200 1323433936 13.908 100 23 47 0 1335019808 5891232 5694640
23:23:47 9311 8192000000 10212554656 10633555968 1329722496 13.965 100 15 31 0 1339047120 6047648 3276976
23:24:57 43972 8192000000 10237860416 10650984448 1333730016 14.001 100 39 76 1 1348352432 5747056 8875360
23:26:45 3774 8192000000 10231697408 10648915968 1335852800 14.020 100 30 54 2 1348322112 6238096 6231216
23:27:51 8494 8192000000 10229360016 10651852800 1337482896 14.035 100 28 56 0 1350147376 6560752 6103728
23:29:02 10159 8192000000 10189734080 10606063616 1287668176 13.583 99 17 34 1 1297368880 5706752 3993952
23:30:13 20520 8192000000 10112316272 10510565376 1164885392 12.449 90 41 78 1 1179261824 4517680 9858752
23:31:35 10331 8192000000 10136938528 10530455552 1193786272 12.719 95 20 42 2 1203105776 4941744 4377760
23:32:47 8055 8192000000 10145007504 10552406016 1258527616 13.317 100 21 43 0 1268816752 5337488 4951648
23:33:58 8546 8192000000 10166631696 10577301504 1277401024 13.489 100 19 38 0 1287808816 6421920 3985872
23:35:07 33084 8192000000 10172494224 10586451968 1283390896 13.544 100 11 22 0 1291033472 5354832 2287744
23:36:41 8194 8192000000 10199270304 10608459776 1300423200 13.699 100 19 36 1 1309924128 5277312 4223616
23:37:49 10974 8192000000 10200129872 10619031552 1309217904 13.779 100 17 35 0 1318557504 5674976 3664624
23:39:04 20413 8192000000 10247799264 10661527552 1344147120 14.095 100 50 98 1 1361607728 5767088 11693520
23:40:29 89835 8192000000 10258997104 10686783488 1359135472 14.230 100 42 84 0 1380290240 11663904 9490864
23:43:20 34836 8192000000 10295849680 10711916544 1370002192 14.327 100 43 82 0 1392844624 13846240 8996192
23:44:57 8065 8192000000 10296288192 10717167616 1373559472 14.359 99 55 110 0 1413980576 27460576 12960528
23:46:09 9565 8192000000 10315604144 10737836032 1391724496 14.521 100 50 99 0 1430253872 26971392 11557984
23:47:24 9315 8192000000 10324746640 10743480320 1390608144 14.511 100 59 116 2 1431084816 27079616 13397056
23:48:40 8389 8192000000 10323591760 10741493760 1392189280 14.525 100 66 129 1 1435556288 27984592 15382416
23:50:01 4976 8192000000 10343141376 10762842112 1404680384 14.637 100 61 120 0 1451841632 32865952 14295296
23:51:15 2764 8192000000 10338765760 10758057984 1391473792 14.519 99 59 118 0 1441159568 35151952 14533824
23:52:25 9835 8192000000 10326684240 10752737280 1376775504 14.388 98 47 92 0 1423423312 34787440 11860368
23:53:41 4091 8192000000 10377778576 10795978752 1424177312 14.810 100 66 131 0 1482152336 40734128 17240896
23:54:49 2045 8192000000 10366833312 10779467776 1390967040 14.514 98 70 140 0 1451143056 42392096 17783920
23:55:55 2314 8192000000 10355986656 10757431296 1389400032 14.501 100 85 170 1 1455871936 45843952 20627952
23:57:01 2069 8192000000 10355693792 10768130048 1393794656 14.540 100 75 148 1 1462177408 49495184 18887568
23:58:10 2998 8192000000 10339959280 10750136320 1361246032 14.249 99 66 129 0 1433555120 54815472 17493616
23:59:16 6537 8192000000 10337592384 10772140032 1389935392 14.505 100 37 74 1 1446733680 46024480 10773808
00:00:28 21355 8192000000 10297107088 10716790784 1339878368 14.056 99 35 70 0 1392148592 42537120 9733104
00:01:54 39232 8192000000 10297989472 10732994560 1357981904 14.219 100 26 52 2 1416069648 49994432 8093312
00:03:39 25384 8192000000 10306686016 10747699200 1360300224 14.240 100 26 52 1 1407235776 39739872 7195680
00:05:12 47086 8192000000 10282744672 10741788672 1370466768 14.331 100 21 42 1 1405209968 29039984 5703216
00:07:12 59919 8192000000 10264470368 10707243008 1317652544 13.855 96 15 31 1 1329975280 8839824 3482912
00:09:36 57546 8192000000 10298235888 10729451520 1363088176 14.265 99 25 48 0 1376341472 8401776 4851520
00:12:26 49047 8192000000 10242522944 10679156736 1322172368 13.896 97 20 38 0 1334862448 8450960 4239120
00:14:21 53570 8192000000 10278112816 10729836544 1375918096 14.380 100 9 16 0 1388116640 10539744 1658800
00:16:15 18258 8192000000 10293809168 10746322944 1393314640 14.535 100 22 42 1 1409686352 11225296 5146416
00:17:35 51528 8192000000 10299706752 10756292608 1383476704 14.448 99 28 57 1 1407029184 16548720 7003760
00:19:27 32114 8192000000 10284679504 10748059648 1387689648 14.485 100 5 10 0 1398354720 9765872 899200
00:21:00 28456 8192000000 10298630288 10749624320 1389671136 14.503 100 26 52 0 1402626608 7141216 5814256
00:22:34 82097 8192000000 10319010768 10770403328 1385978192 14.470 99 30 60 0 1409387488 16391616 7017680
00:24:57 58468 8192000000 10299661440 10769223680 1389265616 14.499 99 14 28 0 1412260512 19766752 3228144
00:27:02 15593 8192000000 10307344016 10760331264 1404666912 14.637 100 25 46 1 1420800368 10344080 5789376
00:28:18 45303 8192000000 10294918624 10763370496 1396725504 14.566 100 14 26 0 1412326928 12408864 3192560
00:30:04 36904 8192000000 10308069216 10759557120 1377991472 14.399 98 31 62 0 1392607472 7510320 7105680
00:31:59 46695 8192000000 10311486384 10768924672 1403111104 14.623 100 15 30 0 1417615520 11027328 3477088
00:33:58 42028 8192000000 10282990512 10753159168 1347839680 14.128 97 10 20 0 1369369792 19302448 2227664
00:35:42 57053 8192000000 10292955936 10746359808 1380365712 14.420 100 22 44 0 1398265072 12895328 5004032
00:37:41 14515 8192000000 10307521376 10756239360 1390611200 14.511 100 40 80 1 1412537520 13047040 8879280
00:38:59 49823 8192000000 10273296240 10721759232 1294260176 13.643 93 18 36 0 1308325664 9991360 4074128
00:40:56 45732 8192000000 10276535872 10735714304 1370339952 14.330 100 9 18 0 1384244352 11819552 2084848
00:42:43 49454 8192000000 10259670160 10705256448 1319336704 13.871 96 36 72 3 1335990320 8157568 8496048
00:44:34 36962 8192000000 10253645472 10707927040 1341711328 14.073 98 25 50 1 1356723792 9546032 5466432
00:46:33 49676 8192000000 10238840256 10691973120 1301590432 13.710 96 10 20 0 1312280928 8135744 2554752
00:48:23 51297 8192000000 10239090768 10694299648 1308131776 13.769 96 15 30 0 1321642848 9909504 3601568
00:50:18 41891 8192000000 10258445552 10701365248 1351887424 14.164 100 41 81 0 1372860192 11391072 9581696
00:52:02 75443 8192000000 10266066160 10714066944 1353372480 14.178 100 12 24 0 1371933776 15882048 2679248
00:54:18 37734 8192000000 10251123488 10697228288 1333038064 13.995 99 22 44 0 1349901856 11946208 4917584
00:55:56 40397 8192000000 10262377984 10709991424 1311317456 13.798 97 27 53 0 1331824320 14548352 5958512
00:57:39 71585 8192000000 10241274096 10680348672 1267852976 13.402 94 10 20 0 1284506736 14955792 1697968
00:59:53 111315 8192000000 10260011488 10709364736 1332553488 13.990 98 26 52 0 1354339488 15171952 6614048
01:02:44 30698 8192000000 10262520832 10702381056 1354614768 14.189 100 65 128 1 1379084208 9572960 14896480
01:04:20 23016 8192000000 10261292400 10720477184 1370452928 14.331 100 47 94 0 1399333216 18463904 10416384
01:05:45 43083 8192000000 10273361360 10724085760 1371490224 14.340 100 22 43 0 1383348240 6687136 5170880
01:07:38 62033 8192000000 10290565056 10746269696 1390573824 14.511 100 22 44 0 1407354336 11980304 4800208
01:11:15 68358 8192000000 10299785312 10762850304 1383892176 14.451 99 9 18 0 1397733872 11893184 1948512
01:13:27 57453 8192000000 10302571824 10768121856 1381532960 14.430 99 8 16 0 1394541232 11354352 1653920
01:15:35 33927 8192000000 10296015376 10755280896 1381271920 14.428 99 46 92 0 1403771776 11751920 10747936
01:17:10 27506 8192000000 10286659936 10729840640 1349903840 14.147 97 32 64 0 1365690320 8827328 6959152
01:18:47 51064 8192000000 10291345168 10745430016 1346832224 14.119 97 18 36 0 1366960768 15981472 4147072
01:20:41 9691 8192000000 10298423648 10733776896 1374659424 14.369 100 61 118 1 1397209136 8737120 13812592
01:21:55 36406 8192000000 10296747984 10737577984 1366723968 14.298 99 33 66 0 1385888656 12054208 7110480
01:23:34 76689 8192000000 10296221712 10746527744 1340324304 14.060 96 13 26 0 1354887856 11659680 2903872
01:25:51 79960 8192000000 10297941872 10746347520 1391075040 14.515 100 20 40 0 1408019600 12552640 4391920
01:28:11 40691 8192000000 10296044880 10757648384 1379267472 14.410 100 25 50 1 1404547408 19084800 6195136
01:29:52 70604 8192000000 10271875728 10731888640 1357243360 14.213 98 9 18 0 1370500160 11143616 2113184
01:32:03 79950 8192000000 10261295472 10724483072 1354422160 14.187 98 14 26 0 1368487936 11012480 3053296
01:34:24 77632 8192000000 10287248720 10746298368 1366792592 14.298 99 12 24 0 1381256736 11996880 2467264
01:36:43 34130 8192000000 10277683056 10729402368 1354809632 14.191 98 19 38 0 1366860976 7465808 4585536
01:38:21 17998 8192000000 9255721376 9534435328 397813008 4.631 29 6 12 0 409821888 10514480 1494400

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-09-2022 01:40:06 to 25-09-2022 01:40:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 127 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-09-2022 01:40:23 to 25-09-2022 01:40:25.

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          242946
MON$OLDEST_ACTIVE               246262
MON$OLDEST_SNAPSHOT             246262
MON$NEXT_TRANSACTION            246262
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                       2039328
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.33629 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33629 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33629 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33629 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 ---
:53: 
:54: mscs-test-161.budzdorov.ru    Sun Sep 25 01:38:46 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

25.09.22 01:40:26. 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-09-25 01:40:28 No severe PSQL-related problems occured
25.09.22 01:40:30. End of report.