Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.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 16400380 kB
MemAvailable 31054332 kB
Buffers 0 kB
Cached 15295944 kB
SwapCached 2568 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-08-09 01:37:41
DTS_END                         2021-08-09 01:37:41.7560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39762
TRN_ID                          235769
ATT_ID                          708
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: 9280.48 24955 1113657
customer order: creation 165.33 370 19840
customer order: refuse 14.29 355 1715
order to supplier: creation 44.88 1336 5386
order to supplier: removal 3.38 119472 405
invoice (draft): creation 44.78 3345 5374
invoice (draft): removal 54.12 977 6494
invoice accept: apply 40.29 17502 4835
invoice accept: cancel 48.90 829 5868
customer reserve: creation 3788.06 169 454567
customer reserve: removal 4787.20 65 574464
realization accept: apply 81.26 182 9751
realization accept: cancel 83.29 104 9995
payment from customer: creation 56.79 107 6815
payment from customer: removal 5.58 82 670
payment to supplier: creation 47.48 126 5698
payment to supplier: removal 3.53 69 424
service: total inventory turnovers 4.26 1006 511
service: total monetary turnovers 6.13 31 735
service: aggregate perf. data 0.88 18654 106
service: refresh index statistics 0.03 334317 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-08-2021 01:39:49 to 09-08-2021 01:39:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2073
TEST_TIME 2 2280
TEST_TIME 3 4074
TEST_TIME 4 4396
TEST_TIME 5 4976
TEST_TIME 6 4818
TEST_TIME 7 4980
TEST_TIME 8 5304
TEST_TIME 9 5438
TEST_TIME 10 5468
TEST_TIME 11 5567
TEST_TIME 12 6084
TEST_TIME 13 6610
TEST_TIME 14 6756
TEST_TIME 15 6973
TEST_TIME 16 7141
TEST_TIME 17 7306
TEST_TIME 18 7487
TEST_TIME 19 7562
TEST_TIME 20 8012
TEST_TIME 21 8107
TEST_TIME 22 8331
TEST_TIME 23 8643
TEST_TIME 24 8721
TEST_TIME 25 9072
TEST_TIME 26 10927
TEST_TIME 27 11695
TEST_TIME 28 11320
TEST_TIME 29 11120
TEST_TIME 30 11015
TEST_TIME 31 10922
TEST_TIME 32 10706
TEST_TIME 33 10571
TEST_TIME 34 10623
TEST_TIME 35 10498
TEST_TIME 36 10310
TEST_TIME 37 10111
TEST_TIME 38 9938
TEST_TIME 39 10082
TEST_TIME 40 10067
TEST_TIME 41 9923
TEST_TIME 42 9773
TEST_TIME 43 9712
TEST_TIME 44 9614
TEST_TIME 45 9635
TEST_TIME 46 9569
TEST_TIME 47 9636
TEST_TIME 48 9678
TEST_TIME 49 9625
TEST_TIME 50 9623
TEST_TIME 51 9670
TEST_TIME 52 9690
TEST_TIME 53 9734
TEST_TIME 54 9612
TEST_TIME 55 9643
TEST_TIME 56 9826
TEST_TIME 57 9790
TEST_TIME 58 9763
TEST_TIME 59 9761
TEST_TIME 60 9813
TEST_TIME 61 9763
TEST_TIME 62 9695
TEST_TIME 63 9623
TEST_TIME 64 9560
TEST_TIME 65 9543
TEST_TIME 66 9533
TEST_TIME 67 9489
TEST_TIME 68 9425
TEST_TIME 69 9408
TEST_TIME 70 9325
TEST_TIME 71 9306
TEST_TIME 72 9225
TEST_TIME 73 9282
TEST_TIME 74 9327
TEST_TIME 75 9324
TEST_TIME 76 9289
TEST_TIME 77 9289
TEST_TIME 78 9296
TEST_TIME 79 9350
TEST_TIME 80 9408
TEST_TIME 81 9504
TEST_TIME 82 9463
TEST_TIME 83 9572
TEST_TIME 84 9556
TEST_TIME 85 9583
TEST_TIME 86 9485
TEST_TIME 87 9437
TEST_TIME 88 9406
TEST_TIME 89 9354
TEST_TIME 90 9408
TEST_TIME 91 9360
TEST_TIME 92 9305
TEST_TIME 93 9310
TEST_TIME 94 9294
TEST_TIME 95 9290
TEST_TIME 96 9291
TEST_TIME 97 9324
TEST_TIME 98 9302
TEST_TIME 99 9347
TEST_TIME 100 9397
TEST_TIME 101 9405
TEST_TIME 102 9360
TEST_TIME 103 9380
TEST_TIME 104 9380
TEST_TIME 105 9406
TEST_TIME 106 9390
TEST_TIME 107 9402
TEST_TIME 108 9518
TEST_TIME 109 9486
TEST_TIME 110 9406
TEST_TIME 111 9450
TEST_TIME 112 9401
TEST_TIME 113 9378
TEST_TIME 114 9345
TEST_TIME 115 9292
TEST_TIME 116 9294
TEST_TIME 117 9305
TEST_TIME 118 9318
TEST_TIME 119 9322
TEST_TIME 120 9285

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-08-2021 01:39:52 to 09-08-2021 01:39:56.

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 1533228 1533228 0 0.00 0 16736 6 0 0
doc_list_biud 1533228 1533228 0 0.00 0 46184 31 0 0
sp_add_invoice_to_stock 4975 4835 140 2.81 30 173206 17502 0 140
sp_cancel_adding_invoice 5868 5868 0 0.00 4 88786 829 0 0
sp_cancel_client_order 1715 1715 0 0.00 4 9400 355 0 0
sp_cancel_customer_reserve 574464 574464 0 0.00 1 42795 66 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 9212 83 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 2668 69 0 0
sp_cancel_supplier_invoice 6504 6494 10 0.15 1 14998 978 0 10
sp_cancel_supplier_order 405 405 0 0.00 12 1215538 119473 0 0
sp_cancel_write_off 9995 9995 0 0.00 1 8254 104 0 0
sp_client_order 19840 19840 0 0.00 3 17959 370 0 0
sp_customer_reserve 454567 454567 0 0.00 0 53100 169 0 0
sp_fill_shopping_cart 30585 30585 0 0.00 1 13633 36 0 0
sp_fill_shopping_cart_clo_res 454559 454559 0 0.00 0 29473 15 0 0
sp_get_clo_for_invoice 4826 4826 0 0.00 0 17369 340 0 0
sp_kill_cost_storno 16957 16957 0 0.00 0 10642 55 0 0
sp_kill_qstorno_ret_qs2qd 1165822 1165822 0 0.00 0 42788 36 0 0
sp_kill_qty_storno 613517 613517 0 0.00 0 46183 78 0 0
sp_lock_selected_doc 20224 20224 0 0.00 0 9269 4 0 0
sp_make_cost_storno 27089 27089 0 0.00 0 16711 48 0 0
sp_multiply_rows_for_pdistr 27089 27089 0 0.00 0 10420 31 0 0
sp_multiply_rows_for_qdistr 19840 19840 0 0.00 0 11893 165 0 0
sp_pay_from_customer 6815 6815 0 0.00 4 10570 108 0 0
sp_pay_to_supplier 5698 5698 0 0.00 4 16746 126 0 0
sp_payment_common 12513 12513 0 0.00 1 13847 32 0 0
sp_qd_handle_on_cancel_clo 1715 1715 0 0.00 0 9393 324 0 0
sp_qd_handle_on_invoice_upd_sts 10693 10693 0 0.00 0 46183 349 0 0
sp_qd_handle_on_reserve_upd_sts 9751 9751 0 0.00 0 15526 66 0 0
sp_reserve_write_off 9751 9751 0 0.00 6 15539 182 0 0
sp_supplier_invoice 5374 5374 0 0.00 6 65136 3345 0 0
sp_supplier_order 5386 5386 0 0.00 5 37133 1336 0 0
srv_aggregate_perf_data 106 106 0 0.00 2772 118574 18654 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1555 170004 47586 0 0
srv_find_qd_qs_mism 1066579 1066579 0 0.00 0 11909 5 0 0
srv_make_invnt_saldo 511 511 0 0.00 52 26046 1006 0 0
srv_make_money_saldo 735 735 0 0.00 1 3466 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 288445 394185 334317 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4835 4835 0 0.00 0 6626 164 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 2206 82 0 0
t$perf-norm:sp_cancel_client_order 1715 1715 0 0.00 0 20408 33 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 2983 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 4370 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 884 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 4873 56 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 19482 1770 0 0
t$perf-norm:sp_cancel_write_off 997 997 0 0.00 0 5459 13 0 0
t$perf-norm:sp_client_order 19840 19840 0 0.00 0 18734 21 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 2554 10 0 0
t$perf-norm:sp_pay_from_customer 6815 6815 0 0.00 0 14180 17 0 0
t$perf-norm:sp_pay_to_supplier 5698 5698 0 0.00 0 5429 16 0 0
t$perf-norm:sp_reserve_write_off 9751 9751 0 0.00 0 13503 14 0 0
t$perf-norm:sp_supplier_invoice 5374 5374 0 0.00 0 12809 26 0 0
t$perf-norm:sp_supplier_order 5386 5386 0 0.00 0 6999 17 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 626 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2255 36 0 0
t$perf-norm:srv_make_invnt_saldo 511 511 0 0.00 0 4073 27 0 0
t$perf-norm:srv_make_money_saldo 735 735 0 0.00 0 3643 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9920 9640 280 2.82 0 11220 22 0 280
v_all_customers 35868 35868 0 0.00 0 17 1 0 0
v_all_suppliers 29894 29894 0 0.00 0 15 1 0 0
v_all_wares 39680 39680 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2599 11 0 0
v_cancel_client_order 3430 3430 0 0.00 0 3987 6 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 3701 10 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 1752 5 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 2661 10 0 0
v_cancel_supplier_invoice 788 768 20 2.53 0 545 15 0 20
v_cancel_supplier_order 810 810 0 0.00 0 1142 6 0 0
v_cancel_write_off 1994 1994 0 0.00 0 2605 12 0 0
v_max_id_clo_ord 5386 5386 0 0.00 0 207 1 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 4315 19 0 0
v_max_id_ord_sup 5358 5358 0 0.00 0 2696 3 0 0
v_max_non_paid_invoice 1495 1495 0 0.00 0 857 3 0 0
v_max_non_paid_realizn 3676 3676 0 0.00 0 3452 5 0 0
v_min_id_clo_ord 5386 5386 0 0.00 0 3157 5 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 1972 9 0 0
v_min_id_ord_sup 5358 5358 0 0.00 0 10004 8 0 0
v_min_non_paid_invoice 5698 5698 0 0.00 0 13840 25 0 0
v_min_non_paid_realizn 6815 6815 0 0.00 0 10526 22 0 0
v_our_firm 10090 10090 0 0.00 0 16 1 0 0
v_reserve_write_off 19502 19502 0 0.00 0 9102 6 0 0
x_lock_depdocs_on_canc_invoice 11978 11978 0 0.00 0 38409 124 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 10679 352 0 0
x_make_qstorno_1000_1200 5386 5386 0 0.00 2 37125 1268 0 0
x_make_qstorno_1200_2000 5374 5374 0 0.00 3 64910 3211 0 0
x_make_qstorno_2100_3300 454567 454567 0 0.00 0 53100 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2021 01:39:57 to 09-08-2021 01:40:01.

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:45 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:46 27273 8192000000 10367750336 10690080768 1417240832 14.748 100 84 167 1 1450640976 5990416 27409728
23:10:21 8316 8192000000 10447324528 10777636864 1502707840 15.500 100 92 184 0 1539809904 8144208 28957856
23:11:40 13448 8192000000 10472759104 10817302528 1535088192 15.781 100 76 152 0 1569026272 9772160 24165920
23:13:03 13084 8192000000 10554467568 10913050624 1619799808 16.508 100 75 150 0 1652271680 10558096 21913776
23:14:18 7703 8192000000 10648851840 11017035776 1709534320 17.265 100 76 151 1 1742364960 11123792 21706848
23:15:27 5694 8192000000 10695517200 11074027520 1762022528 17.701 100 82 162 3 1798625760 13785280 22817952
23:16:34 3567 8192000000 10753247040 11132825600 1806102736 18.064 100 86 170 3 1844275280 14186672 23985872
23:17:40 2301 8192000000 10796300736 11181277184 1849137296 18.415 100 87 175 1 1886388368 12902704 24348368
23:18:43 40715 8192000000 10795323088 11198128128 1870170800 18.586 100 52 105 1 1893874832 8560480 15143552
23:20:25 8289 8192000000 10786600304 11210928128 1875393408 18.628 100 34 69 1 1892189680 6766448 10029824
23:21:37 11853 8192000000 10789014816 11223629824 1884662112 18.703 100 25 48 1 1898210752 6907616 6641024
23:22:49 12460 8192000000 10809814656 11238686720 1899759120 18.824 100 21 42 0 1912080960 5833440 6488400
23:24:05 22348 8192000000 10857120496 11267575808 1913029744 18.931 100 63 127 2 1938663312 5978560 19655008
23:25:28 5154 8192000000 10826777056 11260178432 1912068704 18.923 100 35 70 2 1929638064 7571264 9998096
23:26:33 11535 8192000000 10813873744 11241975808 1865819088 18.550 98 20 40 1 1877272448 5555184 5898176
23:27:47 53974 8192000000 10809218768 11214622720 1834389792 18.295 97 50 100 0 1855070944 5620576 15060576
23:29:49 4371 8192000000 10746384192 11160551424 1798880512 18.005 98 39 78 3 1815915216 5695840 11338864
23:30:55 7997 8192000000 10712583264 11126288384 1732397392 17.455 96 32 64 2 1748209104 6572720 9238992
23:32:04 12945 8192000000 10748932128 11157749760 1815096192 18.138 100 32 63 0 1830419536 6074160 9249184
23:33:17 23068 8192000000 10742278528 11169361920 1827664512 18.240 100 15 30 1 1836756816 4448368 4643936
23:34:44 3269 8192000000 10767553888 11200409600 1859295072 18.498 100 29 58 1 1874534800 6053184 9186544
23:35:48 4756 8192000000 10784309008 11214528512 1867564288 18.565 100 12 24 1 1876484080 5865856 3053936
23:36:55 69798 8192000000 10808770704 11235401728 1887765088 18.728 100 36 70 2 1903551232 4998208 10787936
23:39:12 36795 8192000000 10865623472 11288322048 1922931696 19.010 100 44 87 2 1947571728 12782112 11857920
23:40:56 26372 8192000000 10892525360 11325628416 1956530000 19.278 100 47 94 2 1986865904 16283520 14052384
23:42:25 25050 8192000000 10907252672 11352563712 1974608624 19.422 100 45 90 1 2009429968 20709344 14112000
23:44:04 11851 8192000000 10931343248 11366932480 1990428240 19.547 100 49 98 3 2026305744 21006688 14870816
23:45:22 17331 8192000000 10932588144 11366690816 1986900224 19.519 100 59 118 0 2029396672 25104640 17391808
23:46:45 7817 8192000000 10975824288 11402874880 2004917216 19.661 100 58 116 1 2051004032 29469920 16616896
23:47:56 8738 8192000000 10982341568 11417743360 2010546816 19.706 100 45 90 0 2063288448 38673808 14067824
23:49:10 8666 8192000000 10984834880 11420786688 2004878112 19.661 100 58 116 2 2073891920 49840416 19173392
23:50:23 2017 8192000000 10988054848 11407417344 1973168960 19.411 98 77 147 2 2043084784 47649728 22266096
23:51:30 3748 8192000000 10967362992 11392393216 1979668464 19.462 99 64 128 2 2049973552 50373456 19931632
23:52:40 2971 8192000000 10989081456 11411779584 1990540400 19.548 100 76 153 3 2074444944 59503344 24401200
23:53:48 1844 8192000000 11011900016 11434811392 2008147808 19.687 100 84 167 1 2095246864 59853360 27245696
23:54:56 1553 8192000000 11006779024 11421069312 2001332256 19.633 100 87 172 2 2090761440 61963856 27465328
23:56:00 6765 8192000000 10988069248 11422171136 1975289536 19.427 99 47 94 2 2058489744 67404336 15795872
23:57:20 15814 8192000000 10971804416 11418624000 1964915216 19.345 99 41 82 2 2050789072 71232048 14641808
23:58:37 5625 8192000000 10955305280 11388936192 1958743552 19.296 100 40 81 3 2036089600 63158560 14187488
23:59:47 16054 8192000000 10964984976 11409747968 1958099248 19.291 99 35 70 0 2037265824 66512256 12654320
00:01:10 16220 8192000000 10943492688 11394547712 1929996720 19.067 99 33 66 2 2013396976 70453728 12946528
00:02:31 22170 8192000000 10957664864 11409604608 1938891280 19.138 99 29 58 1 2024384320 74744192 10748848
00:03:59 46231 8192000000 10891291088 11359453184 1926183856 19.036 99 12 24 1 1965309504 35840544 3285104
00:05:47 37412 8192000000 10868439856 11296972800 1901126960 18.835 98 34 66 1 1919944144 9347648 9469536
00:07:25 112589 8192000000 10889161680 11351318528 1924556432 19.023 98 31 60 0 1944279520 11487120 8235968
00:10:32 57567 8192000000 10884055584 11347111936 1958170384 19.291 100 9 18 0 1971026336 10255040 2600912
00:12:30 16841 8192000000 10905373696 11362705408 1971329744 19.396 100 16 35 0 1987681024 11822016 4529264
00:13:47 170004 8192000000 10876639632 11339087872 1895660016 18.791 96 14 28 0 1913718416 14507392 3551008
00:17:46 40248 8192000000 10883343744 11347849216 1935928304 19.114 98 24 47 0 1950074816 7716000 6430512
00:19:26 6947 8192000000 10933206048 11367096320 1970702480 19.391 99 62 122 2 1998287312 9547520 18037312
00:20:36 48615 8192000000 10936934784 11381665792 1963590864 19.335 99 21 42 0 1986127744 16052640 6484240
00:22:32 37216 8192000000 10925022912 11392323584 1992399504 19.563 100 18 37 1 2013624640 16105216 5119920
00:24:10 88508 8192000000 10926699584 11400630272 1995644624 19.588 100 12 24 0 2013302768 14109168 3548976
00:26:39 50210 8192000000 10948113408 11408281600 2003909968 19.654 100 22 44 2 2021351776 11286624 6155184
00:29:05 75693 8192000000 10918385904 11410726912 1952603376 19.247 97 18 36 1 1972846416 15614400 4628640
00:31:26 14799 8192000000 10974184576 11413618688 1996966400 19.599 99 57 114 1 2029353712 16139520 16247792
00:32:44 91911 8192000000 10933193184 11421868032 1982510576 19.485 99 17 34 1 2007206896 20145936 4550384
00:35:24 47242 8192000000 10943002272 11389018112 1966048800 19.354 98 45 88 3 1987652432 9855520 11748112
00:37:16 70315 8192000000 10892741056 11355750400 1874324432 18.619 94 21 42 1 1892353584 12784800 5244352
00:39:27 96513 8192000000 10868807920 11333529600 1895623936 18.791 96 34 67 3 1915437968 10228000 9586032
00:42:07 2649 8192000000 10887220304 11333697536 1934859776 19.106 99 61 120 1 1960390240 8729456 16801008
00:43:10 77318 8192000000 10873069664 11358068736 1926230768 19.037 99 11 21 2 1944798160 15379488 3187904
00:45:30 61761 8192000000 10831980080 11294031872 1836172384 18.310 95 13 26 0 1850545008 10301520 4071104
00:47:47 20273 8192000000 10817381920 11282153472 1879518336 18.661 99 17 34 2 1899913040 15139040 5255664
00:49:08 67545 8192000000 10835335616 11312066560 1880741216 18.671 98 19 38 0 1903071904 16633264 5697424
00:51:15 59634 8192000000 10845372896 11309314048 1847534000 18.402 96 19 38 1 1872544096 19676784 5333312
00:53:23 48537 8192000000 10864965088 11323764736 1899593072 18.823 99 31 62 1 1921262336 13149600 8519664
00:55:11 57496 8192000000 10833090288 11310071808 1862517360 18.524 96 15 30 0 1887348928 20426496 4405072
00:57:16 43111 8192000000 10862130192 11347202048 1923678672 19.016 99 22 44 1 1950843872 20294896 6870304
00:59:03 53538 8192000000 10904599904 11361808384 1916430624 18.958 98 14 28 0 1932344720 12333072 3581024
01:00:59 143475 8192000000 10891534384 11380105216 1947703520 19.208 99 17 34 0 1963226000 11301504 4220976
01:04:27 81981 8192000000 10900307776 11377238016 1936586448 19.120 98 23 46 0 1956370416 13781920 6002048
01:06:59 27173 8192000000 10889534800 11359461376 1955428368 19.270 99 16 31 0 1970608880 10977504 4203008
01:08:29 39873 8192000000 10870448432 11350667264 1945602000 19.191 99 9 18 0 1954109632 6586800 1920832
01:10:17 67912 8192000000 10880352016 11361181696 1932481216 19.087 98 12 24 0 1945104336 9283312 3339808
01:12:35 35765 8192000000 10919468400 11390398464 1987888112 19.527 100 18 36 1 2003304064 10765664 4650288
01:14:12 59437 8192000000 10912295776 11384860672 1951751120 19.240 98 18 36 0 1965213728 8159520 5303088
01:16:16 71610 8192000000 10915915056 11407421440 1976274880 19.435 99 15 30 0 1990610432 9522496 4813056
01:18:28 47445 8192000000 10914726864 11387731968 1930218912 19.069 96 17 34 0 1942633568 7986384 4428272
01:20:17 47716 8192000000 10939263360 11413348352 2000299120 19.625 100 28 56 0 2018449456 10460432 7689904
01:22:11 122580 8192000000 10913600256 11381882880 1838228128 18.326 92 9 18 0 1855756528 15013472 2514928
01:25:15 126254 8192000000 10868436640 11352633344 1898953728 18.818 97 7 14 0 1923787408 22805120 2028560
01:28:25 69921 8192000000 10853609200 11339202560 1914885616 18.946 99 13 27 1 1931226624 12822384 3518624
01:30:44 52937 8192000000 10820225200 11296092160 1871749056 18.598 98 9 18 0 1881307648 7219200 2339392
01:32:37 54323 8192000000 10852154192 11324968960 1899307520 18.821 98 16 32 2 1916610832 11739680 5563632
01:34:36 76756 8192000000 10835798864 11314446336 1891936144 18.761 97 22 44 0 1913631280 14594032 7101104
01:36:53 59882 8192000000 9952342816 10364764160 844870064 9.349 44 4 8 1 854685760 8626896 1188800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-08-2021 01:40:03 to 09-08-2021 01:40:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 140 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2021 01:40:19 to 09-08-2021 01:40:21.

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          233861
MON$OLDEST_ACTIVE               236021
MON$OLDEST_SNAPSHOT             236021
MON$NEXT_TRANSACTION            236021
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                       2022784
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:413: 
:414: mscs-test-161.budzdorov.ru    Mon Aug  9 01:38:00 2021
:415:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:416: 
:417: 
:418: mscs-test-161.budzdorov.ru    Mon Aug  9 01:38:06 2021
:419:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:420: 
:421: 
:422: mscs-test-161.budzdorov.ru    Mon Aug  9 01:38:07 2021
:423:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:424: 
--- end of diff output ---

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

09.08.21 01:40:22. 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-08-09 01:40:24 No severe PSQL-related problems occured
09.08.21 01:40:25. End of report.