Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.08.2021 19:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16322632 kB
MemAvailable 31004512 kB
Buffers 0 kB
Cached 15388368 kB
SwapCached 2604 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-19 19:37:50
DTS_END                         2021-08-19 19:37:50.4070
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/39634
TRN_ID                          238209
ATT_ID                          663
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: 9188.35 18330 1102602
customer order: creation 171.52 335 20582
customer order: refuse 14.83 342 1780
order to supplier: creation 45.63 1282 5476
order to supplier: removal 3.31 106480 397
invoice (draft): creation 46.00 3091 5520
invoice (draft): removal 52.18 811 6261
invoice accept: apply 41.56 17850 4987
invoice accept: cancel 47.11 765 5653
customer reserve: creation 3861.50 177 463380
customer reserve: removal 4603.52 59 552423
realization accept: apply 82.99 150 9959
realization accept: cancel 88.47 108 10616
payment from customer: creation 58.07 100 6968
payment from customer: removal 5.74 97 689
payment to supplier: creation 50.80 103 6096
payment to supplier: removal 3.42 58 410
service: total inventory turnovers 4.69 945 563
service: total monetary turnovers 6.21 29 745
service: aggregate perf. data 0.78 22620 93
service: refresh index statistics 0.03 211198 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-08-2021 19:40:19 to 19-08-2021 19:40:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1466
TEST_TIME 2 3097
TEST_TIME 3 3389
TEST_TIME 4 3861
TEST_TIME 5 4420
TEST_TIME 6 4561
TEST_TIME 7 4381
TEST_TIME 8 5276
TEST_TIME 9 5596
TEST_TIME 10 5691
TEST_TIME 11 5901
TEST_TIME 12 6035
TEST_TIME 13 6305
TEST_TIME 14 6338
TEST_TIME 15 6275
TEST_TIME 16 6418
TEST_TIME 17 6552
TEST_TIME 18 6589
TEST_TIME 19 6659
TEST_TIME 20 7255
TEST_TIME 21 7768
TEST_TIME 22 7919
TEST_TIME 23 8314
TEST_TIME 24 8612
TEST_TIME 25 8968
TEST_TIME 26 9632
TEST_TIME 27 10389
TEST_TIME 28 10630
TEST_TIME 29 10624
TEST_TIME 30 10333
TEST_TIME 31 10359
TEST_TIME 32 10351
TEST_TIME 33 10326
TEST_TIME 34 10435
TEST_TIME 35 10494
TEST_TIME 36 10279
TEST_TIME 37 10031
TEST_TIME 38 9768
TEST_TIME 39 9776
TEST_TIME 40 9687
TEST_TIME 41 9694
TEST_TIME 42 9840
TEST_TIME 43 9743
TEST_TIME 44 9609
TEST_TIME 45 9527
TEST_TIME 46 9606
TEST_TIME 47 9582
TEST_TIME 48 9499
TEST_TIME 49 9402
TEST_TIME 50 9360
TEST_TIME 51 9276
TEST_TIME 52 9118
TEST_TIME 53 9302
TEST_TIME 54 9259
TEST_TIME 55 9201
TEST_TIME 56 9203
TEST_TIME 57 9185
TEST_TIME 58 9323
TEST_TIME 59 9418
TEST_TIME 60 9516
TEST_TIME 61 9462
TEST_TIME 62 9609
TEST_TIME 63 9624
TEST_TIME 64 9589
TEST_TIME 65 9708
TEST_TIME 66 9709
TEST_TIME 67 9721
TEST_TIME 68 9718
TEST_TIME 69 9600
TEST_TIME 70 9536
TEST_TIME 71 9502
TEST_TIME 72 9431
TEST_TIME 73 9467
TEST_TIME 74 9423
TEST_TIME 75 9381
TEST_TIME 76 9388
TEST_TIME 77 9339
TEST_TIME 78 9313
TEST_TIME 79 9361
TEST_TIME 80 9341
TEST_TIME 81 9316
TEST_TIME 82 9395
TEST_TIME 83 9367
TEST_TIME 84 9382
TEST_TIME 85 9400
TEST_TIME 86 9464
TEST_TIME 87 9446
TEST_TIME 88 9479
TEST_TIME 89 9493
TEST_TIME 90 9470
TEST_TIME 91 9528
TEST_TIME 92 9455
TEST_TIME 93 9397
TEST_TIME 94 9397
TEST_TIME 95 9347
TEST_TIME 96 9319
TEST_TIME 97 9277
TEST_TIME 98 9280
TEST_TIME 99 9259
TEST_TIME 100 9256
TEST_TIME 101 9210
TEST_TIME 102 9177
TEST_TIME 103 9155
TEST_TIME 104 9174
TEST_TIME 105 9133
TEST_TIME 106 9181
TEST_TIME 107 9224
TEST_TIME 108 9228
TEST_TIME 109 9261
TEST_TIME 110 9224
TEST_TIME 111 9245
TEST_TIME 112 9305
TEST_TIME 113 9278
TEST_TIME 114 9345
TEST_TIME 115 9324
TEST_TIME 116 9311
TEST_TIME 117 9284
TEST_TIME 118 9240
TEST_TIME 119 9223
TEST_TIME 120 9194

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-08-2021 19:40:23 to 19-08-2021 19:40:26.

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 1532497 1532497 0 0.00 0 19706 6 0 0
doc_list_biud 1532493 1532493 0 0.00 0 59053 27 0 0
sp_add_invoice_to_stock 5054 4987 67 1.32 36 170794 17850 0 67
sp_cancel_adding_invoice 5653 5653 0 0.00 5 71872 765 0 0
sp_cancel_client_order 1780 1780 0 0.00 4 7914 342 0 0
sp_cancel_customer_reserve 552423 552423 0 0.00 1 59057 60 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 3 10383 98 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 3824 59 0 0
sp_cancel_supplier_invoice 6272 6261 11 0.17 2 19318 811 0 11
sp_cancel_supplier_order 397 397 0 0.00 6 1158129 106481 0 0
sp_cancel_write_off 10616 10616 0 0.00 1 10750 109 0 0
sp_client_order 20582 20582 0 0.00 4 27994 336 0 0
sp_customer_reserve 463380 463380 0 0.00 0 128056 177 0 0
sp_fill_shopping_cart 31568 31568 0 0.00 1 11590 38 0 0
sp_fill_shopping_cart_clo_res 463372 463372 0 0.00 0 21132 13 0 0
sp_get_clo_for_invoice 4977 4977 0 0.00 0 29019 270 0 0
sp_kill_cost_storno 17368 17368 0 0.00 0 9542 52 0 0
sp_kill_qstorno_ret_qs2qd 1122120 1122120 0 0.00 0 59037 33 0 0
sp_kill_qty_storno 592065 592065 0 0.00 0 59052 70 0 0
sp_lock_selected_doc 20699 20699 0 0.00 0 5874 6 0 0
sp_make_cost_storno 27999 27999 0 0.00 0 19704 45 0 0
sp_multiply_rows_for_pdistr 27998 27998 0 0.00 0 5951 24 0 0
sp_multiply_rows_for_qdistr 20582 20582 0 0.00 0 18352 130 0 0
sp_pay_from_customer 6968 6968 0 0.00 4 15222 100 0 0
sp_pay_to_supplier 6096 6096 0 0.00 4 19710 104 0 0
sp_payment_common 13063 13063 0 0.00 1 15190 27 0 0
sp_qd_handle_on_cancel_clo 1780 1780 0 0.00 0 7906 315 0 0
sp_qd_handle_on_invoice_upd_sts 10629 10629 0 0.00 0 58958 320 0 0
sp_qd_handle_on_reserve_upd_sts 9959 9959 0 0.00 0 7669 50 0 0
sp_reserve_write_off 9959 9959 0 0.00 6 14602 150 0 0
sp_supplier_invoice 5520 5520 0 0.00 7 81655 3092 0 0
sp_supplier_order 5476 5476 0 0.00 5 28361 1283 0 0
srv_aggregate_perf_data 93 93 0 0.00 2746 287295 22620 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2539 160246 47657 0 0
srv_find_qd_qs_mism 1055204 1055204 0 0.00 0 16087 5 0 0
srv_make_invnt_saldo 563 563 0 0.00 30 30601 946 0 0
srv_make_money_saldo 745 745 0 0.00 1 5814 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 120579 401643 211198 0 0
t$perf-abend:sp_add_invoice_to_stock 67 67 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4988 4988 0 0.00 0 19676 174 0 0
t$perf-norm:sp_cancel_adding_invoice 394 394 0 0.00 0 9367 93 0 0
t$perf-norm:sp_cancel_client_order 1780 1780 0 0.00 0 4314 11 0 0
t$perf-norm:sp_cancel_customer_reserve 648 648 0 0.00 0 4283 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 13012 41 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 486 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 4503 35 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 19603 1689 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 2364 10 0 0
t$perf-norm:sp_client_order 20582 20582 0 0.00 0 24279 19 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 5417 12 0 0
t$perf-norm:sp_pay_from_customer 6968 6968 0 0.00 0 12708 19 0 0
t$perf-norm:sp_pay_to_supplier 6096 6096 0 0.00 0 12708 12 0 0
t$perf-norm:sp_reserve_write_off 9959 9959 0 0.00 0 22144 13 0 0
t$perf-norm:sp_supplier_invoice 5520 5520 0 0.00 0 9673 21 0 0
t$perf-norm:sp_supplier_order 5476 5476 0 0.00 0 3009 15 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 225 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 183 3 0 0
t$perf-norm:srv_make_invnt_saldo 563 563 0 0.00 0 4697 36 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 1045 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 71 22 0 0
v_add_invoice_to_stock 10084 9950 134 1.32 0 7277 16 0 134
v_all_customers 37222 37222 0 0.00 0 145 1 0 0
v_all_suppliers 30938 30938 0 0.00 0 24 1 0 0
v_all_wares 41164 41164 0 0.00 0 26 1 0 0
v_cancel_adding_invoice 788 788 0 0.00 0 3379 14 0 0
v_cancel_client_order 3560 3560 0 0.00 0 4078 7 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 6741 20 0 0
v_cancel_customer_reserve 1296 1296 0 0.00 0 2344 8 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 815 5 0 0
v_cancel_supplier_invoice 834 812 22 2.63 0 1028 13 0 22
v_cancel_supplier_order 794 794 0 0.00 0 1170 6 0 0
v_cancel_write_off 2082 2082 0 0.00 0 4545 19 0 0
v_max_id_clo_ord 5474 5474 0 0.00 0 2578 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 4734 20 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 6234 7 0 0
v_max_non_paid_invoice 1612 1612 0 0.00 0 620 2 0 0
v_max_non_paid_realizn 3713 3713 0 0.00 0 3696 5 0 0
v_min_id_clo_ord 5474 5474 0 0.00 0 3043 6 0 0
v_min_id_clo_res 998 998 0 0.00 0 1775 4 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 6175 5 0 0
v_min_non_paid_invoice 6095 6095 0 0.00 0 11100 14 0 0
v_min_non_paid_realizn 6968 6968 0 0.00 0 15177 20 0 0
v_our_firm 10452 10452 0 0.00 0 21 1 0 0
v_reserve_write_off 19918 19918 0 0.00 0 3412 5 0 0
x_lock_depdocs_on_canc_invoice 11508 11508 0 0.00 0 17187 120 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 11923 384 0 0
x_make_qstorno_1000_1200 5476 5476 0 0.00 2 28354 1203 0 0
x_make_qstorno_1200_2000 5520 5520 0 0.00 3 81642 2952 0 0
x_make_qstorno_2100_3300 463380 463380 0 0.00 0 128055 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-08-2021 19:40:27 to 19-08-2021 19:40:31.

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
17:07:53 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:54 19551 8192000000 10374683408 10689753088 1423698000 14.805 100 89 178 0 1459290992 5891696 29701296
17:10:21 17568 8192000000 10445984336 10773225472 1498878016 15.466 100 89 178 0 1534846128 7541312 28426800
17:11:45 18627 8192000000 10481831808 10821181440 1536293984 15.792 100 87 172 2 1572148896 9283232 26571680
17:13:10 11694 8192000000 10561477168 10920665088 1624285088 16.546 100 66 132 1 1654108784 10733456 19090240
17:14:24 9710 8192000000 10624153712 10998980608 1693138096 17.128 100 73 146 1 1726188272 11814320 21235856
17:15:41 6161 8192000000 10700616272 11082866688 1768070688 17.751 100 82 165 1 1803960528 12713264 23176576
17:16:49 8746 8192000000 10743138736 11127025664 1807510400 18.075 100 80 158 0 1843473344 13974240 21988704
17:18:00 2218 8192000000 10789620272 11174305792 1845661952 18.387 100 86 173 1 1882409424 12613328 24134144
17:19:04 1621 8192000000 10821712576 11205697536 1871035936 18.593 100 91 182 3 1909340464 13432480 24872048
17:20:06 2634 8192000000 10819607536 11216228352 1878697840 18.655 100 71 142 2 1908625664 9197504 20730320
17:21:14 5123 8192000000 10798060032 11214827520 1883836992 18.696 100 36 73 0 1901581824 7755600 9989232
17:22:21 7586 8192000000 10812594128 11231494144 1895329024 18.789 100 40 81 3 1914252640 8030192 10893424
17:23:30 4826 8192000000 10816321808 11243692032 1900390800 18.829 100 33 66 0 1917216928 6855840 9970288
17:24:37 61434 8192000000 10837036496 11259674624 1910772800 18.913 100 35 70 1 1930647600 9495616 10379184
17:26:52 5163 8192000000 10839548544 11260710912 1899020496 18.818 99 41 83 1 1918385232 7343984 12020752
17:27:58 9948 8192000000 10813070992 11242819584 1887903344 18.729 99 26 52 0 1902834992 6461392 8470256
17:29:08 10030 8192000000 10734620752 11155238912 1750430592 17.605 95 21 41 2 1764770272 8945552 5394128
17:30:21 20819 8192000000 10753483968 11164266496 1783844672 17.881 97 51 99 1 1805537520 6847520 14845328
17:31:43 6744 8192000000 10707633168 11122475008 1772169680 17.785 98 17 35 1 1782677216 5458240 5049296
17:32:52 10169 8192000000 10715644720 11142266880 1779114464 17.842 98 13 26 3 1789147600 5278256 4754880
17:34:03 17927 8192000000 10764554864 11192262656 1848218208 18.408 100 19 36 1 1859683616 5903600 5561808
17:35:22 26872 8192000000 10763543696 11194810368 1854806784 18.461 100 35 70 0 1872024784 6347920 10870080
17:36:49 13474 8192000000 10797240400 11227738112 1885242560 18.707 100 30 61 1 1899640800 5637936 8760304
17:38:07 37790 8192000000 10839293104 11268734976 1917002240 18.963 100 40 80 1 1938154688 9923920 11228528
17:39:48 14107 8192000000 10869549568 11297730560 1941000240 19.155 100 50 98 2 1964487312 8957712 14529360
17:41:12 12167 8192000000 10889004432 11321827328 1959040000 19.298 100 40 80 1 1983755584 12529344 12186240
17:42:35 22479 8192000000 10895685328 11338850304 1971030000 19.394 100 32 64 1 1991577712 10177504 10370208
17:44:01 35980 8192000000 10934280320 11366866944 1996441312 19.595 100 52 103 1 2026309392 15609824 14258256
17:45:49 8500 8192000000 10958185200 11393245184 1996064912 19.592 99 58 114 1 2033504048 20730656 16708480
17:47:02 12893 8192000000 10966062608 11403051008 2009399104 19.697 100 52 104 1 2052115088 26747776 15968208
17:48:20 9193 8192000000 10949800336 11394605056 2003050016 19.647 100 45 90 0 2041670624 24136448 14484160
17:49:33 6136 8192000000 10976877376 11419078656 2016724096 19.754 100 60 118 1 2062820224 28433600 17662528
17:50:50 12720 8192000000 10983576080 11418071040 2023799248 19.810 100 60 119 3 2069276512 28012096 17465168
17:52:17 3009 8192000000 10981947600 11416248320 2017853680 19.763 100 66 130 2 2070616128 32415344 20347104
17:53:24 3753 8192000000 11009905904 11436429312 2029730720 19.857 100 80 159 0 2089482064 36126704 23624640
17:54:38 3269 8192000000 11007088720 11429318656 2019824496 19.779 100 80 157 1 2085712832 41466144 24422192
17:55:49 2536 8192000000 10998057152 11419246592 2006706672 19.676 100 79 158 2 2078077328 46011248 25359408
17:56:59 2641 8192000000 11001498592 11412180992 1991121168 19.553 100 87 173 0 2075222096 56658240 27442688
17:58:06 12393 8192000000 10945173728 11375005696 1957505072 19.286 100 39 78 0 2021938688 50778448 13655168
17:59:24 3378 8192000000 10951584080 11384832000 1967001472 19.362 100 45 90 1 2033195744 50557584 15636688
18:00:32 15605 8192000000 10906282016 11344830464 1892906016 18.769 96 34 68 1 1948475488 43735952 11833520
18:02:09 19240 8192000000 10938277280 11389632512 1959740912 19.304 100 32 64 0 2018656512 47550352 11365248
18:03:35 20966 8192000000 10883605440 11342143488 1884857568 18.704 97 22 44 1 1938269840 45528624 7883648
18:04:58 48726 8192000000 10845653040 11309371392 1878019408 18.649 97 7 14 0 1898264096 17790720 2453968
18:06:55 64574 8192000000 10876268320 11322519552 1937358304 19.126 100 17 33 2 1951087072 9324576 4404192
18:09:17 46893 8192000000 10869520256 11327066112 1923790288 19.017 99 23 46 0 1936694384 7084000 5820096
18:11:04 131020 8192000000 10879375072 11338211328 1861547104 18.516 95 9 18 0 1880236560 16276192 2413264
18:14:16 115059 8192000000 10904131248 11353063424 1952783696 19.249 100 25 49 0 1977055360 16083568 8188096
18:17:39 28943 8192000000 10901052080 11356610560 1938907984 19.138 99 54 107 0 1969162224 14090016 16164224
18:19:09 49288 8192000000 10873661904 11348566016 1939689680 19.144 99 10 20 0 1958193024 15322928 3180416
18:20:59 39224 8192000000 10874989168 11345387520 1917306176 18.965 98 12 24 0 1931257104 10654480 3296448
18:23:54 160245 8192000000 10884148624 11341352960 1887730240 18.727 96 26 50 1 1907616048 12062864 7822944
18:27:45 39458 8192000000 10896309856 11374686208 1974061552 19.418 100 19 36 1 1991145408 12208672 4875184
18:29:32 75423 8192000000 10911707040 11391995904 1982598544 19.485 100 16 32 0 2000370064 13353760 4417760
18:32:04 37104 8192000000 10915568016 11384754176 1968642624 19.375 99 21 42 0 1987892048 13109744 6139680
18:33:53 80029 8192000000 10923724704 11402407936 1954118064 19.259 98 16 32 0 1975347376 16652576 4576736
18:36:14 60030 8192000000 10911467232 11382906880 1933228480 19.093 96 18 36 1 1947712784 9968976 4515328
18:38:47 60780 8192000000 10914728352 11398352896 1964766432 19.344 99 15 30 0 1987452240 18381168 4304640
18:40:49 16315 8192000000 10952537568 11395043328 1956388272 19.277 98 56 113 1 1982310544 9894416 16027856
18:42:09 55889 8192000000 10888429568 11367100416 1935801952 19.113 99 13 26 0 1957451632 18154848 3494832
18:44:06 76392 8192000000 10858120592 11328507904 1778436400 17.837 91 14 28 0 1799238016 17105248 3696368
18:46:24 94725 8192000000 10843940608 11305177088 1884505280 18.701 97 24 48 0 1898647552 7593936 6548336
18:48:59 31377 8192000000 10850934064 11304943616 1899116976 18.819 98 32 63 1 1915639520 7549312 8973232
18:50:31 42098 8192000000 10857194048 11337125888 1927949840 19.050 99 14 28 1 1942968576 10208720 4810016
18:52:34 51336 8192000000 10835970928 11304165376 1869151968 18.577 97 18 37 0 1885563152 11116304 5294880
18:54:31 54331 8192000000 10829154256 11308273664 1845874256 18.389 96 7 14 0 1863249200 15084944 2290000
18:56:41 58926 8192000000 10864049552 11319320576 1874883040 18.624 97 24 48 1 1888909328 7641872 6384416
18:58:46 78408 8192000000 10859029200 11345178624 1906780240 18.881 98 16 32 0 1926745936 15614048 4351648
19:01:05 40053 8192000000 10894969536 11360800768 1948388432 19.214 100 31 62 2 1971058672 13888048 8782192
19:02:52 72996 8192000000 10884837056 11363991552 1929083200 19.060 99 6 12 0 1951476624 20710912 1682512
19:05:05 45255 8192000000 10881957616 11341086720 1916391568 18.958 98 25 51 1 1936875328 13209952 7273808
19:06:51 25475 8192000000 10903349024 11358310400 1959917968 19.305 100 22 44 0 1976226576 9370272 6938336
19:08:18 60742 8192000000 10887878400 11357937664 1932323280 19.085 98 28 56 0 1954122384 13818144 7980960
19:10:20 124802 8192000000 10877057360 11355754496 1891084368 18.755 96 21 42 2 1906513888 9200384 6229136
19:13:32 64231 8192000000 10882582960 11364487168 1964424640 19.341 100 11 21 2 1976797696 9031184 3341872
19:15:40 61292 8192000000 10889376320 11354488832 1953197360 19.252 100 18 36 0 1966691968 8000704 5493904
19:17:49 39940 8192000000 10893828720 11367571456 1968274176 19.372 100 17 34 0 1981415216 8255632 4885408
19:19:29 42769 8192000000 10886990448 11373531136 1951378240 19.237 99 17 34 0 1964096016 7788672 4929104
19:21:12 69176 8192000000 10908346224 11386810368 1959491632 19.302 99 10 21 0 1973385264 11355600 2538032
19:23:22 73533 8192000000 10895197536 11375689728 1948508720 19.215 98 15 29 2 1967237328 14717168 4011440
19:25:36 37222 8192000000 10912010464 11382599680 1961351952 19.317 99 30 60 0 1983074736 12211728 9511056
19:27:15 43927 8192000000 10902951824 11372351488 1953525344 19.255 99 24 48 2 1973801792 13902992 6373456
19:28:59 96625 8192000000 10878358096 11325353984 1766137952 17.735 90 10 20 1 1780604416 11890144 2576320
19:31:38 70057 8192000000 10869732000 11324522496 1859549568 18.500 96 8 16 0 1874571216 12953072 2068576
19:33:53 104853 8192000000 10860586176 11331993600 1921737584 19.001 99 28 56 0 1939998256 10700016 7560656
19:36:43 74026 8192000000 10505642608 10965266432 1586444896 16.223 83 5 10 0 1605247184 17698368 1103920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-08-2021 19:40:33 to 19-08-2021 19:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 67 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

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

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          237771
MON$OLDEST_ACTIVE               238349
MON$OLDEST_SNAPSHOT             238349
MON$NEXT_TRANSACTION            238349
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                       2024992
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:107: 
:108: mscs-test-161.budzdorov.ru    Thu Aug 19 19:38:01 2021
:109:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:110: 
:111: 
:112: mscs-test-161.budzdorov.ru    Thu Aug 19 19:38:13 2021
:113:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:114: 
:115: 
:116: mscs-test-161.budzdorov.ru    Thu Aug 19 19:38:23 2021
:117:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:118: 
--- end of diff output ---

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

19.08.21 19:40:52. 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-19 19:40:54 No severe PSQL-related problems occured
19.08.21 19:40:55. End of report.