Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.06.2022 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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16201464 kB
MemAvailable 31271772 kB
Buffers 0 kB
Cached 15485788 kB
SwapCached 2440 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.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-06-12 01:38:18
DTS_END                         2022-06-12 01:38:18.6950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_MAKE_MONEY_SALDO(33:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/40130
TRN_ID                          236332
ATT_ID                          673
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: 9363.42 19891 1123610
customer order: creation 168.78 350 20254
customer order: refuse 14.14 431 1697
order to supplier: creation 45.51 1344 5461
order to supplier: removal 3.37 110358 404
invoice (draft): creation 44.34 3364 5321
invoice (draft): removal 52.83 848 6339
invoice accept: apply 41.51 18246 4981
invoice accept: cancel 47.58 837 5710
customer reserve: creation 3896.21 177 467545
customer reserve: removal 4768.99 61 572279
realization accept: apply 81.86 200 9823
realization accept: cancel 72.47 113 8696
payment from customer: creation 57.01 130 6841
payment from customer: removal 5.73 104 688
payment to supplier: creation 47.85 167 5742
payment to supplier: removal 3.68 132 441
service: total inventory turnovers 4.69 1194 563
service: total monetary turnovers 6.13 39 736
service: aggregate perf. data 0.71 20779 85
service: refresh index statistics 0.03 238955 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-06-2022 01:39:38 to 12-06-2022 01:39:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6634
TEST_TIME 2 6357
TEST_TIME 3 5219
TEST_TIME 4 6038
TEST_TIME 5 5763
TEST_TIME 6 5421
TEST_TIME 7 5733
TEST_TIME 8 6192
TEST_TIME 9 6814
TEST_TIME 10 6885
TEST_TIME 11 6889
TEST_TIME 12 7173
TEST_TIME 13 7733
TEST_TIME 14 8055
TEST_TIME 15 8254
TEST_TIME 16 8361
TEST_TIME 17 8263
TEST_TIME 18 8348
TEST_TIME 19 8482
TEST_TIME 20 8378
TEST_TIME 21 8279
TEST_TIME 22 8482
TEST_TIME 23 8628
TEST_TIME 24 8619
TEST_TIME 25 8820
TEST_TIME 26 8892
TEST_TIME 27 9322
TEST_TIME 28 9359
TEST_TIME 29 9541
TEST_TIME 30 9686
TEST_TIME 31 10410
TEST_TIME 32 11505
TEST_TIME 33 11317
TEST_TIME 34 11436
TEST_TIME 35 11410
TEST_TIME 36 11101
TEST_TIME 37 10921
TEST_TIME 38 10813
TEST_TIME 39 10620
TEST_TIME 40 10597
TEST_TIME 41 10417
TEST_TIME 42 10335
TEST_TIME 43 10255
TEST_TIME 44 10227
TEST_TIME 45 10127
TEST_TIME 46 9981
TEST_TIME 47 10001
TEST_TIME 48 9818
TEST_TIME 49 9961
TEST_TIME 50 10048
TEST_TIME 51 9903
TEST_TIME 52 9843
TEST_TIME 53 9754
TEST_TIME 54 9715
TEST_TIME 55 9646
TEST_TIME 56 9647
TEST_TIME 57 9489
TEST_TIME 58 9384
TEST_TIME 59 9498
TEST_TIME 60 9511
TEST_TIME 61 9523
TEST_TIME 62 9699
TEST_TIME 63 9632
TEST_TIME 64 9614
TEST_TIME 65 9691
TEST_TIME 66 9676
TEST_TIME 67 9704
TEST_TIME 68 9702
TEST_TIME 69 9755
TEST_TIME 70 9860
TEST_TIME 71 9870
TEST_TIME 72 9828
TEST_TIME 73 9915
TEST_TIME 74 9952
TEST_TIME 75 9834
TEST_TIME 76 9805
TEST_TIME 77 9841
TEST_TIME 78 9780
TEST_TIME 79 9710
TEST_TIME 80 9788
TEST_TIME 81 9723
TEST_TIME 82 9686
TEST_TIME 83 9728
TEST_TIME 84 9670
TEST_TIME 85 9625
TEST_TIME 86 9588
TEST_TIME 87 9524
TEST_TIME 88 9602
TEST_TIME 89 9591
TEST_TIME 90 9555
TEST_TIME 91 9626
TEST_TIME 92 9622
TEST_TIME 93 9647
TEST_TIME 94 9657
TEST_TIME 95 9689
TEST_TIME 96 9676
TEST_TIME 97 9682
TEST_TIME 98 9740
TEST_TIME 99 9726
TEST_TIME 100 9737
TEST_TIME 101 9714
TEST_TIME 102 9705
TEST_TIME 103 9761
TEST_TIME 104 9678
TEST_TIME 105 9660
TEST_TIME 106 9642
TEST_TIME 107 9594
TEST_TIME 108 9598
TEST_TIME 109 9589
TEST_TIME 110 9550
TEST_TIME 111 9521
TEST_TIME 112 9483
TEST_TIME 113 9446
TEST_TIME 114 9454
TEST_TIME 115 9434
TEST_TIME 116 9447
TEST_TIME 117 9440
TEST_TIME 118 9452
TEST_TIME 119 9433
TEST_TIME 120 9369

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-06-2022 01:39:41 to 12-06-2022 01:39:45.

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 1557030 1557030 0 0.00 0 27416 6 0 0
doc_list_biud 1557030 1557030 0 0.00 0 48012 29 0 0
sp_add_invoice_to_stock 5109 4981 128 2.50 61 195721 18246 0 128
sp_cancel_adding_invoice 5710 5710 0 0.00 5 70588 838 0 0
sp_cancel_client_order 1697 1697 0 0.00 4 15329 432 0 0
sp_cancel_customer_reserve 572279 572279 0 0.00 1 24772 61 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 3 8764 104 0 0
sp_cancel_pay_to_supplier 441 441 0 0.00 3 4677 132 0 0
sp_cancel_supplier_invoice 6349 6339 10 0.15 2 48019 849 0 10
sp_cancel_supplier_order 404 404 0 0.00 7 904719 110359 0 0
sp_cancel_write_off 8696 8696 0 0.00 1 14550 113 0 0
sp_client_order 20254 20254 0 0.00 3 26791 351 0 0
sp_customer_reserve 467545 467545 0 0.00 0 42561 177 0 0
sp_fill_shopping_cart 31033 31033 0 0.00 1 14073 41 0 0
sp_fill_shopping_cart_clo_res 467537 467537 0 0.00 0 11941 12 0 0
sp_get_clo_for_invoice 4968 4968 0 0.00 0 23502 286 0 0
sp_kill_cost_storno 15535 15535 0 0.00 0 11132 57 0 0
sp_kill_qstorno_ret_qs2qd 1159997 1159997 0 0.00 0 48007 33 0 0
sp_kill_qty_storno 609916 609916 0 0.00 0 48009 72 0 0
sp_lock_selected_doc 20548 20548 0 0.00 0 9172 6 0 0
sp_make_cost_storno 27374 27374 0 0.00 0 27415 60 0 0
sp_multiply_rows_for_pdistr 27374 27374 0 0.00 0 15369 34 0 0
sp_multiply_rows_for_qdistr 20254 20254 0 0.00 0 14637 144 0 0
sp_pay_from_customer 6841 6841 0 0.00 4 27420 130 0 0
sp_pay_to_supplier 5742 5742 0 0.00 4 16292 167 0 0
sp_payment_common 12583 12583 0 0.00 2 10113 29 0 0
sp_qd_handle_on_cancel_clo 1697 1697 0 0.00 0 12756 373 0 0
sp_qd_handle_on_invoice_upd_sts 10678 10678 0 0.00 0 25663 355 0 0
sp_qd_handle_on_reserve_upd_sts 9823 9823 0 0.00 0 22501 69 0 0
sp_reserve_write_off 9823 9823 0 0.00 6 22659 201 0 0
sp_supplier_invoice 5321 5321 0 0.00 6 74483 3365 0 0
sp_supplier_order 5461 5461 0 0.00 5 42890 1345 0 0
srv_aggregate_perf_data 85 85 0 0.00 3030 189364 20779 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1850 115892 47695 0 0
srv_find_qd_qs_mism 1076621 1076621 0 0.00 0 11843 4 0 0
srv_make_invnt_saldo 563 563 0 0.00 31 47003 1195 0 0
srv_make_money_saldo 736 736 0 0.00 1 7001 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 105073 462823 238956 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 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 4981 4981 0 0.00 0 11887 167 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 3558 119 0 0
t$perf-norm:sp_cancel_client_order 1697 1697 0 0.00 0 3165 16 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 2745 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 2128 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 441 441 0 0.00 0 1092 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 1415 19 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 25594 1730 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 1125 10 0 0
t$perf-norm:sp_client_order 20254 20254 0 0.00 0 6996 17 0 0
t$perf-norm:sp_customer_reserve 1003 1003 0 0.00 0 4100 9 0 0
t$perf-norm:sp_pay_from_customer 6841 6841 0 0.00 0 4646 9 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 2634 7 0 0
t$perf-norm:sp_reserve_write_off 9823 9823 0 0.00 0 2985 9 0 0
t$perf-norm:sp_supplier_invoice 5321 5321 0 0.00 0 13246 18 0 0
t$perf-norm:sp_supplier_order 5461 5461 0 0.00 0 7100 18 0 0
t$perf-norm:srv_aggregate_perf_data 85 85 0 0.00 0 1670 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 93 2 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 1082 21 0 0
t$perf-norm:srv_make_money_saldo 736 736 0 0.00 0 441 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10192 9936 256 2.51 0 11121 23 0 256
v_all_customers 36292 36292 0 0.00 0 20 1 0 0
v_all_suppliers 30176 30176 0 0.00 0 19 1 0 0
v_all_wares 40508 40508 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 909 7 0 0
v_cancel_client_order 3394 3394 0 0.00 0 14255 20 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 8734 17 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 2782 6 0 0
v_cancel_payment_to_supplier 882 882 0 0.00 0 4669 28 0 0
v_cancel_supplier_invoice 848 828 20 2.35 0 1804 16 0 20
v_cancel_supplier_order 808 808 0 0.00 0 8730 29 0 0
v_cancel_write_off 2028 2028 0 0.00 0 13516 24 0 0
v_max_id_clo_ord 5461 5461 0 0.00 0 1412 2 0 0
v_max_id_clo_res 1003 1003 0 0.00 0 7390 18 0 0
v_max_id_ord_sup 5318 5318 0 0.00 0 5752 4 0 0
v_max_non_paid_invoice 1433 1433 0 0.00 0 509 3 0 0
v_max_non_paid_realizn 3857 3857 0 0.00 0 3894 5 0 0
v_min_id_clo_ord 5461 5461 0 0.00 0 3030 7 0 0
v_min_id_clo_res 1003 1003 0 0.00 0 1594 7 0 0
v_min_id_ord_sup 5318 5318 0 0.00 0 3409 5 0 0
v_min_non_paid_invoice 5742 5742 0 0.00 0 9179 25 0 0
v_min_non_paid_realizn 6841 6841 0 0.00 0 9447 18 0 0
v_our_firm 10184 10184 0 0.00 0 22 1 0 0
v_reserve_write_off 19646 19646 0 0.00 0 11443 11 0 0
x_lock_depdocs_on_canc_invoice 11635 11635 0 0.00 0 20988 138 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 5507 379 0 0
x_make_qstorno_1000_1200 5461 5461 0 0.00 1 42858 1250 0 0
x_make_qstorno_1200_2000 5321 5321 0 0.00 3 69819 3226 0 0
x_make_qstorno_2100_3300 467545 467545 0 0.00 0 34158 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-06-2022 01:39:46 to 12-06-2022 01:39:50.

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:08:22 10 8192000000 8811697456 8827412480 15818256 0.192 5 2 3 0 16034976 78272 138448
23:09:23 27421 8192000000 9905487280 10217705472 985081536 10.734 100 82 164 0 1011914736 5399424 21433776
23:10:58 15924 8192000000 9950087024 10271428608 1033524816 11.202 100 91 182 0 1065001072 8548224 22928032
23:12:33 12402 8192000000 10006368256 10344411136 1089252384 11.736 100 87 174 0 1120695680 10332096 21111200
23:13:51 14984 8192000000 10047093024 10396262400 1129454912 12.116 100 73 146 0 1157282176 10853424 16973840
23:15:09 7602 8192000000 10103699520 10463961088 1185372560 12.640 100 82 164 0 1216952192 13337120 18242512
23:16:18 5208 8192000000 10140118208 10510405632 1221399008 12.975 100 82 164 1 1253876352 13567792 18909552
23:17:25 2842 8192000000 10183575280 10558918656 1269516528 13.417 100 87 174 0 1302084960 12807040 19761392
23:18:30 2095 8192000000 10210573584 10592559104 1292118128 13.624 100 89 174 2 1324202928 13087248 18997552
23:19:33 36278 8192000000 10208267568 10611150848 1306800928 13.757 100 43 86 0 1325510272 8672576 10036768
23:21:11 4759 8192000000 10224092368 10630303744 1321838080 13.893 100 40 80 0 1339755104 9731616 8185408
23:22:17 9415 8192000000 10213089616 10631991296 1324964784 13.922 100 17 34 2 1334993728 6188000 3840944
23:23:29 76807 8192000000 10233483856 10652889088 1331977408 13.985 100 29 58 1 1344100944 5190128 6933408
23:25:48 5768 8192000000 10230716608 10652934144 1341323088 14.069 100 26 52 1 1353515984 6385104 5807792
23:26:57 6842 8192000000 10223968800 10646618112 1323098864 13.905 99 20 40 1 1334272400 5946400 5227136
23:28:06 51827 8192000000 10202682784 10620211200 1288292960 13.589 97 38 74 2 1302514368 5183504 9037904
23:29:58 4604 8192000000 10191006400 10600038400 1286942432 13.576 99 30 57 1 1298963744 5693728 6327584
23:31:04 6193 8192000000 10150586320 10548006912 1228401616 13.039 98 28 56 0 1240469008 5231104 6836288
23:32:13 65474 8192000000 10141743632 10551451648 1240684592 13.153 98 33 65 1 1254966832 6601184 7681056
23:34:21 8204 8192000000 10156471312 10565849088 1268737712 13.410 100 20 40 0 1278630688 5343040 4549936
23:35:31 7809 8192000000 10183230208 10597400576 1294739104 13.647 100 22 44 0 1305386464 5810672 4836688
23:36:39 15874 8192000000 10220231504 10618867712 1303847488 13.730 100 25 49 0 1315820336 4898720 7074128
23:37:55 3028 8192000000 10223627072 10617245696 1309223472 13.779 100 71 144 1 1333093312 5519184 18350656
23:39:05 9011 8192000000 10243672416 10654572544 1336089344 14.022 100 61 122 2 1359156480 8469872 14597264
23:40:20 18803 8192000000 10255325872 10676183040 1353511664 14.179 100 42 84 1 1373553920 10253088 9789168
23:41:41 31621 8192000000 10276689504 10696744960 1366836688 14.299 100 54 108 1 1391452896 11866192 12750016
23:43:17 8674 8192000000 10282117792 10704338944 1375817872 14.379 100 38 75 0 1394617024 9840464 8958688
23:44:37 13790 8192000000 10287842384 10708881408 1378286016 14.401 100 47 94 0 1403685424 14543312 10856096
23:46:09 4171 8192000000 10308438448 10726543360 1383345968 14.446 100 58 116 0 1413933248 16257824 14329456
23:47:15 3216 8192000000 10305400368 10724683776 1378646736 14.404 99 64 128 1 1412682944 18419328 15616880
23:48:24 10116 8192000000 10299505840 10720264192 1376565968 14.386 100 57 114 0 1408910816 18533680 13811168
23:49:39 7699 8192000000 10317185456 10734206976 1384045296 14.453 100 74 148 0 1421603696 19648560 17909840
23:50:51 5598 8192000000 10326063088 10744483840 1391892880 14.523 100 77 152 0 1430833008 21686208 17253920
23:52:03 3512 8192000000 10324355056 10740051968 1378048880 14.399 99 88 172 2 1422523424 24269152 20205392
23:53:12 1849 8192000000 10326430608 10736713728 1374421136 14.367 99 79 158 2 1425865616 31198576 20245904
23:54:17 10975 8192000000 10300985040 10723819520 1363421600 14.268 100 54 106 1 1406947280 30655072 12870608
23:55:35 8298 8192000000 10316886544 10725232640 1364593856 14.279 100 68 133 1 1417796752 36936560 16266336
23:56:48 3801 8192000000 10366609408 10775752704 1403387328 14.625 100 76 152 0 1468409648 45753312 19269008
23:57:54 3708 8192000000 10357034784 10769625088 1382438608 14.438 98 67 132 6 1442791440 43856928 16495904
23:59:06 16149 8192000000 10344649760 10768375808 1373189712 14.356 98 48 96 1 1439799984 53930208 12680064
00:00:29 6105 8192000000 10348694448 10762674176 1381642048 14.431 100 53 106 1 1454037984 58273952 14121984
00:01:40 10016 8192000000 10354074880 10777755648 1393215344 14.535 100 44 87 2 1460818752 55670768 11932640
00:02:54 17249 8192000000 10352672544 10778566656 1388290560 14.491 99 50 100 0 1463544576 62090880 13163136
00:04:20 14357 8192000000 10378106832 10804682752 1400737984 14.602 100 46 92 1 1483846224 70358832 12749408
00:05:37 18573 8192000000 10372343824 10814996480 1399670704 14.592 100 37 73 1 1479573328 69676240 10226384
00:07:17 23590 8192000000 10337187600 10782670848 1377873200 14.398 100 29 58 2 1457396192 70913952 8609040
00:09:06 26064 8192000000 10346090160 10798768128 1376968688 14.389 100 21 42 1 1456080624 72466544 6645392
00:10:34 60419 8192000000 10278931968 10706460672 1321200864 13.888 96 37 71 1 1352320704 21704784 9415056
00:12:44 102397 8192000000 10287621152 10732097536 1345388944 14.106 98 9 17 2 1365515360 18238832 1887584
00:15:33 55212 8192000000 10299944528 10741276672 1384294624 14.455 100 25 52 0 1398362992 8785328 5283040
00:17:28 64816 8192000000 10283523952 10748805120 1390582512 14.511 100 33 66 0 1408665328 11035664 7047152
00:19:46 87444 8192000000 10292309904 10752933888 1392744080 14.530 100 23 45 0 1407609632 9705952 5159600
00:22:14 61591 8192000000 10299423632 10759507968 1397855200 14.576 100 11 22 0 1411323920 10713888 2754832
00:24:18 74649 8192000000 10325803408 10784452608 1409844352 14.683 100 19 36 1 1433421360 19149504 4427504
00:26:34 61936 8192000000 10305836928 10779971584 1395104912 14.551 99 29 58 0 1430921920 28119872 7697136
00:28:47 80468 8192000000 10311152608 10782138368 1406691744 14.655 100 11 22 0 1420762512 11587760 2483008
00:31:10 46711 8192000000 10307667344 10772881408 1394316736 14.544 99 30 60 0 1419933344 18976144 6640464
00:32:58 70532 8192000000 10310549184 10777362432 1393665808 14.539 99 14 28 1 1409825424 12902736 3256880
00:35:16 98525 8192000000 10307380064 10762829824 1372445552 14.349 97 18 36 0 1393878880 17205056 4228272
00:37:57 71044 8192000000 10305429536 10768752640 1379483920 14.412 98 10 20 0 1398112368 16329024 2299424
00:40:08 83218 8192000000 10283610656 10742689792 1272934224 13.448 91 5 10 1 1287706768 13836640 935904
00:42:37 97005 8192000000 10295269472 10747469824 1350738896 14.154 97 15 30 0 1365108128 10741168 3628064
00:45:42 19910 8192000000 10277669392 10729955328 1346272688 14.114 97 29 56 1 1361142624 8797648 6072288
00:47:06 65596 8192000000 10294930640 10748375040 1344689008 14.100 97 20 40 0 1363569184 13807120 5073056
00:49:12 71497 8192000000 10269569104 10714804224 1289315760 13.598 94 9 18 0 1305630976 14297184 2018032
00:51:24 68324 8192000000 10273383808 10738028544 1330458432 13.971 98 4 8 0 1349168352 17917232 792688
00:53:34 81499 8192000000 10266438160 10720063488 1363800544 14.271 100 31 62 0 1381952288 10580032 7571712
00:55:58 81144 8192000000 10278652192 10720772096 1337834080 14.038 97 29 58 1 1352104592 8319040 5951472
00:58:42 50742 8192000000 10259932064 10707034112 1307499136 13.763 96 15 30 0 1326336144 14827776 4009232
01:00:34 37564 8192000000 10253877296 10701586432 1335305600 14.015 98 20 40 0 1352187344 12332992 4548752
01:02:17 47365 8192000000 10252271920 10704584704 1334019616 14.003 98 14 28 1 1346434416 9162576 3252224
01:04:37 63735 8192000000 10255757104 10714193920 1344159216 14.095 99 11 22 0 1358873648 12116976 2597456
01:06:42 65696 8192000000 10263635264 10718756864 1364535008 14.278 100 6 12 0 1379389792 13588080 1266704
01:08:50 102005 8192000000 10272814896 10722791424 1331699328 13.983 97 12 24 0 1344031232 10152800 2179104
01:11:33 108077 8192000000 10283724448 10736160768 1328358496 13.952 96 14 28 1 1340846080 9323280 3164304
01:14:28 71685 8192000000 10286340400 10746855424 1370247120 14.329 99 27 54 0 1387335312 11196848 5891344
01:16:48 74557 8192000000 10314077088 10766229504 1393902128 14.541 100 14 28 0 1418339136 21406752 3030256
01:19:03 67198 8192000000 10313878544 10758115328 1367336640 14.303 98 11 22 0 1378922224 9361792 2223792
01:21:13 68396 8192000000 10303471888 10762600448 1374173120 14.364 98 28 56 2 1398392400 17395584 6823696
01:23:25 115892 8192000000 10289618384 10746580992 1341543840 14.071 96 21 41 0 1355445792 8980352 4921600
01:26:23 98392 8192000000 10268919824 10736615424 1306112224 13.751 94 8 16 0 1320423776 12387472 1924080
01:29:01 67854 8192000000 10275353152 10732277760 1374182272 14.365 100 10 20 0 1384082400 7597200 2302928
01:31:52 38935 8192000000 10277849760 10732912640 1351539440 14.161 98 18 36 0 1370653584 14441328 4672816
01:33:43 44331 8192000000 10259102208 10715721728 1292747056 13.629 94 6 12 0 1306116800 11935824 1433920
01:35:28 68316 8192000000 10253915440 10711126016 1312864176 13.812 96 27 54 0 1331902592 12635120 6403296
01:37:50 33070 8192000000 9593796784 9931735040 725061552 8.131 53 17 33 0 740156432 11396256 3698624

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-06-2022 01:39:52 to 12-06-2022 01:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 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 12-06-2022 01:40:08 to 12-06-2022 01: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          234726
MON$OLDEST_ACTIVE               236549
MON$OLDEST_SNAPSHOT             236549
MON$NEXT_TRANSACTION            236549
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                       2043744
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:409: 
:410: mscs-test-161.budzdorov.ru    Sun Jun 12 01:38:29 2022
:411:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:412: 
:413: 
:414: mscs-test-161.budzdorov.ru    Sun Jun 12 01:38:30 2022
:415:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:416: 
:417: 
:418: mscs-test-161.budzdorov.ru    Sun Jun 12 01:38:31 2022
:419:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:420: 
:421: 
:422: mscs-test-161.budzdorov.ru    Sun Jun 12 01:38:33 2022
: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'

12.06.22 01: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
2022-06-12 01:40:13 No severe PSQL-related problems occured
12.06.22 01:40:14. End of report.