Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.2021 07:37
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 15530564 kB
MemAvailable 30690180 kB
Buffers 0 kB
Cached 16142752 kB
SwapCached 3804 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 4.0.1
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-08 07:37:05
DTS_END                         2021-10-08 07:37:05.1140
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_CLIENT_ORDER(32:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58256
TRN_ID                          240491
ATT_ID                          628
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: 9358.80 21155 1123056
customer order: creation 176.21 367 21145
customer order: refuse 14.42 333 1730
order to supplier: creation 45.88 1366 5506
order to supplier: removal 3.51 115047 421
invoice (draft): creation 46.92 3499 5630
invoice (draft): removal 53.31 969 6397
invoice accept: apply 42.90 18906 5148
invoice accept: cancel 48.45 818 5814
customer reserve: creation 3904.45 188 468534
customer reserve: removal 4719.62 66 566354
realization accept: apply 86.38 175 10365
realization accept: cancel 86.35 115 10362
payment from customer: creation 60.68 104 7281
payment from customer: removal 6.14 84 737
payment to supplier: creation 48.82 129 5858
payment to supplier: removal 3.68 106 442
service: total inventory turnovers 3.50 4183 420
service: total monetary turnovers 6.54 529 785
service: aggregate perf. data 1.02 15248 122
service: refresh index statistics 0.04 260861 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2021 07:37:55 to 08-10-2021 07:37:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6381
TEST_TIME 2 4643
TEST_TIME 3 4916
TEST_TIME 4 5003
TEST_TIME 5 5143
TEST_TIME 6 5548
TEST_TIME 7 5735
TEST_TIME 8 5649
TEST_TIME 9 6091
TEST_TIME 10 6298
TEST_TIME 11 7027
TEST_TIME 12 7321
TEST_TIME 13 7977
TEST_TIME 14 8198
TEST_TIME 15 8076
TEST_TIME 16 8190
TEST_TIME 17 8782
TEST_TIME 18 8729
TEST_TIME 19 9152
TEST_TIME 20 9081
TEST_TIME 21 9064
TEST_TIME 22 9575
TEST_TIME 23 9627
TEST_TIME 24 9729
TEST_TIME 25 9676
TEST_TIME 26 9947
TEST_TIME 27 9866
TEST_TIME 28 10091
TEST_TIME 29 11282
TEST_TIME 30 11778
TEST_TIME 31 11939
TEST_TIME 32 12150
TEST_TIME 33 12089
TEST_TIME 34 11845
TEST_TIME 35 11599
TEST_TIME 36 11537
TEST_TIME 37 11358
TEST_TIME 38 11231
TEST_TIME 39 11207
TEST_TIME 40 11098
TEST_TIME 41 11006
TEST_TIME 42 10888
TEST_TIME 43 10915
TEST_TIME 44 10914
TEST_TIME 45 10888
TEST_TIME 46 10912
TEST_TIME 47 10828
TEST_TIME 48 10635
TEST_TIME 49 10582
TEST_TIME 50 10518
TEST_TIME 51 10419
TEST_TIME 52 10298
TEST_TIME 53 10219
TEST_TIME 54 10229
TEST_TIME 55 10252
TEST_TIME 56 10252
TEST_TIME 57 10320
TEST_TIME 58 10292
TEST_TIME 59 10247
TEST_TIME 60 10331
TEST_TIME 61 10337
TEST_TIME 62 10297
TEST_TIME 63 10366
TEST_TIME 64 10304
TEST_TIME 65 10390
TEST_TIME 66 10290
TEST_TIME 67 10164
TEST_TIME 68 10190
TEST_TIME 69 10116
TEST_TIME 70 10008
TEST_TIME 71 9915
TEST_TIME 72 9890
TEST_TIME 73 9805
TEST_TIME 74 9849
TEST_TIME 75 9843
TEST_TIME 76 9856
TEST_TIME 77 9792
TEST_TIME 78 9743
TEST_TIME 79 9811
TEST_TIME 80 9837
TEST_TIME 81 9843
TEST_TIME 82 9850
TEST_TIME 83 9890
TEST_TIME 84 9903
TEST_TIME 85 9808
TEST_TIME 86 9721
TEST_TIME 87 9754
TEST_TIME 88 9670
TEST_TIME 89 9675
TEST_TIME 90 9612
TEST_TIME 91 9606
TEST_TIME 92 9559
TEST_TIME 93 9528
TEST_TIME 94 9501
TEST_TIME 95 9479
TEST_TIME 96 9576
TEST_TIME 97 9574
TEST_TIME 98 9568
TEST_TIME 99 9592
TEST_TIME 100 9592
TEST_TIME 101 9576
TEST_TIME 102 9602
TEST_TIME 103 9615
TEST_TIME 104 9626
TEST_TIME 105 9578
TEST_TIME 106 9512
TEST_TIME 107 9513
TEST_TIME 108 9518
TEST_TIME 109 9489
TEST_TIME 110 9451
TEST_TIME 111 9408
TEST_TIME 112 9364
TEST_TIME 113 9341
TEST_TIME 114 9347
TEST_TIME 115 9389
TEST_TIME 116 9365
TEST_TIME 117 9367
TEST_TIME 118 9368
TEST_TIME 119 9435
TEST_TIME 120 9363

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-10-2021 07:37:58 to 08-10-2021 07:38:02.

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 1557555 1557555 0 0.00 0 25656 7 0 0
doc_list_biud 1557554 1557554 0 0.00 0 66704 31 0 0
sp_add_invoice_to_stock 5277 5148 129 2.44 24 198336 18906 0 129
sp_cancel_adding_invoice 5814 5814 0 0.00 5 57584 818 0 0
sp_cancel_client_order 1730 1730 0 0.00 5 9376 334 0 0
sp_cancel_customer_reserve 566354 566354 0 0.00 1 29689 67 0 0
sp_cancel_pay_from_customer 737 737 0 0.00 3 8976 85 0 0
sp_cancel_pay_to_supplier 442 442 0 0.00 3 10382 107 0 0
sp_cancel_supplier_invoice 6405 6397 8 0.12 2 66721 969 0 8
sp_cancel_supplier_order 421 421 0 0.00 9 1235257 115048 0 0
sp_cancel_write_off 10362 10362 0 0.00 1 15947 116 0 0
sp_client_order 21145 21145 0 0.00 4 20287 368 0 0
sp_customer_reserve 468534 468534 0 0.00 0 58570 188 0 0
sp_fill_shopping_cart 32271 32271 0 0.00 1 15959 40 0 0
sp_fill_shopping_cart_clo_res 468523 468523 0 0.00 0 21895 17 0 0
sp_get_clo_for_invoice 5137 5137 0 0.00 0 27555 361 0 0
sp_kill_cost_storno 17355 17355 0 0.00 0 9722 56 0 0
sp_kill_qstorno_ret_qs2qd 1149888 1149888 0 0.00 0 66700 36 0 0
sp_kill_qty_storno 606580 606580 0 0.00 0 66703 79 0 0
sp_lock_selected_doc 21358 21358 0 0.00 0 9643 6 0 0
sp_make_cost_storno 28641 28641 0 0.00 0 25601 51 0 0
sp_multiply_rows_for_pdistr 28641 28641 0 0.00 0 8084 32 0 0
sp_multiply_rows_for_qdistr 21145 21145 0 0.00 0 18151 187 0 0
sp_pay_from_customer 7281 7281 0 0.00 4 10530 104 0 0
sp_pay_to_supplier 5858 5858 0 0.00 5 12591 129 0 0
sp_payment_common 13139 13139 0 0.00 1 10203 24 0 0
sp_qd_handle_on_cancel_clo 1730 1730 0 0.00 0 9370 307 0 0
sp_qd_handle_on_invoice_upd_sts 10951 10951 0 0.00 0 58242 418 0 0
sp_qd_handle_on_reserve_upd_sts 10365 10365 0 0.00 0 16434 56 0 0
sp_reserve_write_off 10365 10365 0 0.00 7 17002 175 0 0
sp_supplier_invoice 5630 5630 0 0.00 7 92374 3500 0 0
sp_supplier_order 5506 5506 0 0.00 6 50991 1366 0 0
srv_aggregate_perf_data 122 122 0 0.00 2918 186502 15248 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1227 137919 41367 0 0
srv_find_qd_qs_mism 1074916 1074916 0 0.00 0 12078 6 0 0
srv_make_invnt_saldo 420 420 0 0.00 64 157237 4183 0 0
srv_make_money_saldo 785 785 0 0.00 1 14584 530 0 0
srv_recalc_idx_stat 5 5 0 0.00 155250 415597 260862 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5148 5148 0 0.00 0 17452 184 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 2853 66 0 0
t$perf-norm:sp_cancel_client_order 1730 1730 0 0.00 0 3474 15 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 2939 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 737 737 0 0.00 0 3389 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 442 442 0 0.00 0 14353 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2493 21 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 37136 1701 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 1236 4 0 0
t$perf-norm:sp_client_order 21145 21145 0 0.00 0 11270 19 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 5743 17 0 0
t$perf-norm:sp_pay_from_customer 7281 7281 0 0.00 0 6868 8 0 0
t$perf-norm:sp_pay_to_supplier 5858 5858 0 0.00 0 8210 10 0 0
t$perf-norm:sp_reserve_write_off 10365 10365 0 0.00 0 8520 16 0 0
t$perf-norm:sp_supplier_invoice 5630 5630 0 0.00 0 7139 17 0 0
t$perf-norm:sp_supplier_order 5506 5506 0 0.00 0 9412 18 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 186 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 764 14 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 5031 16 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 843 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 245 60 0 0
v_add_invoice_to_stock 10532 10274 258 2.44 0 4959 15 0 258
v_all_customers 38164 38164 0 0.00 0 34 1 0 0
v_all_suppliers 30680 30680 0 0.00 0 18 1 0 0
v_all_wares 42290 42290 0 0.00 0 106 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 1041 9 0 0
v_cancel_client_order 3460 3460 0 0.00 0 1593 4 0 0
v_cancel_customer_prepayment 1474 1474 0 0.00 0 5657 8 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 1566 5 0 0
v_cancel_payment_to_supplier 884 884 0 0.00 0 653 3 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 336 12 0 16
v_cancel_supplier_order 842 842 0 0.00 0 1688 7 0 0
v_cancel_write_off 2008 2008 0 0.00 0 883 13 0 0
v_max_id_clo_ord 5501 5501 0 0.00 0 1955 2 0 0
v_max_id_clo_res 971 971 0 0.00 0 1275 8 0 0
v_max_id_ord_sup 5624 5624 0 0.00 0 4140 4 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 3800 5 0 0
v_max_non_paid_realizn 3994 3994 0 0.00 0 5418 6 0 0
v_min_id_clo_ord 5501 5501 0 0.00 0 2486 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 2153 6 0 0
v_min_id_ord_sup 5624 5624 0 0.00 0 10515 10 0 0
v_min_non_paid_invoice 5858 5858 0 0.00 0 9775 13 0 0
v_min_non_paid_realizn 7281 7281 0 0.00 0 10196 17 0 0
v_our_firm 10700 10700 0 0.00 0 15 1 0 0
v_reserve_write_off 20728 20728 0 0.00 0 2696 5 0 0
x_lock_depdocs_on_canc_invoice 11801 11801 0 0.00 0 14782 118 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 14958 398 0 0
x_make_qstorno_1000_1200 5506 5506 0 0.00 2 50917 1288 0 0
x_make_qstorno_1200_2000 5630 5630 0 0.00 3 92331 3353 0 0
x_make_qstorno_2100_3300 468534 468534 0 0.00 0 58569 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2021 07:38:03 to 08-10-2021 07:38:07.

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
05:07:08 4 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:10 15018 8192000000 9728779216 10029801472 804720432 8.944 100 85 170 0 832296624 7095824 20480368
05:09:30 19238 8192000000 9763030992 10070769664 838355184 9.283 100 93 186 0 872718688 12258944 22104560
05:10:54 18424 8192000000 9803987168 10120871936 866598320 9.566 100 87 174 1 906444368 20114688 19731360
05:12:37 13057 8192000000 9883707440 10215845888 917799376 10.074 100 82 165 0 990601104 54546720 18255008
05:13:54 7818 8192000000 9933005248 10276233216 960453696 10.493 100 77 151 1 1038649648 62926528 15269424
05:15:05 7101 8192000000 9957420560 10312265728 998504000 10.864 100 77 155 0 1072475008 58533648 15437360
05:16:14 1929 8192000000 9996128016 10359599104 1023341536 11.104 100 86 172 0 1117295152 76841104 17112512
05:17:17 1258 8192000000 10023106000 10391515136 1052251680 11.382 100 95 191 0 1134600096 62406224 19942192
05:18:19 24445 8192000000 9984635344 10374258688 1063246912 11.488 100 68 136 1 1106453088 28819584 14386592
05:19:50 5922 8192000000 9989375312 10376159232 1066462976 11.518 100 29 58 0 1089852016 16951472 6437568
05:20:57 6798 8192000000 9998368304 10392477696 1081356656 11.660 100 36 71 1 1110517216 21749696 7410864
05:22:05 7489 8192000000 10005796752 10407223296 1095319392 11.793 100 38 75 2 1135972336 32796960 7855984
05:23:14 41567 8192000000 10014805664 10408701952 1098155456 11.820 100 59 118 1 1125787344 14129728 13502160
05:24:56 4756 8192000000 10020969136 10423029760 1105684816 11.892 100 47 92 2 1128596128 13107264 9804048
05:26:04 15496 8192000000 10007133536 10421342208 1088024976 11.724 98 21 42 2 1113114656 20675216 4414464
05:27:21 7830 8192000000 9989985200 10387886080 1046507984 11.327 96 27 54 0 1069537856 17093856 5936016
05:28:32 45522 8192000000 9947348096 10332221440 955102688 10.441 88 33 65 0 976573440 14489072 6981680
05:30:20 9310 8192000000 9932878000 10307801088 938689936 10.280 92 39 76 2 972879616 26041104 8148576
05:31:31 4677 8192000000 9936068496 10321068032 1020428528 11.076 99 46 91 2 1045891760 15684752 9778480
05:32:36 7027 8192000000 9951761312 10341208064 1031188496 11.180 99 50 98 1 1070194592 27369472 11636624
05:33:44 31145 8192000000 9963535776 10361692160 1027218416 11.142 98 22 42 2 1059746528 27576608 4951504
05:35:16 5732 8192000000 9978406448 10375417856 1060025248 11.457 100 52 102 2 1088942432 17234224 11682960
05:36:26 9091 8192000000 9994501968 10399162368 1070509184 11.557 100 52 102 4 1099567584 16977056 12081344
05:37:51 23946 8192000000 10026884176 10437287936 1093711280 11.778 100 43 86 0 1135204592 32367248 9126064
05:39:32 44679 8192000000 10057150704 10463469568 1112268016 11.954 100 41 81 2 1161270192 40449456 8552720
05:41:23 26071 8192000000 10092485200 10502623232 1127521232 12.098 100 53 104 3 1197011824 58941104 10549488
05:42:53 5391 8192000000 10129124560 10533875712 1138675104 12.203 100 56 113 0 1235387280 83985968 12726208
05:44:07 16795 8192000000 10158432432 10570817536 1148760912 12.298 100 58 114 1 1255415440 94851056 11803472
05:45:28 5710 8192000000 10196765856 10600378368 1161120176 12.414 100 61 122 1 1305662128 131637488 12904464
05:46:38 5286 8192000000 10191900480 10605449216 1164386896 12.444 100 73 146 1 1307062080 126179616 16495568
05:47:45 3518 8192000000 10285381328 10701316096 1174388592 12.538 100 64 128 0 1382934672 193589584 14956496
05:48:54 2557 8192000000 10284059168 10685804544 1175166512 12.545 100 86 171 0 1381304208 186385296 19752400
05:50:01 1227 8192000000 10290072992 10711220224 1181942752 12.608 100 85 167 1 1387537008 186019296 19574960
05:51:06 8348 8192000000 10291159664 10716168192 1150361568 12.313 98 47 94 0 1379225328 218227440 10636320
05:52:27 5958 8192000000 10266686128 10676584448 1134853728 12.167 99 67 132 1 1360296736 210534224 14908784
05:53:41 2325 8192000000 10309965952 10721931264 1132867248 12.148 99 83 162 3 1411292880 260116992 18308640
05:54:45 12590 8192000000 10253216592 10660872192 1115335456 11.983 99 38 75 0 1336757024 212043360 9378208
05:56:04 10620 8192000000 10314378832 10728226816 1125943408 12.083 99 45 90 0 1401989344 264627856 11418080
05:57:16 11029 8192000000 10305973552 10738884608 1135838176 12.176 99 37 72 0 1408029792 263047712 9143904
05:58:35 7797 8192000000 10380862016 10796269568 1164991760 12.450 100 62 124 1 1499315312 319735712 14587840
05:59:46 14014 8192000000 10356607904 10829729792 1141995072 12.234 99 47 94 0 1488633104 334496624 12141408
06:01:03 8161 8192000000 10366095280 10797309952 1139734400 12.213 99 30 60 0 1464090480 316408336 7947744
06:02:23 9955 8192000000 10418127104 10849505280 1149253024 12.302 100 41 82 0 1524588208 364760608 10574576
06:03:34 11147 8192000000 10443099296 10888228864 1152917360 12.337 100 29 58 2 1541861824 380741744 8202720
06:05:08 17124 8192000000 10510688496 10959106048 1128868736 12.111 99 25 50 1 1598712528 462347472 7496320
06:06:45 30949 8192000000 10105891520 10560647168 1100810528 11.845 98 23 44 0 1256133088 150113664 5208896
06:08:19 40450 8192000000 10127013008 10584272896 1098871392 11.827 98 13 26 1 1183097424 81470080 2755952
06:10:02 25377 8192000000 10050662512 10504081408 1115213296 11.982 100 30 59 1 1145014464 22622528 7178640
06:11:34 90318 8192000000 10122954528 10550427648 1135481216 12.173 99 29 57 1 1192015216 50542064 5991936
06:14:08 117654 8192000000 10098799328 10566627328 1144250640 12.255 100 38 74 1 1208050272 55954112 7845520
06:17:12 6341 8192000000 10099669040 10529259520 1160124944 12.404 100 52 104 1 1194981232 23724384 11131904
06:18:24 20302 8192000000 10142278304 10583027712 1163891744 12.440 100 38 75 2 1225553936 53442528 8219664
06:19:45 37904 8192000000 10185177216 10650750976 1168573728 12.483 100 32 63 0 1299416976 124182288 6660960
06:21:25 33328 8192000000 10128517712 10582818816 1171098608 12.507 100 14 28 0 1241398320 66953328 3346384
06:23:04 28675 8192000000 10101754112 10537328640 1173616000 12.531 100 28 56 1 1228644448 49930016 5098432
06:24:34 47371 8192000000 10102761840 10581778432 1159859088 12.402 99 11 24 0 1198436464 36294272 2283104
06:26:22 54474 8192000000 10094073008 10602397696 1175419296 12.547 100 14 28 0 1239236384 60866160 2950928
06:28:23 54479 8192000000 10127476016 10588053504 1174919520 12.543 100 12 24 1 1214416096 36824176 2672400
06:30:21 56243 8192000000 10124306144 10609152000 1150003296 12.310 98 24 48 1 1241914000 85850640 6060064
06:32:19 32933 8192000000 10125374528 10582044672 1169085776 12.488 100 15 30 0 1216373056 44098816 3188464
06:34:13 56623 8192000000 10069398336 10530078720 1096398528 11.803 94 20 39 1 1139246544 38782208 4065808
06:36:13 33734 8192000000 10078743008 10516783104 1078361648 11.632 94 22 44 0 1135748704 52992720 4394336
06:37:49 54188 8192000000 10050859888 10524622848 1059990864 11.456 94 17 34 2 1107837728 44538096 3308768
06:39:44 58957 8192000000 10050884496 10516258816 1091966032 11.761 98 31 62 0 1167971456 69865056 6140368
06:41:45 23667 8192000000 10106536592 10545995776 1112252048 11.954 99 33 66 0 1183571648 65191344 6128256
06:43:12 126679 8192000000 10098740576 10542735360 1110009520 11.933 98 32 64 0 1178191056 61085088 7096448
06:46:20 33925 8192000000 10045384144 10506940416 1107053888 11.905 99 11 22 0 1136406960 26888656 2464416
06:47:56 64104 8192000000 10066305376 10525802496 1101190336 11.849 98 13 26 0 1162858480 58824784 2843360
06:50:05 65050 8192000000 10105104544 10547982336 1094214656 11.783 97 21 42 1 1166554304 67295968 5043680
06:52:43 57749 8192000000 10055372688 10496688128 1094973792 11.790 98 20 40 0 1131456624 32267344 4215488
06:54:49 52503 8192000000 10126689904 10573377536 1110762304 11.940 98 35 70 1 1204550560 86248112 7540144
06:56:42 23137 8192000000 10089758000 10538192896 1124527376 12.070 100 39 79 0 1217763888 84792416 8444096
06:58:09 27775 8192000000 10107381952 10543517696 1096061440 11.800 97 58 116 0 1148648432 40852480 11734512
06:59:39 55365 8192000000 10100476160 10551304192 1099233568 11.830 98 33 66 0 1145785520 40151856 6400096
07:01:36 60612 8192000000 10118756048 10620424192 1124192272 12.067 100 19 38 0 1249600160 121306032 4101856
07:04:06 56873 8192000000 10065828496 10538827776 1128170448 12.104 100 13 26 2 1186280736 55150448 2959840
07:06:06 94100 8192000000 10045687984 10522140672 1114198864 11.972 99 6 12 0 1159866512 44549488 1118160
07:08:41 58851 8192000000 10077640320 10534535168 1135692224 12.175 100 11 22 0 1185184720 46669440 2823056
07:10:40 56732 8192000000 10133114432 10582523904 1150227344 12.312 100 18 36 0 1221880064 67607792 4044928
07:12:50 105513 8192000000 10065347536 10546245632 1143795248 12.251 99 20 39 1 1233238368 85281520 4161600
07:15:39 63443 8192000000 10088296288 10534363136 1134638736 12.165 99 31 63 1 1215350576 74142992 6568848
07:17:45 137919 8192000000 10105940368 10574557184 1053936080 11.398 92 15 30 0 1098062240 41467264 2658896
07:21:34 94131 8192000000 10093551968 10555588608 1122102240 12.047 98 20 40 1 1162980384 37162528 3715616
07:24:53 52165 8192000000 10075609952 10601594880 1112718944 11.958 98 17 34 0 1179441248 63669568 3052736
07:26:45 33648 8192000000 10040093584 10486689792 1100762512 11.845 97 11 22 0 1134499808 31784752 1952544
07:28:29 67766 8192000000 10105348832 10579079168 1133691248 12.156 100 12 24 0 1210942160 74387680 2863232
07:30:41 67141 8192000000 10096654912 10578518016 1108439808 11.918 98 14 28 0 1159618720 48338912 2840000
07:33:03 38847 8192000000 10079162000 10521247744 1107958448 11.913 98 27 54 1 1157247728 43602960 5686320
07:34:42 77918 8192000000 10088569680 10572292096 1110038960 11.933 99 34 68 0 1199473840 82389872 7045008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-10-2021 07:38:24 to 08-10-2021 07:38:26.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          240278
MON$OLDEST_ACTIVE               240623
MON$OLDEST_SNAPSHOT             240623
MON$NEXT_TRANSACTION            240623
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-30 07:30:08.6030 GMT
MON$PAGES                       2083936
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2624 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2624 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2624 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2624 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:33: 
:34: mscs-test-161.budzdorov.ru    Fri Oct  8 07:37:07 2021
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Fri Oct  8 07:37:10 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

08.10.21 07:38:27. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-08 07:38:30 No severe PSQL-related problems occured
08.10.21 07:38:31. End of report.