Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.11.2021 22: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 16051492 kB
MemAvailable 30810968 kB
Buffers 0 kB
Cached 15670512 kB
SwapCached 2452 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-08 22:37:53
DTS_END                         2021-11-08 22:37:53.0690
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38388
TRN_ID                          244319
ATT_ID                          662
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: 9278.16 21192 1113379
customer order: creation 174.73 338 20967
customer order: refuse 14.66 337 1759
order to supplier: creation 46.48 1113 5578
order to supplier: removal 3.63 78015 435
invoice (draft): creation 47.58 3501 5710
invoice (draft): removal 52.98 670 6358
invoice accept: apply 42.88 16379 5145
invoice accept: cancel 47.28 669 5673
customer reserve: creation 3924.67 163 470960
customer reserve: removal 4618.84 47 554261
realization accept: apply 83.78 207 10053
realization accept: cancel 88.51 100 10621
payment from customer: creation 61.54 170 7385
payment from customer: removal 6.08 104 730
payment to supplier: creation 49.33 149 5919
payment to supplier: removal 3.40 53 408
service: total inventory turnovers 4.63 1418 555
service: total monetary turnovers 6.38 71 766
service: aggregate perf. data 0.77 18377 92
service: refresh index statistics 0.03 301953 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-11-2021 22:39:39 to 08-11-2021 22:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2328
TEST_TIME 2 2778
TEST_TIME 3 2708
TEST_TIME 4 3106
TEST_TIME 5 3272
TEST_TIME 6 3727
TEST_TIME 7 3947
TEST_TIME 8 4279
TEST_TIME 9 4386
TEST_TIME 10 4536
TEST_TIME 11 4774
TEST_TIME 12 5033
TEST_TIME 13 5923
TEST_TIME 14 5921
TEST_TIME 15 6401
TEST_TIME 16 7013
TEST_TIME 17 7167
TEST_TIME 18 7177
TEST_TIME 19 7986
TEST_TIME 20 8156
TEST_TIME 21 8875
TEST_TIME 22 9135
TEST_TIME 23 9403
TEST_TIME 24 9535
TEST_TIME 25 10056
TEST_TIME 26 9982
TEST_TIME 27 9826
TEST_TIME 28 9747
TEST_TIME 29 9679
TEST_TIME 30 9786
TEST_TIME 31 9748
TEST_TIME 32 9786
TEST_TIME 33 9780
TEST_TIME 34 9694
TEST_TIME 35 9647
TEST_TIME 36 9535
TEST_TIME 37 9557
TEST_TIME 38 9585
TEST_TIME 39 9459
TEST_TIME 40 9594
TEST_TIME 41 9455
TEST_TIME 42 9315
TEST_TIME 43 9275
TEST_TIME 44 9350
TEST_TIME 45 9341
TEST_TIME 46 9415
TEST_TIME 47 9384
TEST_TIME 48 9332
TEST_TIME 49 9256
TEST_TIME 50 9176
TEST_TIME 51 9119
TEST_TIME 52 8956
TEST_TIME 53 9009
TEST_TIME 54 9161
TEST_TIME 55 9123
TEST_TIME 56 9122
TEST_TIME 57 9198
TEST_TIME 58 9251
TEST_TIME 59 9449
TEST_TIME 60 9439
TEST_TIME 61 9486
TEST_TIME 62 9548
TEST_TIME 63 9629
TEST_TIME 64 9647
TEST_TIME 65 9719
TEST_TIME 66 9732
TEST_TIME 67 9799
TEST_TIME 68 9741
TEST_TIME 69 9725
TEST_TIME 70 9651
TEST_TIME 71 9621
TEST_TIME 72 9633
TEST_TIME 73 9609
TEST_TIME 74 9571
TEST_TIME 75 9519
TEST_TIME 76 9465
TEST_TIME 77 9436
TEST_TIME 78 9379
TEST_TIME 79 9377
TEST_TIME 80 9359
TEST_TIME 81 9391
TEST_TIME 82 9361
TEST_TIME 83 9357
TEST_TIME 84 9346
TEST_TIME 85 9366
TEST_TIME 86 9378
TEST_TIME 87 9466
TEST_TIME 88 9456
TEST_TIME 89 9441
TEST_TIME 90 9470
TEST_TIME 91 9479
TEST_TIME 92 9542
TEST_TIME 93 9529
TEST_TIME 94 9544
TEST_TIME 95 9469
TEST_TIME 96 9458
TEST_TIME 97 9411
TEST_TIME 98 9415
TEST_TIME 99 9344
TEST_TIME 100 9305
TEST_TIME 101 9261
TEST_TIME 102 9269
TEST_TIME 103 9226
TEST_TIME 104 9260
TEST_TIME 105 9244
TEST_TIME 106 9208
TEST_TIME 107 9186
TEST_TIME 108 9242
TEST_TIME 109 9243
TEST_TIME 110 9283
TEST_TIME 111 9289
TEST_TIME 112 9292
TEST_TIME 113 9263
TEST_TIME 114 9275
TEST_TIME 115 9336
TEST_TIME 116 9310
TEST_TIME 117 9354
TEST_TIME 118 9340
TEST_TIME 119 9276
TEST_TIME 120 9283

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-11-2021 22:39:42 to 08-11-2021 22:39:46.

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 1551194 1551194 0 0.00 0 39633 6 0 0
doc_list_biud 1551194 1551194 0 0.00 0 34497 22 0 0
sp_add_invoice_to_stock 5250 5145 105 2.00 28 187144 16380 0 105
sp_cancel_adding_invoice 5673 5673 0 0.00 5 55380 670 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 8194 337 0 0
sp_cancel_customer_reserve 554261 554261 0 0.00 1 21766 47 0 0
sp_cancel_pay_from_customer 730 730 0 0.00 3 10326 105 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 1790 54 0 0
sp_cancel_supplier_invoice 6364 6358 6 0.09 1 34503 670 0 6
sp_cancel_supplier_order 435 435 0 0.00 6 1131392 78016 0 0
sp_cancel_write_off 10621 10621 0 0.00 1 23218 101 0 0
sp_client_order 20967 20967 0 0.00 3 22074 338 0 0
sp_customer_reserve 470960 470960 0 0.00 0 56692 163 0 0
sp_fill_shopping_cart 32252 32252 0 0.00 1 11083 36 0 0
sp_fill_shopping_cart_clo_res 470952 470952 0 0.00 0 16830 13 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 21186 279 0 0
sp_kill_cost_storno 17432 17432 0 0.00 0 22879 51 0 0
sp_kill_qstorno_ret_qs2qd 1125936 1125936 0 0.00 0 34494 26 0 0
sp_kill_qty_storno 594297 594297 0 0.00 0 34496 57 0 0
sp_lock_selected_doc 21053 21053 0 0.00 0 7899 5 0 0
sp_make_cost_storno 28494 28494 0 0.00 0 39581 73 0 0
sp_multiply_rows_for_pdistr 28494 28494 0 0.00 0 16939 34 0 0
sp_multiply_rows_for_qdistr 20967 20967 0 0.00 0 13960 156 0 0
sp_pay_from_customer 7385 7385 0 0.00 4 36309 170 0 0
sp_pay_to_supplier 5919 5919 0 0.00 4 23245 149 0 0
sp_payment_common 13304 13304 0 0.00 1 36304 64 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 7592 302 0 0
sp_qd_handle_on_invoice_upd_sts 10810 10810 0 0.00 0 23121 306 0 0
sp_qd_handle_on_reserve_upd_sts 10053 10053 0 0.00 0 17118 58 0 0
sp_reserve_write_off 10053 10053 0 0.00 6 21744 207 0 0
sp_supplier_invoice 5710 5710 0 0.00 5 97195 3502 0 0
sp_supplier_order 5578 5578 0 0.00 5 22601 1114 0 0
srv_aggregate_perf_data 92 92 0 0.00 2757 205391 18378 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1000 119666 52495 0 0
srv_find_qd_qs_mism 1065530 1065530 0 0.00 0 15048 5 0 0
srv_make_invnt_saldo 555 555 0 0.00 19 173936 1419 0 0
srv_make_money_saldo 766 766 0 0.00 0 19103 71 0 0
srv_recalc_idx_stat 4 4 0 0.00 77681 457191 301954 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 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 5147 5147 0 0.00 0 17100 173 0 0
t$perf-norm:sp_cancel_adding_invoice 411 411 0 0.00 1 9608 140 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 4850 12 0 0
t$perf-norm:sp_cancel_customer_reserve 684 684 0 0.00 0 5000 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 730 730 0 0.00 0 12221 40 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 2945 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 443 443 0 0.00 0 733 9 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 23680 1403 0 0
t$perf-norm:sp_cancel_write_off 994 994 0 0.00 0 4647 15 0 0
t$perf-norm:sp_client_order 20967 20967 0 0.00 0 12209 21 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 4747 14 0 0
t$perf-norm:sp_pay_from_customer 7385 7385 0 0.00 0 6406 14 0 0
t$perf-norm:sp_pay_to_supplier 5919 5919 0 0.00 0 11803 21 0 0
t$perf-norm:sp_reserve_write_off 10053 10053 0 0.00 0 5082 14 0 0
t$perf-norm:sp_supplier_invoice 5710 5710 0 0.00 0 9670 25 0 0
t$perf-norm:sp_supplier_order 5578 5578 0 0.00 0 11312 20 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 1741 36 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 445 9 0 0
t$perf-norm:srv_make_invnt_saldo 555 555 0 0.00 0 1012 22 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 12209 36 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10483 10273 210 2.00 0 6441 19 0 210
v_all_customers 38544 38544 0 0.00 0 47 0 0 0
v_all_suppliers 31284 31284 0 0.00 0 17 1 0 0
v_all_wares 41932 41932 0 0.00 0 84 1 0 0
v_cancel_adding_invoice 822 822 0 0.00 0 2829 11 0 0
v_cancel_client_order 3518 3518 0 0.00 0 3455 5 0 0
v_cancel_customer_prepayment 1460 1460 0 0.00 0 3897 10 0 0
v_cancel_customer_reserve 1368 1368 0 0.00 0 5403 9 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 1475 6 0 0
v_cancel_supplier_invoice 898 886 12 1.33 0 482 13 0 12
v_cancel_supplier_order 870 870 0 0.00 0 3996 10 0 0
v_cancel_write_off 1988 1988 0 0.00 0 7290 19 0 0
v_max_id_clo_ord 5577 5577 0 0.00 0 8536 3 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 4609 11 0 0
v_max_id_ord_sup 5709 5709 0 0.00 0 1658 3 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 1141 3 0 0
v_max_non_paid_realizn 3810 3810 0 0.00 0 4270 6 0 0
v_min_id_clo_ord 5577 5577 0 0.00 0 9865 12 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 4667 22 0 0
v_min_id_ord_sup 5709 5709 0 0.00 0 9743 11 0 0
v_min_non_paid_invoice 5919 5919 0 0.00 0 21808 37 0 0
v_min_non_paid_realizn 7385 7385 0 0.00 0 36300 63 0 0
v_our_firm 10538 10538 0 0.00 0 19 1 0 0
v_reserve_write_off 20104 20104 0 0.00 0 7563 7 0 0
x_lock_depdocs_on_canc_invoice 11588 11588 0 0.00 0 27975 113 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 14232 343 0 0
x_make_qstorno_1000_1200 5578 5578 0 0.00 1 22002 1026 0 0
x_make_qstorno_1200_2000 5710 5710 0 0.00 2 94564 3385 0 0
x_make_qstorno_2100_3300 470960 470960 0 0.00 0 56690 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-11-2021 22:39:47 to 08-11-2021 22:39:51.

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
20:07:56 7 8192000000 8803629328 8816660480 9015392 0.109 4 1 2 0 9117680 59504 42784
20:08:58 20970 8192000000 9891951584 10200264704 968873904 10.576 100 85 170 0 997540944 5856224 22810816
20:10:26 17546 8192000000 9938634576 10256867328 1012531616 11.000 100 90 180 0 1043818064 7754672 23531776
20:11:49 25730 8192000000 9976939440 10310356992 1052377472 11.383 100 84 168 0 1083196928 9218064 21601392
20:13:17 13718 8192000000 10037140592 10383306752 1120742672 12.034 100 86 172 2 1152372880 10827360 20802848
20:14:41 10193 8192000000 10071608704 10431389696 1161721824 12.419 100 81 162 0 1193717760 13105616 18890320
20:15:55 5852 8192000000 10122370496 10493153280 1211428112 12.882 100 72 144 1 1240505232 12328000 16749120
20:17:03 2486 8192000000 10167780288 10545631232 1256954928 13.302 100 83 166 2 1287006336 11604992 18446416
20:18:10 3221 8192000000 10196959376 10582409216 1282648592 13.537 100 91 181 0 1316559824 13229392 20681840
20:19:14 28923 8192000000 10202689312 10600841216 1300925408 13.704 100 62 125 2 1326512000 10642928 14943664
20:20:45 2208 8192000000 10216955120 10616467456 1312807344 13.812 100 63 126 0 1337850704 10752128 14291232
20:21:50 8542 8192000000 10200384816 10615255040 1316054032 13.841 100 24 47 2 1327170800 5812368 5304400
20:22:59 13932 8192000000 10201667072 10622803968 1320596880 13.882 100 19 38 1 1330045680 5301376 4147424
20:24:13 62911 8192000000 10215634496 10637406208 1327719504 13.947 100 32 62 2 1341261200 5200048 8341648
20:26:16 6992 8192000000 10221662816 10645118976 1333026384 13.994 100 29 58 0 1345866448 5793648 7046416
20:27:25 6069 8192000000 10203134496 10622369792 1314525920 13.827 100 26 52 1 1326505120 6320320 5658880
20:28:32 9400 8192000000 10206945904 10617057280 1284541552 13.554 98 22 43 0 1295314864 6059536 4713776
20:29:42 17321 8192000000 10173977952 10577076224 1238920816 13.136 96 48 97 2 1256936480 6156736 11858928
20:31:04 6441 8192000000 10150567424 10546458624 1234970576 13.100 98 31 59 4 1246476064 5185744 6319744
20:32:11 7360 8192000000 10148875328 10547871744 1222734704 12.987 97 20 40 1 1232944272 5851904 4357664
20:33:19 11991 8192000000 10149562768 10557820928 1250196304 13.240 99 27 54 1 1262753824 6052928 6504592
20:34:32 24646 8192000000 10177167104 10586419200 1282924304 13.540 100 34 68 2 1297766880 7228000 7614576
20:35:58 7450 8192000000 10182586384 10600718336 1300272736 13.698 100 36 70 1 1315203344 5837488 9093120
20:37:09 7157 8192000000 10209260304 10624442368 1314138112 13.824 100 23 46 1 1323832016 4487312 5206592
20:38:26 14415 8192000000 10236531648 10649063424 1336674320 14.027 100 34 66 0 1350723264 6555232 7493712
20:39:59 91326 8192000000 10264672320 10689171456 1355655712 14.198 100 42 81 2 1380734880 15116224 9962944
20:42:50 78620 8192000000 10283327072 10714923008 1369191504 14.320 100 32 64 0 1399615664 22056928 8367232
20:45:12 15077 8192000000 10302542288 10727952384 1384556480 14.457 100 38 76 1 1412098880 18405712 9136688
20:46:42 13847 8192000000 10324490912 10749194240 1397331168 14.571 100 41 81 0 1430803488 23443648 10028672
20:48:00 5314 8192000000 10345241664 10766196736 1410441024 14.688 100 58 116 0 1451742880 26493024 14808832
20:49:08 2297 8192000000 10343341360 10752856064 1394543584 14.546 100 82 156 0 1442347296 29207248 18596464
20:50:14 10702 8192000000 10323838768 10753122304 1396553392 14.564 100 40 80 2 1432179440 25395488 10230560
20:51:32 4829 8192000000 10351618432 10767413248 1402423200 14.617 100 77 152 0 1451386192 30499392 18463600
20:52:40 3411 8192000000 10343954944 10761428992 1378861808 14.406 98 74 147 2 1429378912 32651312 17865792
20:53:54 4041 8192000000 10327824864 10754703360 1399377472 14.589 100 62 121 4 1439649008 25314048 14957488
20:55:00 7317 8192000000 10298580256 10713513984 1338154240 14.041 96 55 108 2 1380951216 28626320 14170656
20:56:15 995 8192000000 10302760032 10704478208 1346291664 14.114 100 93 186 3 1410157264 40007344 23858256
20:57:19 6362 8192000000 10292006896 10702487552 1340658656 14.063 100 54 107 2 1388559600 33648944 14252000
20:58:32 19053 8192000000 10271321200 10704781312 1320984944 13.886 98 29 57 0 1362555808 33790400 7780464
21:00:11 6612 8192000000 10265684976 10685431808 1338180464 14.041 100 49 95 1 1370851248 20485280 12185504
21:01:23 44136 8192000000 10256143664 10694635520 1335668336 14.018 100 15 31 0 1359617712 19697296 4252080
21:03:09 26881 8192000000 10262616576 10707435520 1355598640 14.198 100 21 42 1 1375810784 14825296 5386848
21:04:38 110192 8192000000 10274070384 10717175808 1324233584 13.915 97 19 38 0 1351198256 21958960 5005712
21:07:37 91275 8192000000 10280342112 10723586048 1361561840 14.251 99 23 46 0 1387557920 20227136 5768944
21:10:55 61993 8192000000 10291659248 10743427072 1379370480 14.411 99 21 42 0 1396669952 12670432 4629040
21:13:06 30834 8192000000 10307876496 10753900544 1397862304 14.576 100 21 42 0 1416376192 13426528 5087360
21:14:42 44237 8192000000 10303482704 10773671936 1405029632 14.640 100 23 46 0 1431250320 20679376 5541312
21:16:28 75846 8192000000 10308121952 10776834048 1401209536 14.606 100 14 28 0 1425312608 20405792 3697280
21:18:55 69870 8192000000 10324812992 10779770880 1395232656 14.553 98 31 62 0 1418046176 15718624 7094896
21:21:16 67281 8192000000 10317101200 10794668032 1412788288 14.709 100 14 28 0 1438608320 22587616 3232416
21:23:24 67493 8192000000 10309003552 10772688896 1405891152 14.647 100 12 24 0 1422343152 13472800 2979200
21:25:32 45823 8192000000 10311642992 10774093824 1399455264 14.590 99 27 54 1 1414433056 8471472 6506320
21:27:19 76743 8192000000 10301392960 10760454144 1372656352 14.351 97 14 28 0 1384708304 9088144 2963808
21:29:36 89050 8192000000 10308770768 10791903232 1410333232 14.687 100 18 36 3 1437081872 22161056 4587584
21:32:58 37808 8192000000 10311286032 10776637440 1401123872 14.605 99 31 62 0 1419904448 11344560 7436016
21:34:37 97136 8192000000 10295816832 10750894080 1351730864 14.163 96 17 34 0 1374464464 18657536 4076064
21:37:14 113629 8192000000 10272155888 10730348544 1255762784 13.291 90 10 20 1 1270800528 13096128 1941616
21:40:14 99398 8192000000 10265054592 10708230144 1253475120 13.270 91 20 38 1 1268108560 10793904 3839536
21:42:58 62710 8192000000 10273746752 10721914880 1279378288 13.507 93 13 26 0 1296754912 14542496 2834128
21:45:01 97837 8192000000 10264678480 10696413184 1324361168 13.916 98 34 69 2 1343763168 11307328 8094672
21:47:47 22758 8192000000 10282197952 10716291072 1362275760 14.258 100 47 94 2 1385971600 11664016 12031824
21:49:13 47869 8192000000 10237484768 10683584512 1318224000 13.861 97 16 32 1 1328330384 6740288 3366096
21:51:01 60986 8192000000 10230067328 10686095360 1329268336 13.961 99 20 40 0 1343432928 9693856 4470736
21:53:43 60831 8192000000 10248946528 10703097856 1354805344 14.191 100 14 28 0 1367988464 9766544 3416576
21:55:44 63084 8192000000 10257143920 10702495744 1343289568 14.087 99 31 62 0 1359359712 7882592 8187552
21:57:48 101083 8192000000 10245514144 10699108352 1301797600 13.712 96 8 16 0 1315901984 12542784 1561600
22:00:29 45117 8192000000 10266387360 10713038848 1359139232 14.230 100 25 50 0 1373529872 8629344 5761296
22:02:14 60269 8192000000 10273458656 10719051776 1329942848 13.967 97 34 69 2 1349416752 11431552 8042352
22:04:26 82065 8192000000 10271700816 10722484224 1340241952 14.060 98 20 40 0 1356215504 11400880 4572672
22:06:49 24531 8192000000 10297769200 10741997568 1380567472 14.422 100 37 72 0 1399112288 10217568 8327248
22:08:40 42486 8192000000 10296087856 10756435968 1380230736 14.419 99 27 54 1 1400783200 14669264 5883200
22:10:24 103251 8192000000 10307239600 10764324864 1398030880 14.577 100 13 26 1 1409486192 8773664 2681648
22:13:08 70127 8192000000 10306547264 10759241728 1390077088 14.507 99 30 60 0 1409869120 12617872 7174160
22:15:28 50779 8192000000 10270531056 10735497216 1373640656 14.360 99 13 26 0 1387958224 11086704 3230864
22:17:19 61956 8192000000 10278872608 10740477952 1362271568 14.258 98 11 22 0 1375993872 11049872 2672432
22:19:22 77910 8192000000 10279776128 10737389568 1363410768 14.268 98 24 47 2 1383108048 13444752 6252528
22:21:47 91857 8192000000 10249333968 10704236544 1277620320 13.491 92 13 25 2 1291390784 10474672 3295792
22:24:19 23472 8192000000 10268883440 10719424512 1344528448 14.098 97 22 44 1 1361663232 11208368 5926416
22:25:47 66728 8192000000 10283406128 10746966016 1371474288 14.340 100 13 26 0 1387876528 13613728 2788512
22:27:56 46314 8192000000 10280865296 10721816576 1351230688 14.159 98 34 67 1 1369602112 10654288 7717136
22:30:09 16813 8192000000 10264412816 10695991296 1325344784 13.925 98 49 97 1 1345987376 10376896 10265696
22:31:38 78630 8192000000 10235582720 10691026944 1261072288 13.340 94 31 60 1 1283430384 15698720 6659376
22:33:56 36617 8192000000 10251499680 10693197824 1296023392 13.659 96 34 68 2 1316471104 12582592 7865120
22:35:40 119666 8192000000 10230648256 10684846080 1314772960 13.829 98 17 34 0 1330865840 12549472 3543408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 08-11-2021 22:39:53 to 08-11-2021 22:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 105 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 08-11-2021 22:40:08 to 08-11-2021 22:40:10.

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          243575
MON$OLDEST_ACTIVE               244436
MON$OLDEST_SNAPSHOT             244436
MON$NEXT_TRANSACTION            244436
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                       2028128
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.33528 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33528 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33528 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33528 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 ---
:291: 
:292: mscs-test-161.budzdorov.ru    Mon Nov  8 22:38:14 2021
:293:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:294: 
--- end of diff output ---

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

08.11.21 22:40:11. 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-08 22:40:13 No severe PSQL-related problems occured
08.11.21 22:40:15. End of report.