Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.11.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16077052 kB
MemAvailable 30805332 kB
Buffers 0 kB
Cached 15647800 kB
SwapCached 2456 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.8
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 2021-11-11 01:37:55
DTS_END                         2021-11-11 01:37:55.8670
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/43766
TRN_ID                          234434
ATT_ID                          700
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: 9313.14 20635 1117577
customer order: creation 165.59 356 19871
customer order: refuse 14.13 325 1695
order to supplier: creation 43.75 1320 5250
order to supplier: removal 3.55 108887 426
invoice (draft): creation 45.38 3648 5445
invoice (draft): removal 53.74 850 6449
invoice accept: apply 40.75 19153 4890
invoice accept: cancel 49.00 888 5880
customer reserve: creation 3798.48 189 455817
customer reserve: removal 4810.28 62 577234
realization accept: apply 80.09 171 9611
realization accept: cancel 85.07 116 10208
payment from customer: creation 55.37 104 6644
payment from customer: removal 5.48 75 657
payment to supplier: creation 47.13 125 5656
payment to supplier: removal 3.51 69 421
service: total inventory turnovers 4.48 1133 538
service: total monetary turnovers 6.53 25 783
service: aggregate perf. data 0.82 22620 98
service: refresh index statistics 0.03 252580 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-11-2021 01:39:51 to 11-11-2021 01:39:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2056
TEST_TIME 2 3911
TEST_TIME 3 4760
TEST_TIME 4 5387
TEST_TIME 5 5532
TEST_TIME 6 5440
TEST_TIME 7 5303
TEST_TIME 8 5493
TEST_TIME 9 5481
TEST_TIME 10 5781
TEST_TIME 11 6295
TEST_TIME 12 6189
TEST_TIME 13 6597
TEST_TIME 14 6555
TEST_TIME 15 7018
TEST_TIME 16 7099
TEST_TIME 17 7116
TEST_TIME 18 7497
TEST_TIME 19 7641
TEST_TIME 20 8173
TEST_TIME 21 8583
TEST_TIME 22 8676
TEST_TIME 23 8682
TEST_TIME 24 8901
TEST_TIME 25 8851
TEST_TIME 26 9411
TEST_TIME 27 9654
TEST_TIME 28 10407
TEST_TIME 29 10625
TEST_TIME 30 10499
TEST_TIME 31 10350
TEST_TIME 32 10485
TEST_TIME 33 10280
TEST_TIME 34 10218
TEST_TIME 35 10169
TEST_TIME 36 10013
TEST_TIME 37 9910
TEST_TIME 38 9823
TEST_TIME 39 9649
TEST_TIME 40 9696
TEST_TIME 41 9666
TEST_TIME 42 9665
TEST_TIME 43 9567
TEST_TIME 44 9652
TEST_TIME 45 9513
TEST_TIME 46 9435
TEST_TIME 47 9504
TEST_TIME 48 9468
TEST_TIME 49 9383
TEST_TIME 50 9359
TEST_TIME 51 9388
TEST_TIME 52 9273
TEST_TIME 53 9259
TEST_TIME 54 9228
TEST_TIME 55 9294
TEST_TIME 56 9280
TEST_TIME 57 9282
TEST_TIME 58 9228
TEST_TIME 59 9310
TEST_TIME 60 9379
TEST_TIME 61 9381
TEST_TIME 62 9395
TEST_TIME 63 9494
TEST_TIME 64 9511
TEST_TIME 65 9593
TEST_TIME 66 9552
TEST_TIME 67 9604
TEST_TIME 68 9668
TEST_TIME 69 9598
TEST_TIME 70 9531
TEST_TIME 71 9633
TEST_TIME 72 9557
TEST_TIME 73 9518
TEST_TIME 74 9477
TEST_TIME 75 9492
TEST_TIME 76 9419
TEST_TIME 77 9400
TEST_TIME 78 9388
TEST_TIME 79 9420
TEST_TIME 80 9351
TEST_TIME 81 9390
TEST_TIME 82 9369
TEST_TIME 83 9324
TEST_TIME 84 9352
TEST_TIME 85 9375
TEST_TIME 86 9394
TEST_TIME 87 9442
TEST_TIME 88 9405
TEST_TIME 89 9412
TEST_TIME 90 9461
TEST_TIME 91 9427
TEST_TIME 92 9463
TEST_TIME 93 9535
TEST_TIME 94 9536
TEST_TIME 95 9513
TEST_TIME 96 9450
TEST_TIME 97 9404
TEST_TIME 98 9354
TEST_TIME 99 9421
TEST_TIME 100 9395
TEST_TIME 101 9358
TEST_TIME 102 9347
TEST_TIME 103 9305
TEST_TIME 104 9239
TEST_TIME 105 9202
TEST_TIME 106 9215
TEST_TIME 107 9239
TEST_TIME 108 9227
TEST_TIME 109 9218
TEST_TIME 110 9243
TEST_TIME 111 9314
TEST_TIME 112 9323
TEST_TIME 113 9308
TEST_TIME 114 9351
TEST_TIME 115 9403
TEST_TIME 116 9398
TEST_TIME 117 9418
TEST_TIME 118 9391
TEST_TIME 119 9383
TEST_TIME 120 9318
TEST_TIME 121 9241

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-11-2021 01:39:54 to 11-11-2021 01:39:58.

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 1538984 1538984 0 0.00 0 16757 7 0 0
doc_list_biud 1538981 1538981 0 0.00 0 40165 29 0 0
sp_add_invoice_to_stock 4988 4890 98 1.96 44 167466 19154 0 98
sp_cancel_adding_invoice 5880 5880 0 0.00 5 60792 889 0 0
sp_cancel_client_order 1695 1695 0 0.00 4 8864 325 0 0
sp_cancel_customer_reserve 577234 577234 0 0.00 1 32420 62 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 7689 76 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 2 3950 69 0 0
sp_cancel_supplier_invoice 6460 6449 11 0.17 1 25297 850 0 11
sp_cancel_supplier_order 426 426 0 0.00 7 1228599 108887 0 0
sp_cancel_write_off 10208 10208 0 0.00 1 9909 116 0 0
sp_client_order 19871 19871 0 0.00 3 34809 357 0 0
sp_customer_reserve 455817 455817 0 0.00 0 139672 189 0 0
sp_fill_shopping_cart 30559 30559 0 0.00 1 12178 39 0 0
sp_fill_shopping_cart_clo_res 455808 455808 0 0.00 0 30270 14 0 0
sp_get_clo_for_invoice 4879 4879 0 0.00 0 37589 285 0 0
sp_kill_cost_storno 17166 17166 0 0.00 0 9039 60 0 0
sp_kill_qstorno_ret_qs2qd 1171551 1171551 0 0.00 0 32414 34 0 0
sp_kill_qty_storno 616381 616381 0 0.00 0 40165 73 0 0
sp_lock_selected_doc 20141 20141 0 0.00 0 7821 5 0 0
sp_make_cost_storno 26789 26789 0 0.00 0 16749 58 0 0
sp_multiply_rows_for_pdistr 26789 26789 0 0.00 0 7785 25 0 0
sp_multiply_rows_for_qdistr 19871 19871 0 0.00 0 13387 150 0 0
sp_pay_from_customer 6644 6644 0 0.00 4 10302 105 0 0
sp_pay_to_supplier 5656 5656 0 0.00 4 10660 125 0 0
sp_payment_common 12300 12300 0 0.00 1 7797 32 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 7956 297 0 0
sp_qd_handle_on_invoice_upd_sts 10758 10758 0 0.00 0 40163 372 0 0
sp_qd_handle_on_reserve_upd_sts 9611 9611 0 0.00 0 13668 58 0 0
sp_reserve_write_off 9611 9611 0 0.00 6 13685 172 0 0
sp_supplier_invoice 5445 5445 0 0.00 6 89826 3648 0 0
sp_supplier_order 5250 5250 0 0.00 5 75964 1320 0 0
srv_aggregate_perf_data 98 98 0 0.00 2456 324775 22620 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2337 143290 50566 0 0
srv_find_qd_qs_mism 1070999 1070999 0 0.00 0 14951 6 0 0
srv_make_invnt_saldo 538 538 0 0.00 26 33215 1134 0 0
srv_make_money_saldo 783 783 0 0.00 0 998 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 122069 400035 252581 0 0
t$perf-abend:sp_add_invoice_to_stock 98 98 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 4890 4890 0 0.00 0 29106 188 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 6332 136 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 9213 20 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1252 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 3482 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 3362 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 538 10 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 23825 1764 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 8893 17 0 0
t$perf-norm:sp_client_order 19871 19871 0 0.00 0 19513 20 0 0
t$perf-norm:sp_customer_reserve 945 945 0 0.00 0 1884 10 0 0
t$perf-norm:sp_pay_from_customer 6644 6644 0 0.00 0 9398 15 0 0
t$perf-norm:sp_pay_to_supplier 5656 5656 0 0.00 0 6280 15 0 0
t$perf-norm:sp_reserve_write_off 9611 9611 0 0.00 0 24762 25 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 8750 27 0 0
t$perf-norm:sp_supplier_order 5250 5250 0 0.00 0 10778 22 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 184 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 2 0 0 0
t$perf-norm:srv_make_invnt_saldo 538 538 0 0.00 0 12029 41 0 0
t$perf-norm:srv_make_money_saldo 783 783 0 0.00 0 1213 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9940 9744 196 1.97 0 5852 19 0 196
v_all_customers 35790 35790 0 0.00 0 17 1 0 0
v_all_suppliers 29636 29636 0 0.00 0 25 1 0 0
v_all_wares 39740 39740 0 0.00 0 113 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 86 6 0 0
v_cancel_client_order 3390 3390 0 0.00 0 2345 5 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 3121 8 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 1883 5 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 466 4 0 0
v_cancel_supplier_invoice 756 734 22 2.91 0 530 15 0 22
v_cancel_supplier_order 852 852 0 0.00 0 1604 9 0 0
v_cancel_write_off 2086 2086 0 0.00 0 3164 14 0 0
v_max_id_clo_ord 5247 5247 0 0.00 0 11927 4 0 0
v_max_id_clo_res 945 945 0 0.00 0 7314 22 0 0
v_max_id_ord_sup 5440 5440 0 0.00 0 3688 4 0 0
v_max_non_paid_invoice 1525 1525 0 0.00 0 4297 5 0 0
v_max_non_paid_realizn 3603 3603 0 0.00 0 4614 5 0 0
v_min_id_clo_ord 5247 5247 0 0.00 0 3310 7 0 0
v_min_id_clo_res 945 945 0 0.00 0 3997 12 0 0
v_min_id_ord_sup 5440 5440 0 0.00 0 6683 8 0 0
v_min_non_paid_invoice 5656 5656 0 0.00 0 7794 26 0 0
v_min_non_paid_realizn 6644 6644 0 0.00 0 7687 23 0 0
v_our_firm 10032 10032 0 0.00 0 16 1 0 0
v_reserve_write_off 19222 19222 0 0.00 0 3504 5 0 0
x_lock_depdocs_on_canc_invoice 11962 11962 0 0.00 0 18556 142 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 7960 385 0 0
x_make_qstorno_1000_1200 5250 5250 0 0.00 1 75926 1237 0 0
x_make_qstorno_1200_2000 5445 5445 0 0.00 3 89805 3516 0 0
x_make_qstorno_2100_3300 455817 455817 0 0.00 0 139671 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-11-2021 01:39:59 to 11-11-2021 01:40:03.

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:07:59 15 8192000000 8804757040 8818036736 8589392 0.104 4 1 2 0 8691680 59504 42784
23:08:59 19219 8192000000 9877658544 10181869568 961589488 10.505 100 86 172 0 989480864 5513424 22377952
23:10:21 6358 8192000000 9920321520 10236198912 1008614128 10.962 100 86 172 0 1038544560 7806912 22123520
23:11:31 20445 8192000000 9964874688 10290106368 1041394976 11.278 100 86 172 0 1072620704 9695408 21530320
23:12:54 13083 8192000000 10013203712 10356248576 1102450480 11.861 100 78 156 1 1132101392 11135648 18515264
23:14:09 9590 8192000000 10066043376 10418733056 1150785040 12.317 100 90 181 1 1184327712 12689536 20853136
23:15:26 5944 8192000000 10114432592 10481618944 1203408432 12.808 100 79 158 3 1234254832 13554880 17291520
23:16:34 2946 8192000000 10155906832 10524176384 1235795712 13.108 100 88 174 1 1269390976 13916080 19679184
23:17:39 2530 8192000000 10183666816 10562154496 1262895488 13.357 100 85 170 0 1294250704 13222288 18132928
23:18:43 2133 8192000000 10210225472 10591576064 1288765792 13.593 100 93 186 0 1323862096 14050800 21045504
23:19:46 15720 8192000000 10209884624 10598035456 1298704096 13.683 100 74 148 8 1324125584 8740816 16680672
23:21:02 7210 8192000000 10205207152 10612051968 1308281488 13.770 100 25 50 1 1324651824 11290064 5080272
23:22:12 8758 8192000000 10198233520 10615652352 1314875472 13.830 100 28 54 0 1328664864 7176208 6613184
23:23:27 6685 8192000000 10214733216 10633781248 1324567776 13.918 100 25 50 1 1336557344 6726000 5263568
23:24:34 26866 8192000000 10248221600 10648637440 1330197744 13.969 100 62 123 1 1355875104 10872880 14804480
23:26:01 6909 8192000000 10235472592 10649968640 1335297616 14.015 100 38 76 1 1350327872 6219408 8810848
23:27:08 5624 8192000000 10219708048 10638725120 1324217104 13.915 99 34 68 0 1338644080 6666400 7760576
23:28:14 21620 8192000000 10194332496 10616967168 1282103152 13.532 97 38 75 1 1296555664 5507424 8945088
23:29:42 5505 8192000000 10167020240 10567331840 1237948128 13.127 97 29 58 2 1250468032 6009760 6510144
23:30:48 8739 8192000000 10103983168 10500624384 1155124112 12.358 92 20 40 3 1164997280 5287120 4586048
23:31:57 65606 8192000000 10137984512 10544504832 1219739264 12.959 97 38 77 1 1234559600 5855232 8965104
23:34:04 7098 8192000000 10166089728 10570518528 1274018960 13.458 100 23 46 0 1285163312 5843120 5301232
23:35:12 8009 8192000000 10184654608 10590941184 1284539632 13.554 100 21 41 1 1294480400 5385584 4555184
23:36:20 56328 8192000000 10191918096 10606714880 1298658304 13.683 100 38 76 1 1313914208 5554896 9701008
23:38:20 39400 8192000000 10245134496 10653331456 1337105824 14.031 100 56 110 0 1358447776 9165024 12176928
23:40:05 9895 8192000000 10268721168 10686222336 1357830672 14.218 100 56 114 0 1384867856 14362224 12674960
23:41:16 6619 8192000000 10279988416 10696720384 1365599216 14.288 100 49 98 1 1392122448 15193616 11329616
23:42:26 6960 8192000000 10296940992 10713772032 1376391904 14.384 100 62 123 0 1406902960 16298576 14212480
23:43:37 21494 8192000000 10306891632 10727321600 1386652816 14.476 100 41 82 0 1412993152 15651776 10688560
23:45:02 8020 8192000000 10307888992 10724614144 1377387744 14.393 99 63 121 1 1410586320 18803648 14394928
23:46:15 9838 8192000000 10326109120 10750033920 1396221792 14.561 99 46 92 1 1428309216 20385952 11701472
23:47:28 5282 8192000000 10337816032 10756354048 1407525296 14.662 100 66 131 0 1448105440 24871808 15708336
23:48:36 2431 8192000000 10353811776 10769739776 1416345360 14.740 100 79 156 2 1458074528 23337744 18391424
23:49:45 5126 8192000000 10373842944 10788904960 1426735776 14.832 100 85 168 4 1476377936 29007360 20634800
23:50:57 3811 8192000000 10346895360 10766295040 1402369648 14.616 99 58 111 2 1443979120 27444384 14165088
23:52:08 2334 8192000000 10361161744 10776948736 1412584608 14.707 100 72 142 1 1464881808 33995360 18301840
23:53:19 3014 8192000000 10351789520 10763763712 1405489952 14.644 100 73 146 3 1456640192 32385728 18764512
23:54:24 5097 8192000000 10356277280 10771509248 1405017632 14.640 100 72 143 1 1461806368 37635184 19153552
23:55:42 4429 8192000000 10340138912 10748608512 1358814448 14.227 97 78 153 1 1421045968 42613984 19617536
23:56:49 3993 8192000000 10367883840 10772140032 1394332848 14.545 100 74 148 0 1464948096 50678384 19936864
23:57:58 2415 8192000000 10367443232 10773753856 1401030928 14.604 100 89 178 1 1468151392 44602736 22517728
23:59:04 16252 8192000000 10350214736 10773553152 1398692224 14.583 100 38 76 0 1452756896 43390096 10674576
00:00:23 7333 8192000000 10357424608 10789990400 1416840992 14.745 100 42 84 0 1467865120 40017056 11007072
00:01:45 26541 8192000000 10328585792 10764869632 1392416304 14.527 100 32 64 1 1445756816 44703376 8637136
00:03:21 37391 8192000000 10288814400 10737475584 1332907184 13.993 98 20 40 2 1383970672 44703360 6360128
00:05:16 18222 8192000000 10288989424 10723835904 1331599456 13.982 99 29 58 0 1388549744 49288240 7662048
00:06:45 128391 8192000000 10251750704 10698022912 1313056432 13.814 97 29 58 0 1341525536 21020192 7448912
00:09:55 100115 8192000000 10268203616 10717429760 1364826288 14.281 100 22 44 0 1383626000 14026832 4772880
00:12:48 55943 8192000000 10272570768 10721185792 1374041856 14.363 100 15 30 1 1385513168 7559664 3911648
00:14:46 63122 8192000000 10301735008 10746253312 1375295136 14.374 99 28 54 2 1396277600 14845216 6137248
00:16:59 54072 8192000000 10284393152 10744205312 1384498880 14.457 100 19 37 1 1410242192 20615232 5128080
00:19:49 72760 8192000000 10291858896 10739974144 1358413504 14.223 98 28 57 0 1380063984 14524528 7125952
00:22:19 43336 8192000000 10285627008 10740658176 1382368512 14.438 100 14 28 0 1399648256 14159040 3120704
00:24:10 46317 8192000000 10283652352 10751635456 1382026880 14.435 100 20 41 0 1401676016 14825232 4823904
00:25:57 89062 8192000000 10294527824 10757820416 1388312224 14.491 100 24 48 1 1404608016 10893536 5402256
00:28:43 90922 8192000000 10282051408 10750402560 1350935936 14.156 97 8 16 0 1369627200 17306800 1384464
00:31:14 26868 8192000000 10300558512 10747994112 1389708928 14.503 100 31 62 0 1402850176 6254160 6887088
00:32:46 100265 8192000000 10282653168 10742341632 1328199600 13.951 95 17 34 1 1343219792 11482640 3537552
00:35:27 24674 8192000000 10292345856 10739150848 1373732880 14.360 99 32 64 1 1392555424 11509376 7313168
00:36:53 84823 8192000000 10298365728 10756571136 1353644384 14.180 97 20 40 0 1374294400 16287744 4362272
00:39:19 120764 8192000000 10302360832 10759626752 1367477024 14.304 98 11 22 0 1391981648 22090736 2413888
00:42:20 96019 8192000000 10306448752 10744627200 1329518064 13.963 95 20 39 2 1346487344 12956016 4013264
00:44:58 26708 8192000000 10298616976 10747478016 1372685120 14.351 100 23 46 1 1391326528 13134256 5507152
00:46:25 116707 8192000000 10287663440 10734456832 1337734208 14.037 97 37 72 2 1360534000 14447616 8352176
00:49:24 42799 8192000000 10255099248 10708226048 1325650144 13.928 97 18 36 0 1342544608 12173920 4720544
00:51:11 83730 8192000000 10269069728 10732240896 1335251920 14.015 97 16 32 0 1355688608 16571840 3864848
00:54:17 67533 8192000000 10286620032 10749059072 1368496544 14.314 99 19 38 0 1394687824 21592976 4598304
00:56:26 56470 8192000000 10289277984 10744664064 1379511232 14.412 100 13 25 0 1398187552 15547760 3128560
00:58:27 24400 8192000000 10264911648 10712870912 1354772688 14.190 99 16 32 0 1365192816 6583696 3836432
00:59:52 60113 8192000000 10297482640 10734407680 1343869808 14.092 98 29 58 0 1361787440 10528592 7389040
01:02:01 71425 8192000000 10247620656 10697949184 1263903824 13.366 92 13 24 0 1281090304 14561104 2625376
01:04:13 61413 8192000000 10259059856 10715856896 1332406864 13.989 98 21 42 0 1350924224 14084048 4433312
01:06:19 109744 8192000000 10291097440 10740297728 1343747040 14.091 97 15 30 0 1357811920 10789744 3275136
01:09:12 138382 8192000000 10295244048 10746425344 1376958384 14.389 99 7 14 0 1393943184 15334400 1650400
01:12:38 143290 8192000000 10323908304 10772725760 1391595568 14.520 99 21 41 1 1403665376 7837568 4232240
01:16:05 85266 8192000000 10283059696 10747408384 1349670464 14.145 96 9 18 0 1361524976 9920144 1934368
01:18:37 28965 8192000000 10290070848 10746224640 1390278192 14.508 100 21 41 2 1401330336 5992368 5059776
01:20:17 65767 8192000000 10286458432 10748633088 1379928976 14.416 99 10 20 0 1387760592 5762272 2069344
01:22:23 75109 8192000000 10274660512 10739056640 1359718560 14.235 98 8 16 0 1371767008 10165776 1882672
01:24:41 50221 8192000000 10288578048 10747998208 1384795152 14.459 100 11 22 1 1396091664 8720320 2576192
01:26:44 75462 8192000000 10299114032 10758205440 1393787088 14.540 100 14 28 0 1409069232 11988528 3293616
01:29:03 66460 8192000000 10311476080 10762268672 1393622496 14.538 100 17 34 0 1406644768 9231088 3791184
01:31:10 65419 8192000000 10307290176 10775293952 1367227744 14.302 98 26 52 1 1394331648 21375728 5728176
01:33:20 56839 8192000000 10272987168 10717593600 1275028464 13.468 92 14 28 0 1291423712 13262480 3132768
01:35:19 29378 8192000000 10295918864 10734829568 1357535744 14.215 99 38 76 1 1378910816 13073536 8301536
01:36:51 90085 8192000000 9180149120 9449824256 338277888 3.965 25 8 14 2 347080208 7008752 1793568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 11-11-2021 01:40:05 to 11-11-2021 01:40:19.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 98 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 11-11-2021 01:40:20 to 11-11-2021 01:40:22.

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          234739
MON$OLDEST_ACTIVE               234740
MON$OLDEST_SNAPSHOT             234740
MON$NEXT_TRANSACTION            234740
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                       2026336
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33533 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33533 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33533 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33533 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'

11.11.21 01:40:23. 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
2021-11-11 01:40:26 No severe PSQL-related problems occured
11.11.21 01:40:27. End of report.