Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.08.2021 22:38
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 16317892 kB
MemAvailable 30988068 kB
Buffers 0 kB
Cached 15302288 kB
SwapCached 2624 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-07 22:37:42
DTS_END                         2021-08-07 22:37:42.3820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/50318
TRN_ID                          241249
ATT_ID                          678
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: 9473.39 22895 1136807
customer order: creation 174.18 361 20902
customer order: refuse 14.92 336 1790
order to supplier: creation 47.33 1234 5680
order to supplier: removal 3.64 98788 437
invoice (draft): creation 47.73 3181 5728
invoice (draft): removal 53.23 807 6388
invoice accept: apply 43.65 17246 5238
invoice accept: cancel 47.77 744 5732
customer reserve: creation 4065.56 170 487867
customer reserve: removal 4671.48 60 560578
realization accept: apply 85.23 171 10228
realization accept: cancel 87.35 109 10482
payment from customer: creation 60.38 97 7246
payment from customer: removal 5.74 76 689
payment to supplier: creation 49.69 127 5963
payment to supplier: removal 3.49 92 419
service: total inventory turnovers 4.64 1357 557
service: total monetary turnovers 6.63 19 796
service: aggregate perf. data 0.69 29380 83
service: refresh index statistics 0.03 303536 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-08-2021 22:38:31 to 07-08-2021 22:38:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1394
TEST_TIME 2 2195
TEST_TIME 3 3108
TEST_TIME 4 3877
TEST_TIME 5 4262
TEST_TIME 6 4084
TEST_TIME 7 4219
TEST_TIME 8 4212
TEST_TIME 9 4232
TEST_TIME 10 4459
TEST_TIME 11 4599
TEST_TIME 12 4629
TEST_TIME 13 5290
TEST_TIME 14 5837
TEST_TIME 15 5965
TEST_TIME 16 6411
TEST_TIME 17 6705
TEST_TIME 18 7177
TEST_TIME 19 7530
TEST_TIME 20 7851
TEST_TIME 21 8005
TEST_TIME 22 8383
TEST_TIME 23 8799
TEST_TIME 24 9307
TEST_TIME 25 9497
TEST_TIME 26 10014
TEST_TIME 27 10140
TEST_TIME 28 9884
TEST_TIME 29 9756
TEST_TIME 30 10051
TEST_TIME 31 9807
TEST_TIME 32 9728
TEST_TIME 33 9613
TEST_TIME 34 9721
TEST_TIME 35 9954
TEST_TIME 36 9877
TEST_TIME 37 9841
TEST_TIME 38 10038
TEST_TIME 39 9970
TEST_TIME 40 9866
TEST_TIME 41 10055
TEST_TIME 42 10121
TEST_TIME 43 10006
TEST_TIME 44 10060
TEST_TIME 45 10112
TEST_TIME 46 9974
TEST_TIME 47 9856
TEST_TIME 48 9739
TEST_TIME 49 9808
TEST_TIME 50 9701
TEST_TIME 51 9658
TEST_TIME 52 9613
TEST_TIME 53 9613
TEST_TIME 54 9656
TEST_TIME 55 9656
TEST_TIME 56 9608
TEST_TIME 57 9585
TEST_TIME 58 9470
TEST_TIME 59 9376
TEST_TIME 60 9377
TEST_TIME 61 9380
TEST_TIME 62 9385
TEST_TIME 63 9346
TEST_TIME 64 9397
TEST_TIME 65 9501
TEST_TIME 66 9421
TEST_TIME 67 9474
TEST_TIME 68 9562
TEST_TIME 69 9479
TEST_TIME 70 9607
TEST_TIME 71 9631
TEST_TIME 72 9699
TEST_TIME 73 9631
TEST_TIME 74 9716
TEST_TIME 75 9697
TEST_TIME 76 9815
TEST_TIME 77 9710
TEST_TIME 78 9646
TEST_TIME 79 9631
TEST_TIME 80 9572
TEST_TIME 81 9582
TEST_TIME 82 9560
TEST_TIME 83 9527
TEST_TIME 84 9486
TEST_TIME 85 9462
TEST_TIME 86 9393
TEST_TIME 87 9409
TEST_TIME 88 9416
TEST_TIME 89 9389
TEST_TIME 90 9365
TEST_TIME 91 9370
TEST_TIME 92 9413
TEST_TIME 93 9409
TEST_TIME 94 9422
TEST_TIME 95 9460
TEST_TIME 96 9503
TEST_TIME 97 9490
TEST_TIME 98 9552
TEST_TIME 99 9540
TEST_TIME 100 9624
TEST_TIME 101 9591
TEST_TIME 102 9630
TEST_TIME 103 9628
TEST_TIME 104 9635
TEST_TIME 105 9561
TEST_TIME 106 9582
TEST_TIME 107 9539
TEST_TIME 108 9471
TEST_TIME 109 9474
TEST_TIME 110 9470
TEST_TIME 111 9460
TEST_TIME 112 9472
TEST_TIME 113 9455
TEST_TIME 114 9454
TEST_TIME 115 9458
TEST_TIME 116 9497
TEST_TIME 117 9485
TEST_TIME 118 9473
TEST_TIME 119 9506
TEST_TIME 120 9473

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-08-2021 22:38:34 to 07-08-2021 22:38:38.

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 1588393 1588393 0 0.00 0 21483 6 0 0
doc_list_biud 1588393 1588393 0 0.00 0 41300 27 0 0
sp_add_invoice_to_stock 5347 5238 109 2.03 18 202164 17247 0 109
sp_cancel_adding_invoice 5732 5732 0 0.00 4 63945 745 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 15513 336 0 0
sp_cancel_customer_reserve 560578 560578 0 0.00 1 39759 61 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 5294 76 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 7464 92 0 0
sp_cancel_supplier_invoice 6393 6388 5 0.07 2 41306 807 0 5
sp_cancel_supplier_order 437 437 0 0.00 12 1028132 98789 0 0
sp_cancel_write_off 10482 10482 0 0.00 1 9033 109 0 0
sp_client_order 20902 20902 0 0.00 4 21724 361 0 0
sp_customer_reserve 487867 487867 0 0.00 0 171208 170 0 0
sp_fill_shopping_cart 32294 32294 0 0.00 1 17766 35 0 0
sp_fill_shopping_cart_clo_res 487866 487866 0 0.00 0 16296 14 0 0
sp_get_clo_for_invoice 5237 5237 0 0.00 0 35168 309 0 0
sp_kill_cost_storno 17322 17322 0 0.00 0 6844 55 0 0
sp_kill_qstorno_ret_qs2qd 1138463 1138463 0 0.00 0 41297 33 0 0
sp_kill_qty_storno 600872 600872 0 0.00 0 41299 71 0 0
sp_lock_selected_doc 21320 21320 0 0.00 0 8774 4 0 0
sp_make_cost_storno 28674 28674 0 0.00 0 21461 51 0 0
sp_multiply_rows_for_pdistr 28674 28674 0 0.00 0 6393 24 0 0
sp_multiply_rows_for_qdistr 20902 20902 0 0.00 0 18598 146 0 0
sp_pay_from_customer 7246 7246 0 0.00 4 11021 98 0 0
sp_pay_to_supplier 5963 5963 0 0.00 5 25901 127 0 0
sp_payment_common 13209 13209 0 0.00 1 25597 27 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 15505 317 0 0
sp_qd_handle_on_invoice_upd_sts 10969 10969 0 0.00 0 15303 317 0 0
sp_qd_handle_on_reserve_upd_sts 10228 10228 0 0.00 0 30861 65 0 0
sp_reserve_write_off 10228 10228 0 0.00 7 30885 171 0 0
sp_supplier_invoice 5728 5728 0 0.00 7 95807 3181 0 0
sp_supplier_order 5680 5680 0 0.00 5 38819 1235 0 0
srv_aggregate_perf_data 83 83 0 0.00 2953 405276 29380 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 3498 172627 46064 0 0
srv_find_qd_qs_mism 1087479 1087479 0 0.00 0 13035 5 0 0
srv_make_invnt_saldo 557 557 0 0.00 24 181341 1357 0 0
srv_make_money_saldo 796 796 0 0.00 1 813 19 0 0
srv_recalc_idx_stat 4 4 0 0.00 184165 586142 303537 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5238 5238 0 0.00 0 17229 147 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 6477 91 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 6253 19 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 2464 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 2087 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 1178 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 4978 34 0 0
t$perf-norm:sp_cancel_supplier_order 437 437 0 0.00 0 21432 1523 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 1350 7 0 0
t$perf-norm:sp_client_order 20902 20902 0 0.00 0 12875 17 0 0
t$perf-norm:sp_customer_reserve 1004 1004 0 0.00 0 5474 9 0 0
t$perf-norm:sp_pay_from_customer 7246 7246 0 0.00 0 5600 10 0 0
t$perf-norm:sp_pay_to_supplier 5963 5963 0 0.00 0 3794 11 0 0
t$perf-norm:sp_reserve_write_off 10228 10228 0 0.00 0 6587 9 0 0
t$perf-norm:sp_supplier_invoice 5728 5728 0 0.00 0 6088 17 0 0
t$perf-norm:sp_supplier_order 5680 5680 0 0.00 0 4229 12 0 0
t$perf-norm:srv_aggregate_perf_data 83 83 0 0.00 0 343 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1451 30 0 0
t$perf-norm:srv_make_invnt_saldo 557 557 0 0.00 0 1868 18 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 2531 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 69 21 0 0
v_add_invoice_to_stock 10692 10474 218 2.03 0 6850 17 0 218
v_all_customers 38536 38536 0 0.00 0 79 0 0 0
v_all_suppliers 31592 31592 0 0.00 0 15 1 0 0
v_all_wares 41802 41802 0 0.00 0 55 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1300 7 0 0
v_cancel_client_order 3580 3580 0 0.00 0 3543 5 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 4441 8 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 1170 5 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1733 7 0 0
v_cancel_supplier_invoice 808 798 10 1.23 0 1534 15 0 10
v_cancel_supplier_order 874 874 0 0.00 0 3770 13 0 0
v_cancel_write_off 2050 2050 0 0.00 0 2445 13 0 0
v_max_id_clo_ord 5679 5679 0 0.00 0 127 1 0 0
v_max_id_clo_res 1004 1004 0 0.00 0 5236 18 0 0
v_max_id_ord_sup 5714 5714 0 0.00 0 5682 4 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 481 3 0 0
v_max_non_paid_realizn 3659 3659 0 0.00 0 1334 3 0 0
v_min_id_clo_ord 5679 5679 0 0.00 0 3002 4 0 0
v_min_id_clo_res 1004 1004 0 0.00 0 3461 14 0 0
v_min_id_ord_sup 5714 5714 0 0.00 0 2735 4 0 0
v_min_non_paid_invoice 5963 5963 0 0.00 0 25594 20 0 0
v_min_non_paid_realizn 7246 7246 0 0.00 0 2357 10 0 0
v_our_firm 10440 10440 0 0.00 0 142 1 0 0
v_reserve_write_off 20456 20456 0 0.00 0 4228 5 0 0
x_lock_depdocs_on_canc_invoice 11721 11721 0 0.00 0 29052 116 0 0
x_lock_depdocs_on_canc_sup_ord 437 437 0 0.00 0 17180 450 0 0
x_make_qstorno_1000_1200 5680 5680 0 0.00 2 38808 1165 0 0
x_make_qstorno_1200_2000 5728 5728 0 0.00 4 95788 3051 0 0
x_make_qstorno_2100_3300 487867 487867 0 0.00 0 171206 154 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-08-2021 22:38:39 to 07-08-2021 22:38:43.

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:46 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:47 27577 8192000000 10379054496 10697777152 1423873424 14.807 100 84 168 0 1457190608 5889936 27427248
20:10:20 29357 8192000000 10445021168 10772135936 1486061824 15.354 100 90 180 0 1526674704 11579200 29033680
20:11:54 21177 8192000000 10484338752 10828341248 1543090816 15.850 100 79 158 0 1577047696 9427472 24529408
20:13:30 12344 8192000000 10592281360 10952855552 1646732400 16.737 100 76 152 1 1680639600 11585312 22321888
20:14:45 8350 8192000000 10652204640 11023118336 1712641392 17.291 100 84 166 1 1750392624 13624000 24127232
20:15:58 7070 8192000000 10696582448 11071442944 1766142656 17.735 100 81 162 2 1801808160 12535344 23130160
20:17:07 3409 8192000000 10755304592 11137880064 1811678352 18.110 100 85 168 1 1848895936 14328240 22889344
20:18:12 2084 8192000000 10793366064 11177656320 1848711216 18.412 100 88 176 2 1885913360 12767744 24434400
20:19:17 2178 8192000000 10825582880 11212259328 1878787888 18.655 100 90 180 0 1918333600 13701776 25843936
20:20:21 18798 8192000000 10826089296 11219103744 1881476272 18.677 100 71 141 3 1910799488 8531152 20792064
20:21:41 3540 8192000000 10809314016 11226836992 1890819744 18.752 100 40 80 0 1910566704 8563600 11183360
20:22:47 7904 8192000000 10808814432 11236679680 1899070256 18.819 100 38 76 1 1918125552 8687760 10367536
20:23:55 63136 8192000000 10819767664 11253952512 1911837024 18.921 100 42 82 1 1930058768 6319440 11902304
20:25:59 6432 8192000000 10824501232 11249897472 1910781552 18.913 100 33 66 0 1927019968 6218640 10019776
20:27:07 7241 8192000000 10830940416 11258179584 1913868704 18.938 100 38 76 1 1930014032 5742736 10402592
20:28:15 61293 8192000000 10793503136 11230834688 1830670704 18.265 96 27 54 2 1843318784 5180496 7467584
20:30:22 7671 8192000000 10779775680 11203411968 1853786576 18.453 99 40 79 4 1871054448 6052608 11215264
20:31:32 8975 8192000000 10698548704 11117330432 1754070464 17.635 98 28 57 0 1767687744 5678400 7938880
20:32:42 69856 8192000000 10724827216 11149246464 1758498880 17.672 96 28 56 1 1772022096 5712032 7811184
20:34:52 7161 8192000000 10777630048 11199950848 1858615856 18.492 100 32 64 2 1873764064 5941808 9206400
20:36:02 10687 8192000000 10802925760 11223818240 1879867008 18.664 100 33 66 1 1895464496 5068912 10528576
20:37:14 109896 8192000000 10842913344 11268235264 1905456560 18.870 100 31 63 2 1919596160 4777968 9361632
20:40:11 29724 8192000000 10887627552 11305091072 1941950880 19.162 100 59 118 0 1968954752 10780048 16223824
20:41:54 44481 8192000000 10901318752 11342635008 1970883728 19.392 100 37 74 1 1997294032 14753024 11657280
20:43:53 16699 8192000000 10942123136 11368226816 1992549824 19.564 100 58 114 2 2030350672 21456992 16343856
20:45:16 9999 8192000000 10951238240 11381841920 2002156304 19.640 100 53 106 1 2040385536 22399248 15829984
20:46:31 17136 8192000000 10958692624 11397324800 2008365088 19.689 100 46 92 0 2047080944 24596352 14119504
20:47:53 12058 8192000000 10985799152 11413266432 2004871104 19.661 99 62 124 3 2053963504 29947600 19144800
20:49:10 10259 8192000000 10991959904 11430359040 2030433184 19.862 100 60 120 1 2086859680 37250864 19175632
20:50:24 4839 8192000000 11018224640 11453382656 2036342656 19.908 100 67 134 1 2101991216 44569152 21079408
20:51:38 5659 8192000000 11017778816 11450982400 2040834352 19.943 100 65 130 0 2101654320 40010816 20809152
20:52:50 6478 8192000000 11018839248 11453607936 2028161488 19.844 99 72 144 2 2093838032 42239120 23437424
20:54:06 3496 8192000000 11049183584 11482820608 2065220384 20.134 100 69 139 1 2131354720 43742000 22392336
20:55:18 5614 8192000000 11044942240 11483480064 2061472976 20.105 100 73 146 2 2130291072 45828496 22989600
20:56:31 10098 8192000000 10997383824 11451826176 2013567040 19.730 99 47 91 1 2075632800 46253680 15812080
20:57:45 5927 8192000000 10967815456 11412582400 1983113296 19.489 99 47 90 3 2035656512 37859648 14683568
20:59:03 21376 8192000000 10932894144 11386519552 1953908560 19.258 99 32 64 1 2005313904 40370048 11035296
21:00:27 16772 8192000000 10896587664 11352690688 1941974352 19.163 100 29 58 0 1986072064 34294112 9803600
21:02:00 33165 8192000000 10882636720 11344908288 1908005568 18.891 98 17 34 2 1944604304 31079248 5519488
21:03:35 25448 8192000000 10830391408 11263016960 1797779184 17.996 94 25 49 0 1830262416 24686064 7797168
21:05:02 50624 8192000000 10801594592 11256446976 1852279072 18.441 99 8 16 1 1870904272 16224832 2400368
21:07:17 64969 8192000000 10815220016 11275874304 1872203824 18.602 99 8 14 1 1898329312 24347952 1777536
21:09:22 129462 8192000000 10891485072 11339866112 1917325392 18.965 99 25 49 4 1943987088 19525440 7136256
21:12:32 107707 8192000000 10838090544 11296694272 1799861312 18.013 93 12 20 1 1813032112 10741920 2428880
21:15:21 74665 8192000000 10838867520 11285131264 1859661824 18.501 97 4 8 1 1875676496 15282880 731792
21:17:38 46085 8192000000 10872540624 11331760128 1924641664 19.024 99 26 52 1 1943647616 10881008 8124944
21:19:27 90695 8192000000 10889049072 11348152320 1935652384 19.112 99 36 72 4 1956623008 10024864 10945760
21:22:11 65882 8192000000 10896835008 11363893248 1969315424 19.380 100 18 36 0 1985819248 11614144 4889680
21:24:21 86477 8192000000 10923556464 11383832576 1981154896 19.474 100 33 66 0 2001406624 10999008 9252720
21:27:02 172627 8192000000 10917934560 11371012096 1915261264 18.949 96 34 68 0 1933731600 8184672 10285664
21:31:04 29707 8192000000 10927635840 11379945472 1979093072 19.458 100 43 87 2 2002050704 10616368 12341264
21:32:43 30261 8192000000 10913689456 11390226432 1983758256 19.494 100 19 38 2 1998555312 8927648 5869408
21:34:17 41758 8192000000 10896135808 11376750592 1966035200 19.354 99 11 22 0 1976293792 6860384 3398208
21:35:59 77487 8192000000 10899378992 11381428224 1971201360 19.395 99 16 32 1 1986000912 10303920 4495632
21:38:18 8079 8192000000 10947605088 11390967808 1996941232 19.599 100 47 88 4 2016411232 7611376 11858624
21:39:27 51576 8192000000 10917684432 11390865408 1991887152 19.559 100 15 30 1 2003043888 7846368 3310368
21:41:19 44227 8192000000 10905753552 11385847808 1920155024 18.988 96 12 23 1 1935950240 12181440 3613776
21:43:05 29318 8192000000 10922710256 11381141504 1920219440 18.989 96 20 40 1 1940148144 14611584 5317120
21:44:34 58079 8192000000 10909361744 11375575040 1936348912 19.118 98 19 37 0 1955821376 14081648 5390816
21:46:34 43888 8192000000 10870793744 11339169792 1874415072 18.620 95 21 41 2 1896394496 15889440 6089984
21:48:32 26516 8192000000 10862231360 11323432960 1910129696 18.908 99 22 44 0 1929645920 13287200 6229024
21:50:04 33909 8192000000 10889493360 11329765376 1893204288 18.772 98 26 50 3 1914016608 12602448 8209872
21:52:06 64970 8192000000 10851779440 11313090560 1813760320 18.127 93 20 41 0 1836722160 17436576 5525264
21:54:28 113473 8192000000 10828691712 11283165184 1779177024 17.843 93 25 48 2 1799996768 13592720 7227024
21:57:25 7152 8192000000 10860475232 11317694464 1912904272 18.930 100 41 81 2 1939237200 14939344 11393584
21:58:36 46878 8192000000 10826143472 11305873408 1893161456 18.771 99 9 18 1 1908742448 13273200 2307792
22:00:35 56325 8192000000 10842580256 11315650560 1887866736 18.729 98 22 45 0 1905219072 11018848 6333488
22:02:37 54136 8192000000 10854939808 11298738176 1890474176 18.750 98 10 20 1 1901178464 7885024 2819264
22:04:32 91563 8192000000 10846292304 11329908736 1891748432 18.760 98 4 8 0 1910977328 18365344 863552
22:07:04 28696 8192000000 10878030752 11339612160 1928307952 19.053 99 18 34 2 1939040480 6323744 4408784
22:08:34 35160 8192000000 10902179120 11366510592 1968715040 19.375 100 24 48 0 1983821968 8899552 6207376
22:10:09 52466 8192000000 10875505872 11345477632 1912786016 18.929 97 24 49 0 1927926096 8401808 6738272
22:12:35 35900 8192000000 10920773936 11373633536 1948778000 19.217 98 32 64 0 1968271456 10269504 9223952
22:14:11 26769 8192000000 10927081024 11388231680 1970944880 19.393 99 24 48 3 1985702544 8305968 6451696
22:15:40 57665 8192000000 10931735552 11398819840 1976472128 19.437 99 27 54 1 1996938080 13008176 7457776
22:17:45 57739 8192000000 10924670400 11403005952 1994556656 19.580 100 7 14 0 2009709344 13482496 1670192
22:19:43 47755 8192000000 10965097264 11421962240 2008973856 19.693 100 29 57 1 2029965360 12989280 8002224
22:21:36 120345 8192000000 10944683616 11415728128 1972289968 19.404 98 33 66 1 1997800368 16061936 9448464
22:26:23 61647 8192000000 10899347936 11384643584 1943542016 19.175 98 12 24 1 1961257824 14183264 3532544
22:28:26 47583 8192000000 10895935648 11382304768 1955529568 19.270 99 9 18 0 1968563744 9825744 3208432
22:30:17 26246 8192000000 10899479248 11360382976 1919184368 18.980 97 32 65 0 1936210016 8396560 8629088
22:31:43 45935 8192000000 10867371888 11343347712 1873447024 18.612 95 24 48 1 1890382240 10304896 6630320
22:33:38 37618 8192000000 10892517792 11340984320 1907589648 18.887 98 31 62 0 1924335520 7754784 8991088
22:35:20 27041 8192000000 10884547328 11335790592 1904253088 18.860 98 39 78 1 1926025664 11210560 10562016
22:36:49 51856 8192000000 10868491888 11321102336 1883043280 18.690 98 27 55 1 1903276320 12935920 7297120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-08-2021 22:38:44 to 07-08-2021 22:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-08-2021 22:39:00 to 07-08-2021 22:39:02.

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          236915
MON$OLDEST_ACTIVE               241380
MON$OLDEST_SNAPSHOT             241380
MON$NEXT_TRANSACTION            241380
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                       2025568
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 ---
:356: 
:357: mscs-test-161.budzdorov.ru    Sat Aug  7 22:37:42 2021
:358:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:359: 
:360: 
:361: mscs-test-161.budzdorov.ru    Sat Aug  7 22:37:43 2021
:362:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:363: 
:364: 
:365: mscs-test-161.budzdorov.ru    Sat Aug  7 22:37:43 2021
:366:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:367: 
:368: 
:369: mscs-test-161.budzdorov.ru    Sat Aug  7 22:37:48 2021
:370:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:371: 
:372: 
:373: mscs-test-161.budzdorov.ru    Sat Aug  7 22:37:50 2021
:374:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:375: 
--- end of diff output ---

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

07.08.21 22:39:03. 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-07 22:39:05 No severe PSQL-related problems occured
07.08.21 22:39:07. End of report.