Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.10.2021 19: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 15833328 kB
MemAvailable 30724356 kB
Buffers 0 kB
Cached 15882932 kB
SwapCached 3800 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-10-07 19:37:52
DTS_END                         2021-10-07 19:37:52.2090
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44864
TRN_ID                          244017
ATT_ID                          679
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: 9377.78 17708 1125333
customer order: creation 183.10 357 21972
customer order: refuse 14.88 366 1786
order to supplier: creation 48.43 1429 5812
order to supplier: removal 3.38 103467 406
invoice (draft): creation 48.61 3392 5833
invoice (draft): removal 52.03 787 6244
invoice accept: apply 43.99 18372 5279
invoice accept: cancel 46.86 824 5623
customer reserve: creation 4009.08 184 481090
customer reserve: removal 4624.34 59 554921
realization accept: apply 87.26 194 10471
realization accept: cancel 82.28 107 9874
payment from customer: creation 60.83 118 7299
payment from customer: removal 6.20 115 744
payment to supplier: creation 50.31 128 6037
payment to supplier: removal 3.55 79 426
service: total inventory turnovers 4.97 952 596
service: total monetary turnovers 6.88 31 825
service: aggregate perf. data 0.76 20866 91
service: refresh index statistics 0.03 202324 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-10-2021 19:39:34 to 07-10-2021 19:39:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3232
TEST_TIME 2 4379
TEST_TIME 3 4443
TEST_TIME 4 4968
TEST_TIME 5 5455
TEST_TIME 6 4985
TEST_TIME 7 5045
TEST_TIME 8 5596
TEST_TIME 9 5786
TEST_TIME 10 6048
TEST_TIME 11 6013
TEST_TIME 12 6031
TEST_TIME 13 6389
TEST_TIME 14 6525
TEST_TIME 15 6533
TEST_TIME 16 6860
TEST_TIME 17 7172
TEST_TIME 18 7441
TEST_TIME 19 7415
TEST_TIME 20 7349
TEST_TIME 21 7354
TEST_TIME 22 7817
TEST_TIME 23 8140
TEST_TIME 24 8705
TEST_TIME 25 8906
TEST_TIME 26 8790
TEST_TIME 27 8954
TEST_TIME 28 9223
TEST_TIME 29 9623
TEST_TIME 30 10045
TEST_TIME 31 10005
TEST_TIME 32 10171
TEST_TIME 33 10268
TEST_TIME 34 10327
TEST_TIME 35 10214
TEST_TIME 36 10186
TEST_TIME 37 10158
TEST_TIME 38 10071
TEST_TIME 39 9954
TEST_TIME 40 10067
TEST_TIME 41 10154
TEST_TIME 42 9929
TEST_TIME 43 9802
TEST_TIME 44 9799
TEST_TIME 45 9719
TEST_TIME 46 9553
TEST_TIME 47 9584
TEST_TIME 48 9568
TEST_TIME 49 9591
TEST_TIME 50 9559
TEST_TIME 51 9436
TEST_TIME 52 9369
TEST_TIME 53 9324
TEST_TIME 54 9401
TEST_TIME 55 9624
TEST_TIME 56 9552
TEST_TIME 57 9613
TEST_TIME 58 9573
TEST_TIME 59 9566
TEST_TIME 60 9608
TEST_TIME 61 9663
TEST_TIME 62 9732
TEST_TIME 63 9715
TEST_TIME 64 9772
TEST_TIME 65 9787
TEST_TIME 66 9789
TEST_TIME 67 9781
TEST_TIME 68 9852
TEST_TIME 69 9743
TEST_TIME 70 9629
TEST_TIME 71 9618
TEST_TIME 72 9563
TEST_TIME 73 9531
TEST_TIME 74 9562
TEST_TIME 75 9525
TEST_TIME 76 9448
TEST_TIME 77 9398
TEST_TIME 78 9373
TEST_TIME 79 9288
TEST_TIME 80 9279
TEST_TIME 81 9391
TEST_TIME 82 9447
TEST_TIME 83 9402
TEST_TIME 84 9413
TEST_TIME 85 9436
TEST_TIME 86 9454
TEST_TIME 87 9442
TEST_TIME 88 9454
TEST_TIME 89 9539
TEST_TIME 90 9516
TEST_TIME 91 9466
TEST_TIME 92 9573
TEST_TIME 93 9558
TEST_TIME 94 9567
TEST_TIME 95 9495
TEST_TIME 96 9459
TEST_TIME 97 9434
TEST_TIME 98 9437
TEST_TIME 99 9441
TEST_TIME 100 9401
TEST_TIME 101 9368
TEST_TIME 102 9386
TEST_TIME 103 9348
TEST_TIME 104 9300
TEST_TIME 105 9275
TEST_TIME 106 9291
TEST_TIME 107 9290
TEST_TIME 108 9336
TEST_TIME 109 9330
TEST_TIME 110 9354
TEST_TIME 111 9333
TEST_TIME 112 9388
TEST_TIME 113 9399
TEST_TIME 114 9368
TEST_TIME 115 9401
TEST_TIME 116 9430
TEST_TIME 117 9471
TEST_TIME 118 9397
TEST_TIME 119 9382
TEST_TIME 120 9379

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-10-2021 19:39:38 to 07-10-2021 19:39:41.

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 1570464 1570464 0 0.00 0 25163 7 0 0
doc_list_biud 1570463 1570463 0 0.00 0 43158 27 0 0
sp_add_invoice_to_stock 5426 5279 147 2.70 57 210778 18373 0 147
sp_cancel_adding_invoice 5623 5623 0 0.00 5 63952 825 0 0
sp_cancel_client_order 1786 1786 0 0.00 4 10253 367 0 0
sp_cancel_customer_reserve 554921 554921 0 0.00 1 43160 59 0 0
sp_cancel_pay_from_customer 744 744 0 0.00 3 6266 115 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 3 4929 80 0 0
sp_cancel_supplier_invoice 6262 6244 18 0.28 2 24624 787 0 18
sp_cancel_supplier_order 406 406 0 0.00 6 1001781 103467 0 0
sp_cancel_write_off 9874 9874 0 0.00 1 9644 107 0 0
sp_client_order 21972 21972 0 0.00 3 43958 357 0 0
sp_customer_reserve 481090 481090 0 0.00 0 134962 185 0 0
sp_fill_shopping_cart 33606 33606 0 0.00 1 11263 38 0 0
sp_fill_shopping_cart_clo_res 481084 481084 0 0.00 0 15331 13 0 0
sp_get_clo_for_invoice 5273 5273 0 0.00 0 48381 279 0 0
sp_kill_cost_storno 16667 16667 0 0.00 0 22065 55 0 0
sp_kill_qstorno_ret_qs2qd 1126366 1126366 0 0.00 0 43155 32 0 0
sp_kill_qty_storno 594598 594598 0 0.00 0 43158 69 0 0
sp_lock_selected_doc 21718 21718 0 0.00 0 7415 5 0 0
sp_make_cost_storno 29080 29080 0 0.00 0 25144 54 0 0
sp_multiply_rows_for_pdistr 29080 29080 0 0.00 0 10697 29 0 0
sp_multiply_rows_for_qdistr 21972 21972 0 0.00 0 43944 140 0 0
sp_pay_from_customer 7299 7299 0 0.00 4 13088 118 0 0
sp_pay_to_supplier 6037 6037 0 0.00 4 13290 129 0 0
sp_payment_common 13336 13336 0 0.00 1 10389 25 0 0
sp_qd_handle_on_cancel_clo 1786 1786 0 0.00 0 10224 338 0 0
sp_qd_handle_on_invoice_upd_sts 10896 10896 0 0.00 0 17062 334 0 0
sp_qd_handle_on_reserve_upd_sts 10471 10471 0 0.00 0 18880 69 0 0
sp_reserve_write_off 10471 10471 0 0.00 6 18898 194 0 0
sp_supplier_invoice 5833 5833 0 0.00 6 79902 3392 0 0
sp_supplier_order 5812 5812 0 0.00 6 33250 1430 0 0
srv_aggregate_perf_data 91 91 0 0.00 3302 319550 20867 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1136 140428 40532 0 0
srv_find_qd_qs_mism 1075285 1075285 0 0.00 0 23560 6 0 0
srv_make_invnt_saldo 596 596 0 0.00 25 49793 953 0 0
srv_make_money_saldo 825 825 0 0.00 1 5151 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 60877 366830 202324 0 0
t$perf-abend:sp_add_invoice_to_stock 147 147 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5279 5279 0 0.00 0 28236 192 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 11008 171 0 0
t$perf-norm:sp_cancel_client_order 1786 1786 0 0.00 0 3279 14 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 6284 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 744 744 0 0.00 0 722 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 918 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 2258 22 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 16588 1578 0 0
t$perf-norm:sp_cancel_write_off 1075 1075 0 0.00 0 2541 7 0 0
t$perf-norm:sp_client_order 21972 21972 0 0.00 0 10609 18 0 0
t$perf-norm:sp_customer_reserve 1030 1030 0 0.00 0 2144 5 0 0
t$perf-norm:sp_pay_from_customer 7299 7299 0 0.00 0 5708 10 0 0
t$perf-norm:sp_pay_to_supplier 6037 6037 0 0.00 0 6243 16 0 0
t$perf-norm:sp_reserve_write_off 10471 10471 0 0.00 0 15489 13 0 0
t$perf-norm:sp_supplier_invoice 5833 5833 0 0.00 0 8689 21 0 0
t$perf-norm:sp_supplier_order 5812 5812 0 0.00 0 5837 18 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 162 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 5 1 0 0
t$perf-norm:srv_make_invnt_saldo 596 596 0 0.00 0 950 21 0 0
t$perf-norm:srv_make_money_saldo 825 825 0 0.00 0 2283 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 97 26 0 0
v_add_invoice_to_stock 10828 10534 294 2.71 0 6292 22 0 294
v_all_customers 39328 39328 0 0.00 0 16 1 0 0
v_all_suppliers 31694 31694 0 0.00 0 21 1 0 0
v_all_wares 43944 43944 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 2425 21 0 0
v_cancel_client_order 3572 3572 0 0.00 0 1942 7 0 0
v_cancel_customer_prepayment 1488 1488 0 0.00 0 4555 15 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 3292 9 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 4677 13 0 0
v_cancel_supplier_invoice 814 778 36 4.42 0 2622 21 0 36
v_cancel_supplier_order 812 812 0 0.00 0 2798 16 0 0
v_cancel_write_off 2150 2150 0 0.00 0 3779 17 0 0
v_max_id_clo_ord 5805 5805 0 0.00 0 1267 1 0 0
v_max_id_clo_res 1030 1030 0 0.00 0 3851 12 0 0
v_max_id_ord_sup 5827 5827 0 0.00 0 5029 4 0 0
v_max_non_paid_invoice 1822 1822 0 0.00 0 607 3 0 0
v_max_non_paid_realizn 4079 4079 0 0.00 0 3728 5 0 0
v_min_id_clo_ord 5805 5805 0 0.00 0 3798 8 0 0
v_min_id_clo_res 1030 1030 0 0.00 0 2713 11 0 0
v_min_id_ord_sup 5827 5827 0 0.00 0 5631 8 0 0
v_min_non_paid_invoice 6037 6037 0 0.00 0 10383 19 0 0
v_min_non_paid_realizn 7299 7299 0 0.00 0 5707 16 0 0
v_our_firm 11056 11056 0 0.00 0 16 1 0 0
v_reserve_write_off 20942 20942 0 0.00 0 6419 9 0 0
x_lock_depdocs_on_canc_invoice 11478 11478 0 0.00 0 29704 142 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 17248 390 0 0
x_make_qstorno_1000_1200 5812 5812 0 0.00 1 33243 1344 0 0
x_make_qstorno_1200_2000 5833 5833 0 0.00 3 79877 3257 0 0
x_make_qstorno_2100_3300 481090 481090 0 0.00 0 134960 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-10-2021 19:39:42 to 07-10-2021 19:39:46.

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
17:07:55 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:08:56 36715 8192000000 9897199648 10207350784 981403024 10.698 100 88 176 0 1010821184 6433456 22984704
17:10:41 19407 8192000000 9945360560 10268291072 1027256976 11.142 100 83 166 0 1057000560 8351792 21391792
17:12:06 27018 8192000000 9986318880 10324361216 1068448160 11.537 100 78 156 0 1097394848 9198512 19748176
17:13:36 13497 8192000000 10028379264 10376204288 1118617680 12.014 100 77 154 0 1147040672 10141856 18281136
17:14:52 6973 8192000000 10092326768 10447204352 1170154400 12.498 100 86 172 1 1203523536 13554992 19814144
17:16:04 4487 8192000000 10121601184 10488033280 1213923744 12.905 100 89 177 0 1247448320 14198352 19326224
17:17:11 2308 8192000000 10161165360 10534727680 1247414992 13.214 100 86 172 1 1280461664 13837152 19209520
17:18:15 1910 8192000000 10197843600 10575503360 1274990336 13.467 100 99 198 2 1310862464 13032160 22839968
17:19:19 1914 8192000000 10210301776 10595115008 1295297760 13.652 100 96 190 4 1329933872 14065808 20570304
17:20:23 10794 8192000000 10209425008 10603499520 1300976256 13.704 100 69 137 1 1326262736 9089776 16196704
17:21:34 5304 8192000000 10214311904 10616549376 1311930560 13.804 100 43 85 2 1328720144 7881072 8908512
17:22:40 6153 8192000000 10205274672 10619912192 1321121040 13.887 100 25 50 0 1332668576 6017472 5530064
17:23:47 26873 8192000000 10208319920 10631065600 1328157264 13.951 100 29 58 0 1341337584 6163728 7016592
17:25:48 4022 8192000000 10231305936 10648236032 1334522368 14.008 100 28 56 1 1346821216 5494848 6804000
17:26:56 6254 8192000000 10221135344 10639732736 1331468992 13.980 100 34 68 0 1345687968 5971008 8247968
17:28:05 10709 8192000000 10213613744 10637000704 1317942608 13.858 99 25 51 1 1329343440 5705008 5695824
17:29:20 48762 8192000000 10191718032 10609729536 1279913008 13.512 97 20 40 1 1290627440 6463248 4251184
17:31:12 6107 8192000000 10172883328 10576859136 1266715952 13.392 99 21 42 1 1277328416 5813584 4798880
17:32:19 7006 8192000000 10145024336 10544615424 1242206048 13.167 99 24 48 1 1254320576 5719552 6394976
17:33:31 41531 8192000000 10149884000 10546798592 1235529872 13.105 98 40 78 0 1253369168 8914064 8925232
17:35:23 8575 8192000000 10178160752 10580570112 1264766224 13.374 99 40 80 2 1283791040 10216576 8808240
17:36:32 3424 8192000000 10186936608 10596020224 1289721920 13.602 100 26 52 1 1301866544 5141712 7002912
17:37:39 9089 8192000000 10203260736 10620932096 1315705184 13.838 100 10 17 2 1323072736 4827600 2539952
17:38:53 7049 8192000000 10254539152 10661318656 1339607600 14.054 100 64 126 0 1365017920 11472640 13937680
17:40:05 36147 8192000000 10251594720 10668982272 1349211696 14.140 100 46 91 1 1368450272 9007888 10230688
17:41:48 30496 8192000000 10276187936 10698125312 1365521056 14.287 100 54 108 1 1390363728 11548320 13294352
17:43:26 13580 8192000000 10283148688 10705121280 1374237472 14.365 100 45 90 1 1394631920 10040160 10354288
17:44:48 16061 8192000000 10294580576 10721218560 1386633904 14.476 100 51 103 1 1412891632 14348000 11909728
17:46:13 16371 8192000000 10313483536 10741706752 1395099664 14.551 100 49 98 0 1429156256 22317360 11739232
17:47:50 7368 8192000000 10316476384 10747191296 1400158112 14.596 100 55 106 3 1429298512 17038128 12102272
17:49:02 7082 8192000000 10327395024 10748719104 1392610400 14.529 99 74 146 1 1432770096 22969712 17189984
17:50:18 4058 8192000000 10342603504 10760318976 1406360816 14.652 100 86 169 1 1454905472 27744128 20800528
17:51:25 6603 8192000000 10343739520 10765639680 1407326560 14.660 100 59 118 0 1449859520 27927520 14605440
17:52:34 3308 8192000000 10359205600 10778906624 1416533584 14.742 100 71 141 1 1467138048 32841152 17763312
17:53:41 1796 8192000000 10364023600 10777628672 1413541712 14.715 100 84 168 1 1468423168 33894832 20986624
17:54:50 3868 8192000000 10347152608 10770235392 1406892768 14.656 100 62 124 0 1455169648 32138320 16138560
17:56:03 1134 8192000000 10334439344 10742542336 1381954816 14.434 99 86 170 1 1433931120 32015968 19960336
17:57:11 6214 8192000000 10329287392 10740482048 1379379584 14.411 100 72 144 2 1431770640 34335168 18055888
17:58:25 4336 8192000000 10346922112 10762665984 1388994736 14.497 100 74 148 2 1453199392 44869728 19334928
17:59:44 14566 8192000000 10318388400 10746490880 1346734208 14.118 97 38 76 3 1402715472 45423360 10557904
18:01:01 14346 8192000000 10314580304 10754355200 1379729200 14.414 100 34 68 2 1427718736 38020848 9968688
18:02:28 19513 8192000000 10328910864 10763751424 1393439408 14.537 100 30 59 1 1432873680 31273520 8160752
18:04:02 15848 8192000000 10331597344 10761531392 1385307584 14.464 100 36 72 1 1432134752 35955088 10872080
18:05:24 18841 8192000000 10320825328 10759380992 1383319472 14.446 100 31 62 0 1430499552 38125616 9054464
18:06:55 37608 8192000000 10293545424 10743631872 1352744800 14.172 99 17 33 0 1389012512 31604432 4663280
18:08:36 34058 8192000000 10280802224 10741116928 1360526192 14.242 99 17 34 0 1390083584 25652272 3905120
18:10:10 65823 8192000000 10290510176 10747617280 1352827888 14.173 98 21 42 0 1373925120 15952880 5144352
18:12:18 19434 8192000000 10278553152 10721857536 1365565168 14.287 100 17 34 1 1377301104 7885936 3850000
18:14:00 58739 8192000000 10267846576 10710839296 1344078320 14.094 98 26 52 0 1362256400 11542144 6635936
18:16:13 34519 8192000000 10306725440 10737573888 1366888784 14.299 99 33 66 1 1386888064 11805920 8193360
18:17:53 140428 8192000000 10293120272 10734604288 1356054800 14.202 98 19 38 0 1377028784 16377616 4596368
18:21:15 22433 8192000000 10309183600 10754527232 1392448352 14.528 100 36 71 0 1411658032 11572672 7637008
18:22:38 69455 8192000000 10299532688 10746703872 1379554144 14.413 99 35 70 0 1395845584 7960112 8331328
18:24:49 30558 8192000000 10315806528 10763591680 1397160768 14.570 100 27 54 0 1414294144 10812768 6320608
18:26:21 46534 8192000000 10305503888 10770456576 1387631200 14.485 99 30 60 1 1408574528 13166016 7777312
18:28:13 44244 8192000000 10306966752 10765193216 1387799872 14.486 99 17 34 1 1405739680 13732576 4207232
18:30:58 60517 8192000000 10308112464 10776014848 1394636848 14.547 100 18 36 0 1418092336 18734464 4721024
18:33:19 91028 8192000000 10298750192 10765312000 1308602384 13.773 94 20 40 0 1332167696 18773840 4791472
18:35:50 43252 8192000000 10286698944 10734456832 1357112832 14.211 98 19 38 0 1374300400 12474896 4712672
18:37:45 38680 8192000000 10301060848 10768261120 1385739328 14.468 100 25 50 1 1407127504 15669872 5718304
18:39:24 47749 8192000000 10290308064 10740391936 1352509712 14.170 97 11 22 1 1364943728 10047600 2386416
18:41:17 58076 8192000000 10285592272 10740858880 1325286976 13.925 95 22 44 0 1347796480 17557136 4952368
18:43:16 57297 8192000000 10302219200 10743369728 1347721840 14.127 97 19 38 2 1364385968 12249840 4414288
18:45:17 64515 8192000000 10280638144 10734505984 1309358448 13.780 95 26 51 0 1330057856 14696720 6002688
18:47:23 24772 8192000000 10287402352 10719612928 1344694960 14.100 98 59 117 2 1365390880 7031808 13664112
18:48:48 42386 8192000000 10255128768 10692091904 1319791936 13.875 97 45 90 1 1339520016 9100064 10628016
18:50:36 64933 8192000000 10246805360 10703323136 1337173456 14.032 99 14 28 0 1352092672 11996352 2922864
18:52:41 39704 8192000000 10256101760 10707537920 1340726704 14.064 99 5 10 0 1351956096 10107328 1122064
18:54:26 65315 8192000000 10261868480 10714275840 1344159056 14.095 99 15 30 2 1360612256 12959040 3494160
18:56:32 75278 8192000000 10272208624 10732871680 1370797888 14.334 100 17 34 0 1389800720 14819584 4183248
18:58:53 101372 8192000000 10279607856 10740461568 1365215072 14.284 99 14 28 1 1384293632 15701648 3376912
19:01:35 29433 8192000000 10285914000 10734424064 1368201360 14.311 99 31 62 0 1390055072 14131072 7722640
19:03:06 56260 8192000000 10292812672 10738515968 1367601712 14.306 99 15 30 0 1379252080 8552416 3097952
19:05:03 110289 8192000000 10285161088 10745057280 1350700112 14.154 98 11 22 1 1373806528 20012224 3094192
19:07:55 36082 8192000000 10285339984 10729099264 1353180240 14.176 99 20 40 0 1370709472 13151392 4377840
19:09:35 79067 8192000000 10284384832 10723639296 1293102416 13.632 94 13 26 0 1305246944 9300336 2844192
19:11:54 94446 8192000000 10295919376 10739789824 1366900592 14.299 99 35 70 0 1385744320 11050656 7793072
19:14:29 34101 8192000000 10283251984 10735927296 1382241584 14.437 100 22 44 2 1397557136 8901232 6414320
19:16:03 21692 8192000000 10284564096 10731327488 1354380384 14.187 98 45 89 2 1373800736 9863616 9556736
19:17:30 44553 8192000000 10269923408 10730541056 1367802128 14.307 99 8 16 0 1378393488 8397872 2193488
19:19:23 63958 8192000000 10281622448 10751213568 1380313840 14.419 100 16 32 0 1400004848 15798896 3892112
19:21:39 36429 8192000000 10280574624 10732814336 1362327520 14.258 98 27 54 2 1377615088 8953808 6333760
19:23:36 46212 8192000000 10272222160 10717040640 1316978320 13.849 95 13 26 0 1330187600 10255632 2953648
19:25:23 49085 8192000000 10280719472 10740899840 1364937040 14.282 99 23 46 0 1380725664 10390480 5398144
19:27:17 46913 8192000000 10278547760 10729406464 1336466944 14.026 97 26 52 0 1354460704 12317664 5676096
19:29:05 64071 8192000000 10274811936 10737516544 1352752688 14.172 98 27 54 0 1374656592 15754816 6149088
19:31:09 17333 8192000000 10274096672 10715500544 1336441312 14.025 98 38 76 0 1353696176 8885632 8369232
19:32:30 32712 8192000000 10277388144 10718515200 1317303728 13.852 96 29 58 2 1335350944 10957984 7089232
19:34:17 75075 8192000000 10270229760 10735697920 1355674736 14.199 99 6 12 0 1374566864 17494176 1397952
19:37:02 50531 8192000000 9992171344 10416103424 1101411664 11.851 80 9 18 0 1115568592 12716784 1440144

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-10-2021 19:39:48 to 07-10-2021 19:40:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 147 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-10-2021 19:40:04 to 07-10-2021 19:40:06.

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          242801
MON$OLDEST_ACTIVE               244192
MON$OLDEST_SNAPSHOT             244192
MON$NEXT_TRANSACTION            244192
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                       2048864
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.33519 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33519 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33519 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33519 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Thu Oct  7 19:37:54 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Oct  7 19:38:00 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Oct  7 19:38:05 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Oct  7 19:38:05 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Oct  7 19:38:05 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

07.10.21 19:40:07. 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-10-07 19:40:10 No severe PSQL-related problems occured
07.10.21 19:40:11. End of report.