Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.08.2022 16:41
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 15948464 kB
MemAvailable 30954460 kB
Buffers 0 kB
Cached 15798228 kB
SwapCached 2520 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-08-12 16:38:32
DTS_END                         2022-08-12 16:38:32.3610
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/34344
TRN_ID                          236731
ATT_ID                          669
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: 9440.36 19028 1132843
customer order: creation 165.64 390 19877
customer order: refuse 14.15 371 1698
order to supplier: creation 44.08 1351 5289
order to supplier: removal 3.43 124547 411
invoice (draft): creation 43.78 3283 5253
invoice (draft): removal 54.04 801 6485
invoice accept: apply 40.66 19266 4879
invoice accept: cancel 49.76 844 5971
customer reserve: creation 3817.99 188 458159
customer reserve: removal 4908.01 69 588961
realization accept: apply 81.52 182 9782
realization accept: cancel 93.82 112 11258
payment from customer: creation 56.34 114 6761
payment from customer: removal 5.58 107 670
payment to supplier: creation 45.79 145 5495
payment to supplier: removal 3.24 60 389
service: total inventory turnovers 5.09 1115 611
service: total monetary turnovers 6.56 31 787
service: aggregate perf. data 0.86 22190 103
service: refresh index statistics 0.03 205401 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-08-2022 16:41:41 to 12-08-2022 16:41:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5032
TEST_TIME 2 5684
TEST_TIME 3 6775
TEST_TIME 4 6031
TEST_TIME 5 5825
TEST_TIME 6 5318
TEST_TIME 7 5095
TEST_TIME 8 5174
TEST_TIME 9 5553
TEST_TIME 10 5776
TEST_TIME 11 6029
TEST_TIME 12 6003
TEST_TIME 13 6600
TEST_TIME 14 6549
TEST_TIME 15 6773
TEST_TIME 16 7266
TEST_TIME 17 7202
TEST_TIME 18 7149
TEST_TIME 19 7403
TEST_TIME 20 7496
TEST_TIME 21 7773
TEST_TIME 22 7793
TEST_TIME 23 8362
TEST_TIME 24 8590
TEST_TIME 25 8788
TEST_TIME 26 8807
TEST_TIME 27 8820
TEST_TIME 28 9599
TEST_TIME 29 9700
TEST_TIME 30 10533
TEST_TIME 31 11227
TEST_TIME 32 11167
TEST_TIME 33 11097
TEST_TIME 34 10913
TEST_TIME 35 10897
TEST_TIME 36 10637
TEST_TIME 37 10476
TEST_TIME 38 10390
TEST_TIME 39 10253
TEST_TIME 40 10106
TEST_TIME 41 10136
TEST_TIME 42 10115
TEST_TIME 43 9996
TEST_TIME 44 9911
TEST_TIME 45 9786
TEST_TIME 46 9804
TEST_TIME 47 9681
TEST_TIME 48 9612
TEST_TIME 49 9422
TEST_TIME 50 9571
TEST_TIME 51 9624
TEST_TIME 52 9548
TEST_TIME 53 9485
TEST_TIME 54 9434
TEST_TIME 55 9339
TEST_TIME 56 9253
TEST_TIME 57 9315
TEST_TIME 58 9315
TEST_TIME 59 9378
TEST_TIME 60 9348
TEST_TIME 61 9343
TEST_TIME 62 9319
TEST_TIME 63 9341
TEST_TIME 64 9370
TEST_TIME 65 9403
TEST_TIME 66 9507
TEST_TIME 67 9473
TEST_TIME 68 9435
TEST_TIME 69 9515
TEST_TIME 70 9595
TEST_TIME 71 9563
TEST_TIME 72 9656
TEST_TIME 73 9770
TEST_TIME 74 9669
TEST_TIME 75 9566
TEST_TIME 76 9594
TEST_TIME 77 9530
TEST_TIME 78 9508
TEST_TIME 79 9497
TEST_TIME 80 9495
TEST_TIME 81 9448
TEST_TIME 82 9413
TEST_TIME 83 9405
TEST_TIME 84 9413
TEST_TIME 85 9399
TEST_TIME 86 9416
TEST_TIME 87 9376
TEST_TIME 88 9387
TEST_TIME 89 9453
TEST_TIME 90 9494
TEST_TIME 91 9504
TEST_TIME 92 9519
TEST_TIME 93 9498
TEST_TIME 94 9521
TEST_TIME 95 9545
TEST_TIME 96 9579
TEST_TIME 97 9575
TEST_TIME 98 9602
TEST_TIME 99 9616
TEST_TIME 100 9543
TEST_TIME 101 9498
TEST_TIME 102 9432
TEST_TIME 103 9460
TEST_TIME 104 9431
TEST_TIME 105 9412
TEST_TIME 106 9377
TEST_TIME 107 9393
TEST_TIME 108 9381
TEST_TIME 109 9421
TEST_TIME 110 9405
TEST_TIME 111 9403
TEST_TIME 112 9472
TEST_TIME 113 9443
TEST_TIME 114 9430
TEST_TIME 115 9452
TEST_TIME 116 9423
TEST_TIME 117 9507
TEST_TIME 118 9507
TEST_TIME 119 9520
TEST_TIME 120 9445

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-08-2022 16:41:44 to 12-08-2022 16:41: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 1556983 1556983 0 0.00 0 31602 6 0 0
doc_list_biud 1556982 1556982 0 0.00 0 68418 32 0 0
sp_add_invoice_to_stock 5040 4879 161 3.19 49 165735 19267 0 161
sp_cancel_adding_invoice 5971 5971 0 0.00 4 56419 845 0 0
sp_cancel_client_order 1698 1698 0 0.00 4 12294 371 0 0
sp_cancel_customer_reserve 588961 588961 0 0.00 0 68419 70 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 8595 107 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 2 2355 61 0 0
sp_cancel_supplier_invoice 6491 6485 6 0.09 1 27453 801 0 6
sp_cancel_supplier_order 411 411 0 0.00 7 1106985 124547 0 0
sp_cancel_write_off 11258 11258 0 0.00 1 9834 112 0 0
sp_client_order 19877 19877 0 0.00 3 34667 390 0 0
sp_customer_reserve 458159 458159 0 0.00 0 45857 189 0 0
sp_fill_shopping_cart 30407 30407 0 0.00 1 23039 41 0 0
sp_fill_shopping_cart_clo_res 458151 458151 0 0.00 0 18681 16 0 0
sp_get_clo_for_invoice 4870 4870 0 0.00 0 37221 335 0 0
sp_kill_cost_storno 18288 18288 0 0.00 0 11414 58 0 0
sp_kill_qstorno_ret_qs2qd 1196076 1196076 0 0.00 0 68416 37 0 0
sp_kill_qty_storno 629435 629435 0 0.00 0 68418 79 0 0
sp_lock_selected_doc 20172 20172 0 0.00 0 7256 6 0 0
sp_make_cost_storno 26907 26907 0 0.00 0 31597 55 0 0
sp_multiply_rows_for_pdistr 26907 26907 0 0.00 0 11293 28 0 0
sp_multiply_rows_for_qdistr 19877 19877 0 0.00 0 18352 165 0 0
sp_pay_from_customer 6761 6761 0 0.00 4 18892 115 0 0
sp_pay_to_supplier 5495 5495 0 0.00 4 19486 145 0 0
sp_payment_common 12255 12255 0 0.00 1 18886 38 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 12287 337 0 0
sp_qd_handle_on_invoice_upd_sts 10840 10840 0 0.00 0 14361 335 0 0
sp_qd_handle_on_reserve_upd_sts 9782 9782 0 0.00 0 19956 64 0 0
sp_reserve_write_off 9782 9782 0 0.00 5 19990 183 0 0
sp_supplier_invoice 5253 5253 0 0.00 7 96830 3284 0 0
sp_supplier_order 5289 5289 0 0.00 5 43264 1352 0 0
srv_aggregate_perf_data 103 103 0 0.00 2358 519721 22191 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 976 184992 45743 0 0
srv_find_qd_qs_mism 1086561 1086561 0 0.00 0 9536 5 0 0
srv_make_invnt_saldo 611 611 0 0.00 34 92569 1115 0 0
srv_make_money_saldo 787 787 0 0.00 0 2978 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 99731 329923 205402 0 0
t$perf-abend:sp_add_invoice_to_stock 161 161 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4879 4879 0 0.00 0 9861 179 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 2268 96 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 4503 15 0 0
t$perf-norm:sp_cancel_customer_reserve 645 645 0 0.00 0 2104 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1548 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 455 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 3238 35 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 20785 1852 0 0
t$perf-norm:sp_cancel_write_off 912 912 0 0.00 0 1213 5 0 0
t$perf-norm:sp_client_order 19877 19877 0 0.00 0 5745 17 0 0
t$perf-norm:sp_customer_reserve 982 982 0 0.00 0 3607 15 0 0
t$perf-norm:sp_pay_from_customer 6761 6761 0 0.00 0 10456 14 0 0
t$perf-norm:sp_pay_to_supplier 5495 5495 0 0.00 0 5661 9 0 0
t$perf-norm:sp_reserve_write_off 9782 9782 0 0.00 0 12389 11 0 0
t$perf-norm:sp_supplier_invoice 5253 5253 0 0.00 0 6909 19 0 0
t$perf-norm:sp_supplier_order 5289 5289 0 0.00 0 5650 16 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 183 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 47 1 0 0
t$perf-norm:srv_make_invnt_saldo 611 611 0 0.00 0 1230 19 0 0
t$perf-norm:srv_make_money_saldo 787 787 0 0.00 0 3369 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10060 9738 322 3.20 0 11724 21 0 322
v_all_customers 36278 36278 0 0.00 0 31 1 0 0
v_all_suppliers 29484 29484 0 0.00 0 22 1 0 0
v_all_wares 39754 39754 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 167 6 0 0
v_cancel_client_order 3396 3396 0 0.00 0 6033 6 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 8588 17 0 0
v_cancel_customer_reserve 1290 1290 0 0.00 0 3031 11 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 1949 8 0 0
v_cancel_supplier_invoice 754 742 12 1.59 0 3786 21 0 12
v_cancel_supplier_order 822 822 0 0.00 0 362 5 0 0
v_cancel_write_off 1824 1824 0 0.00 0 2841 18 0 0
v_max_id_clo_ord 5284 5284 0 0.00 0 524 1 0 0
v_max_id_clo_res 982 982 0 0.00 0 5513 17 0 0
v_max_id_ord_sup 5246 5246 0 0.00 0 2998 3 0 0
v_max_non_paid_invoice 1283 1283 0 0.00 0 3277 6 0 0
v_max_non_paid_realizn 3545 3545 0 0.00 0 3837 6 0 0
v_min_id_clo_ord 5284 5284 0 0.00 0 13899 17 0 0
v_min_id_clo_res 982 982 0 0.00 0 5684 21 0 0
v_min_id_ord_sup 5246 5246 0 0.00 0 6490 15 0 0
v_min_non_paid_invoice 5495 5495 0 0.00 0 18858 28 0 0
v_min_non_paid_realizn 6760 6760 0 0.00 0 18880 33 0 0
v_our_firm 9906 9906 0 0.00 0 23 1 0 0
v_reserve_write_off 19564 19564 0 0.00 0 5910 8 0 0
x_lock_depdocs_on_canc_invoice 12085 12085 0 0.00 0 20355 131 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 4708 385 0 0
x_make_qstorno_1000_1200 5289 5289 0 0.00 2 43256 1263 0 0
x_make_qstorno_1200_2000 5253 5253 0 0.00 3 89583 3139 0 0
x_make_qstorno_2100_3300 458159 458159 0 0.00 0 45855 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-08-2022 16:41:49 to 12-08-2022 16:41: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:35 2 8192000000 8798646304 8809971712 4739472 0.057 2 1 2 0 4839072 56816 42784
14:09:36 15541 8192000000 9892173248 10198675456 974644944 10.632 100 85 171 0 1003124000 5870768 22608288
14:10:58 5502 8192000000 9939132128 10259312640 1024577984 11.116 100 88 176 0 1055247616 8285472 22384160
14:12:13 16683 8192000000 9969453248 10297049088 1051081040 11.371 100 86 173 0 1082489008 9627424 21780544
14:13:40 5993 8192000000 10014101568 10358173696 1103995360 11.876 100 74 148 0 1131937872 10402352 17540160
14:14:49 6079 8192000000 10074779568 10428526592 1160297168 12.406 100 85 170 1 1191605712 11688256 19620288
14:15:58 4291 8192000000 10124027664 10490626048 1209874672 12.868 100 90 180 0 1244268576 13954944 20438960
14:17:05 2543 8192000000 10161503760 10537242624 1246385088 13.205 100 85 170 0 1278804080 13751504 18667488
14:18:09 1403 8192000000 10182328800 10560856064 1268714080 13.410 100 91 179 0 1301764992 12776208 20274704
14:19:12 1203 8192000000 10209760496 10592149504 1291595440 13.619 100 93 186 2 1325376032 13519984 20260608
14:20:14 22394 8192000000 10204337856 10599731200 1301861312 13.712 100 51 102 3 1321204176 8368208 10974656
14:21:37 8413 8192000000 10181693136 10598699008 1304677152 13.738 100 26 53 0 1316779744 5788896 6313696
14:22:47 8769 8192000000 10194711840 10616086528 1313715696 13.820 100 31 62 0 1329228352 8211840 7300816
14:23:59 5507 8192000000 10204944224 10624544768 1323159504 13.905 100 17 34 0 1332393040 5700512 3533024
14:25:05 65396 8192000000 10215233440 10640429056 1326659392 13.937 100 18 36 0 1335732288 5073632 3999264
14:27:11 5035 8192000000 10218092864 10638995456 1327583584 13.945 100 35 70 1 1342257920 6573584 8100752
14:28:21 3522 8192000000 10209337664 10623565824 1304517344 13.736 99 28 55 0 1316141408 4799120 6824944
14:29:25 15436 8192000000 10165392320 10562670592 1224797344 13.006 96 27 54 0 1237987920 5413760 7776816
14:30:41 7639 8192000000 10149462704 10550403072 1218006800 12.943 96 47 93 0 1235147952 7435120 9706032
14:31:52 6289 8192000000 10143735712 10544734208 1231947152 13.072 98 26 52 0 1247170656 9708320 5515184
14:33:01 4753 8192000000 10154899776 10552926208 1254402000 13.279 100 23 46 1 1265765040 6175824 5187216
14:34:13 6329 8192000000 10172214544 10578907136 1278946736 13.503 100 30 60 1 1291913680 5463952 7502992
14:35:20 32523 8192000000 10177386464 10591649792 1287954720 13.586 100 23 47 0 1299306496 5950592 5401184
14:36:57 7546 8192000000 10194171984 10607878144 1300430400 13.699 100 33 66 0 1313913792 6124896 7358496
14:38:05 12234 8192000000 10219421984 10630172672 1319337264 13.871 100 28 57 0 1334639472 9589680 5712528
14:39:23 10002 8192000000 10252736672 10660909056 1341165712 14.068 100 52 104 0 1361786176 9278272 11342192
14:40:47 42807 8192000000 10268965328 10683097088 1351435904 14.160 100 39 78 1 1377237712 16202864 9598944
14:42:51 22494 8192000000 10276096400 10696339456 1368474688 14.313 100 46 92 0 1390819232 11744208 10600336
14:44:23 28369 8192000000 10295004192 10715197440 1377979616 14.398 100 38 74 0 1400666384 13959072 8727696
14:46:04 6806 8192000000 10309220848 10724466688 1372261888 14.347 99 53 106 1 1405404672 20777120 12365664
14:47:16 8281 8192000000 10320594656 10740494336 1391602656 14.520 100 47 93 1 1423211216 20033520 11575040
14:48:30 6428 8192000000 10329209808 10743209984 1396732064 14.566 100 73 143 0 1440109344 25737520 17639760
14:49:40 11984 8192000000 10329393392 10746949632 1394280016 14.544 100 64 125 1 1436222592 26749184 15193392
14:51:06 971 8192000000 10348758624 10761310208 1388875536 14.496 99 81 160 1 1438733744 29543536 20314672
14:52:12 8552 8192000000 10341285520 10761281536 1400932848 14.603 100 70 138 1 1449217696 31155152 17129696
14:53:29 1197 8192000000 10349071232 10763763712 1399024144 14.586 100 68 137 0 1456510960 40232176 17254640
14:54:38 2297 8192000000 10350009488 10759299072 1387971648 14.488 99 81 159 0 1451443152 44089472 19382032
14:55:47 2154 8192000000 10346073008 10750402560 1379179488 14.409 100 76 148 0 1444514624 47384016 17951120
14:56:54 6067 8192000000 10356868768 10764320768 1383942704 14.452 100 81 162 1 1459508624 54897680 20668240
14:58:08 11092 8192000000 10356452528 10765467648 1389834320 14.504 100 67 134 0 1466138432 58970272 17333840
14:59:24 2800 8192000000 10367137888 10781622272 1380966752 14.425 99 67 131 3 1461731200 64071616 16692832
15:00:34 2864 8192000000 10379851936 10786754560 1386943056 14.479 100 79 157 0 1478236896 70457776 20836064
15:01:41 4647 8192000000 10370905296 10794545152 1385494416 14.466 100 49 94 1 1474185264 74436560 14254288
15:02:51 13166 8192000000 10374313056 10797711360 1384493072 14.457 99 41 82 0 1478070512 82107232 11470208
15:04:07 20983 8192000000 10375332096 10808999936 1399632064 14.592 100 38 76 0 1484276592 73864464 10780064
15:06:05 13115 8192000000 10363593328 10801602560 1385229616 14.463 100 38 76 0 1477176768 81235872 10711280
15:08:35 43253 8192000000 10261688192 10708865024 1323601680 13.909 98 21 42 0 1395947760 66498928 5847152
15:10:20 77252 8192000000 10280826336 10728771584 1374325248 14.366 100 31 59 0 1391183008 10339408 6518352
15:13:34 71947 8192000000 10272969984 10736427008 1348308400 14.132 98 4 8 0 1365087936 16064160 715376
15:15:46 37689 8192000000 10298320144 10738601984 1382614672 14.440 100 69 136 1 1409483232 10804176 16064384
15:17:31 66747 8192000000 10297140064 10731302912 1369442288 14.322 99 46 92 0 1389937120 9683440 10811392
15:19:38 63711 8192000000 10269132576 10725552128 1363952368 14.273 99 13 26 0 1377266560 10412192 2902000
15:22:41 32526 8192000000 10291259536 10749366272 1389627536 14.503 100 8 16 0 1402195072 10799872 1767664
15:24:18 35620 8192000000 10300303728 10761326592 1396201024 14.561 100 12 22 1 1408713408 10056208 2456176
15:25:54 53568 8192000000 10309662256 10774949888 1406375344 14.652 100 22 44 1 1426950896 15471392 5104160
15:28:21 108429 8192000000 10302377824 10775650304 1402669072 14.619 100 11 22 0 1425826160 20720560 2436528
15:31:25 31726 8192000000 10310491408 10770317312 1407401392 14.661 100 18 36 0 1418872832 7746240 3725200
15:33:03 57382 8192000000 10311563248 10773561344 1398136224 14.578 99 15 28 1 1415569664 14304112 3129328
15:35:21 56307 8192000000 10316486928 10789138432 1413354624 14.714 100 21 42 0 1431794672 13495872 4944176
15:37:32 92545 8192000000 10316474080 10790416384 1403974912 14.630 99 12 24 0 1418928752 12423504 2530336
15:40:05 86606 8192000000 10312368192 10771312640 1366582704 14.296 96 12 24 0 1380458496 11134576 2741216
15:42:32 38668 8192000000 10334401840 10778693632 1409552320 14.680 100 24 49 1 1428357808 13249552 5555936
15:44:15 45634 8192000000 10295761840 10758582272 1351126048 14.158 96 28 56 0 1371553648 14248608 6178992
15:46:09 27964 8192000000 10311303728 10760019968 1367454992 14.304 98 39 79 1 1389852064 14065552 8331520
15:47:50 70845 8192000000 10277605616 10722832384 1311612160 13.801 94 27 54 0 1335049536 17207920 6229456
15:50:06 123137 8192000000 10278964656 10738769920 1333277184 13.997 96 16 32 0 1356292288 19499584 3515520
15:53:10 96604 8192000000 10294371936 10748002304 1360636560 14.243 98 34 68 0 1379849024 11114576 8097888
15:55:49 8888 8192000000 10284587584 10716803072 1365962096 14.291 100 53 105 3 1385735440 8779680 10993664
15:56:58 49513 8192000000 10244534112 10697465856 1323003504 13.904 97 29 56 0 1340447920 11252496 6191920
15:58:54 53966 8192000000 10243443168 10698801152 1338833856 14.047 99 14 26 1 1352282432 10374192 3074384
16:00:48 55744 8192000000 10264549568 10714951680 1359120832 14.229 100 15 29 0 1372661072 10227216 3313024
16:02:44 39441 8192000000 10256547008 10707619840 1328312032 13.952 97 12 24 0 1340172960 9221440 2639488
16:04:28 24879 8192000000 10258500768 10694963200 1329859968 13.966 98 29 53 0 1348178528 12059424 6259136
16:05:59 53325 8192000000 10273354768 10719670272 1347071136 14.121 98 26 50 0 1367406704 14915792 5419776
16:07:56 84335 8192000000 10269628848 10720702464 1322486496 13.899 96 14 28 0 1335096352 9961760 2648096
16:10:22 46779 8192000000 10286635952 10729332736 1359087920 14.229 98 16 32 0 1370360352 8123424 3149008
16:12:14 45564 8192000000 10315492384 10762731520 1395427216 14.554 100 34 68 0 1414196656 11811120 6958320
16:14:07 91160 8192000000 10301535328 10761883648 1377504032 14.394 98 15 29 0 1391570352 11084288 2982032
16:16:39 184992 8192000000 10306357200 10766266368 1382169520 14.436 98 20 40 0 1403011664 16312928 4529216
16:21:04 56418 8192000000 10305492000 10771746816 1401719232 14.610 100 15 30 0 1416714160 11417312 3577616
16:23:01 70651 8192000000 10307101824 10775224320 1401962160 14.612 99 8 16 0 1413889440 10163040 1764240
16:25:20 80242 8192000000 10316544288 10782056448 1385339488 14.464 98 11 22 1 1406904352 19286048 2278816
16:27:42 76157 8192000000 10315694912 10773782528 1377218624 14.392 97 19 38 0 1398337056 16970208 4148224
16:30:02 80199 8192000000 10293562784 10760134656 1377193408 14.391 98 11 22 0 1391519040 11751040 2574592
16:32:24 48503 8192000000 10291030176 10746470400 1351281168 14.159 96 35 70 0 1369276048 10276672 7718208
16:34:14 49490 8192000000 10317713744 10761211904 1382770688 14.441 99 33 66 0 1402283424 12021520 7491216
16:36:05 81601 8192000000 10282915456 10745143296 1318378176 13.862 95 13 26 0 1333795536 12567792 2849568
16:38:27 54654 8192000000 9127247328 9375703040 289624128 3.414 22 4 8 0 296064432 5513600 926704

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 12-08-2022 16:41:55 to 12-08-2022 16:42:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 161 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-08-2022 16:42:10 to 12-08-2022 16:42:12.

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          237009
MON$OLDEST_ACTIVE               237010
MON$OLDEST_SNAPSHOT             237010
MON$NEXT_TRANSACTION            237010
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                       2028512
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.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
--- end of diff output ---

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

12.08.22 16:42:13. 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-08-12 16:42:16 No severe PSQL-related problems occured
12.08.22 16:42:17. End of report.