Stream: committers
Topic: FHIR Build issue-FMM = 1
Andy Stechishin (Mar 30 2017 at 22:24):
I am working on a resource (that I thought was FMM 0) that needs some changes. When I try to run the build to see what problems I may have injected with my changes, the build responds:
[java] ERROR @ Contract: Resource Contract (FMM=1) cannot have an FMM level >1 (1) if it has warnings (src = ResourceValidator) [java] Didn't publish FHIR due to errors @ Thu, Mar 30, 2017 16:09-0600 4.182 42sec 1061MB [java] ERROR: Unable to process warnings: Errors executing build. Details logged. [java] This was a Full Build 0.595 42sec 1061MB
Andy Stechishin (Mar 30 2017 at 22:25):
The problem being is it doesn't dump out the warnings, so I don't know what I need to fix
Andy Stechishin (Mar 30 2017 at 22:26):
If I could temporarily adjust the FMM level to 0 while I make changes and then fix the warnings that would work but I cannot find where this is defined (hint the FMM level on the first sheet of the resource spreadsheet doesn't seem to affect anything)
Lloyd McKenzie (Mar 30 2017 at 22:45):
@Andy Stechishin The build should provide a list of all the warning, error and information messages it's encountered to date before it spits out that message. (You may have to scroll back several pages of the console to find the warnings.) The FMM levels for the FM resources were confirmed by Paul, so if it's not FMM 0, presumably that's because that's what FM agreed. In any case, to bring something back to FMM 0, look for your resource name in fhir.ini. There's a section there that establishes the FMM levels. We centralized maintenance there instead of using the metadata page because we need to set them all based on the FMM spreadsheet and doing that resource by resource was too hard.
Andy Stechishin (Mar 30 2017 at 22:47):
Full log:
fhir-build stechia$ ./publish.sh
Running publication process now with args: ''
Buildfile: /Users/stechia/Documents/develop/HL7/fhir-build/build.xml
download-ivy:
[echo] installing ivy...
[get] Getting: http://repo1.maven.org/maven2/org/apache/ivy/ivy/2.4.0/ivy-2.4.0.jar
[get] To: /Users/stechia/Documents/develop/HL7/fhir-build/ivy/ivy.jar
[get] Not modified - so not downloaded
install-ivy:
fetch-imports:
[ivy:resolve] :: Apache Ivy 2.4.0 - 20141213170938 :: http://ant.apache.org/ivy/ ::
[ivy:resolve] :: loading settings :: file = /Users/stechia/Documents/develop/HL7/fhir-build/ivysettings.xml
[ivy:resolve] :: resolving dependencies :: org.hl7.fhir#tools;working@chipmunk-9.local [not transitive]
[ivy:resolve] confs: [default]
[ivy:resolve] found ca.uhn.hapi.fhir#hapi-fhir-base;1.6 in maven
[ivy:resolve] found com.google.code.gson#gson;2.3 in maven
[ivy:resolve] found commons-codec#commons-codec;1.9 in maven
[ivy:resolve] found commons-discovery#commons-discovery;0.2 in maven
[ivy:resolve] found commons-httpclient#commons-httpclient;3.0.1 in maven
[ivy:resolve] found commons-io#commons-io;1.2 in maven
[ivy:resolve] found commons-logging#commons-logging-api;1.1 in maven
[ivy:resolve] found commons-logging#commons-logging;1.1.1 in maven
[ivy:resolve] found org.apache.commons#commons-exec;1.3 in maven
[ivy:resolve] found com.trilead#trilead-ssh2;1.0.0-build217 in maven
[ivy:resolve] found de.regnis.q.sequence#sequence-library;1.0.2 in maven
[ivy:resolve] found junit#junit;4.11 in maven
[ivy:resolve] found net.java.dev.jna#jna;3.5.2 in maven
[ivy:resolve] found org.antlr#antlr-runtime;3.5.2 in maven
[ivy:resolve] found org.antlr#ST4;4.0.7 in maven
[ivy:resolve] found org.apache.commons#commons-lang3;3.3.2 in maven
[ivy:resolve] found org.apache.httpcomponents#fluent-hc;4.2.3 in maven
[ivy:resolve] found org.apache.httpcomponents#httpclient;4.2.3 in maven
[ivy:resolve] found org.apache.httpcomponents#httpcore;4.2.2 in maven
[ivy:resolve] found org.apache.httpcomponents#httpmime;4.2.3 in maven
[ivy:resolve] found org.apache.httpcomponents#httpclient-cache;4.2.3 in maven
[ivy:resolve] found org.eclipse.emf#org.eclipse.emf.ecore;2.9.2-v20131212-0545 in maven
[ivy:resolve] found org.eclipse.emf#org.eclipse.emf.ecore.xmi;2.9.1-v20131212-0545 in maven
[ivy:resolve] found org.eclipse.emf#org.eclipse.emf.common;2.9.2-v20131212-0545 in maven
[ivy:resolve] found org.apache.poi#poi;3.10.1 in maven
[ivy:resolve] found org.mozilla#rhino;1.7R4 in maven
[ivy:resolve] found org.hamcrest#hamcrest-core;1.3 in maven
[ivy:resolve] found org.jdom#jdom;1.1.3 in maven
[ivy:resolve] found org.tmatesoft.sqljet#sqljet;1.1.10 in maven
[ivy:resolve] found org.tmatesoft.svnkit#svnkit-cli;1.8.5 in maven
[ivy:resolve] found org.tmatesoft.svnkit#svnkit-javahl16;1.8.5 in maven
[ivy:resolve] found org.tmatesoft.svnkit#svnkit;1.8.5 in maven
[ivy:resolve] found xpp3#xpp3;1.1.4c in maven
[ivy:resolve] found xpp3#xpp3_xpath;1.1.4c in maven
[ivy:resolve] found org.apache.jena#jena-core;3.1.0 in maven
[ivy:resolve] found org.apache.jena#jena-arq;3.1.0 in maven
[ivy:resolve] found org.apache.jena#jena-iri;3.1.0 in maven
[ivy:resolve] found org.apache.jena#jena-base;3.1.0 in maven
[ivy:resolve] found org.apache.jena#jena-shaded-guava;3.1.0 in maven
[ivy:resolve] found xerces#xercesImpl;2.11.0 in maven
[ivy:resolve] found xml-apis#xml-apis;1.4.01 in maven
[ivy:resolve] found com.fasterxml.jackson.core#jackson-core;2.5.2 in maven
[ivy:resolve] found com.fasterxml.jackson.core#jackson-databind;2.5.2 in maven
[ivy:resolve] found com.fasterxml.jackson.core#jackson-annotations;2.5.2 in maven
[ivy:resolve] found com.github.rjeschke#txtmark;0.11 in maven
[ivy:resolve] found org.pegdown#pegdown;1.5.0 in maven
[ivy:resolve] found net.sf.saxon#Saxon-HE;9.5.1-5 in maven
[ivy:resolve] found org.slf4s#slf4s-api_2.11;1.7.13 in maven
[ivy:resolve] found com.typesafe.scala-logging#scala-logging_2.12;3.5.0 in maven
[ivy:resolve] found org.everit.json#org.everit.json.schema;1.3.0 in maven
[ivy:resolve] found org.json#json;20160212 in maven
[ivy:resolve] found com.google.code.javaparser#javaparser;1.0.11 in maven
[ivy:resolve] found com.github.jsonld-java#jsonld-java;0.9.0 in maven
[ivy:resolve] found com.github.jsonld-java#jsonld-java-jena;0.4.1 in maven
[ivy:resolve] found org.scala-lang#scala-compiler;2.12.1 in maven
[ivy:resolve] found org.scala-lang#scala-library;2.12.1 in maven
[ivy:resolve] found es.weso#schema_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#shacl_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#shex_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#manifest_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#srdfjena_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#srdf_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#utils_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#converter_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#rbe_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#typing_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#validating_2.12;0.0.60 in bintray
[ivy:resolve] found es.weso#server_2.12;0.0.60 in bintray
[ivy:resolve] found org.scalactic#scalactic_2.12;3.0.1 in maven
[ivy:resolve] found org.scalatest#scalatest_2.12;3.0.1 in maven
[ivy:resolve] found org.rogach#scallop_2.12;2.0.6 in maven
[ivy:resolve] found org.typelevel#cats-core_2.12;0.9.0 in maven
[ivy:resolve] found org.typelevel#cats-kernel_2.12;0.9.0 in maven
[ivy:resolve] found org.antlr#antlr4-runtime;4.6 in maven
[ivy:resolve] found io.circe#circe-core_2.11;0.7.0-M2 in maven
[ivy:resolve] found org.slf4j#slf4j-jdk14;1.7.22 in maven
[ivy:resolve] found org.slf4j#slf4j-api;1.7.22 in maven
[ivy:resolve] :: resolution report :: resolve 1731ms :: artifacts dl 91ms
[ivy:resolve] :: evicted modules:
[ivy:resolve] com.github.jsonld-java#jsonld-java;0.8.3 by [com.github.jsonld-java#jsonld-java;0.9.0] in [default]
---------------------------------------------------------------------
| | modules || artifacts |
| conf | number| search|dwnlded|evicted|| number|dwnlded|
---------------------------------------------------------------------
| default | 78 | 0 | 0 | 1 || 221 | 0 |
---------------------------------------------------------------------
[ivy:retrieve] :: retrieving :: org.hl7.fhir#tools [sync]
[ivy:retrieve] confs: [default]
[ivy:retrieve] 0 artifacts copied, 77 already retrieved (0kB/30ms)
[ivy:dependencytree] Dependency tree for org.hl7.fhir-tools
[ivy:dependencytree] +- ca.uhn.hapi.fhir#hapi-fhir-base;1.6
[ivy:dependencytree] +- com.google.code.gson#gson;2.3
[ivy:dependencytree] +- commons-codec#commons-codec;1.9
[ivy:dependencytree] +- commons-discovery#commons-discovery;0.2
[ivy:dependencytree] +- commons-httpclient#commons-httpclient;3.0.1
[ivy:dependencytree] +- commons-io#commons-io;1.2
[ivy:dependencytree] +- commons-logging#commons-logging-api;1.1
[ivy:dependencytree] +- commons-logging#commons-logging;1.1.1
[ivy:dependencytree] +- org.apache.commons#commons-exec;1.3
[ivy:dependencytree] +- com.trilead#trilead-ssh2;1.0.0-build217
[ivy:dependencytree] +- de.regnis.q.sequence#sequence-library;1.0.2
[ivy:dependencytree] +- junit#junit;4.11
[ivy:dependencytree] +- net.java.dev.jna#jna;3.5.2
[ivy:dependencytree] +- org.antlr#antlr-runtime;3.5.2
[ivy:dependencytree] +- org.antlr#ST4;4.0.7
[ivy:dependencytree] +- org.apache.commons#commons-lang3;3.3.2
[ivy:dependencytree] +- org.apache.httpcomponents#fluent-hc;4.2.3
[ivy:dependencytree] +- org.apache.httpcomponents#httpclient;4.2.3
[ivy:dependencytree] +- org.apache.httpcomponents#httpcore;4.2.2
[ivy:dependencytree] +- org.apache.httpcomponents#httpmime;4.2.3
[ivy:dependencytree] +- org.apache.httpcomponents#httpclient-cache;4.2.3
[ivy:dependencytree] +- org.eclipse.emf#org.eclipse.emf.ecore;2.9.2-v20131212-0545
[ivy:dependencytree] +- org.eclipse.emf#org.eclipse.emf.ecore.xmi;2.9.1-v20131212-0545
[ivy:dependencytree] +- org.eclipse.emf#org.eclipse.emf.common;2.9.2-v20131212-0545
[ivy:dependencytree] +- org.apache.poi#poi;3.10.1
[ivy:dependencytree] +- org.mozilla#rhino;1.7R4
[ivy:dependencytree] +- org.hamcrest#hamcrest-core;1.3
[ivy:dependencytree] +- org.jdom#jdom;1.1.3
[ivy:dependencytree] +- org.tmatesoft.sqljet#sqljet;1.1.10
[ivy:dependencytree] +- org.tmatesoft.svnkit#svnkit-cli;1.8.5
[ivy:dependencytree] +- org.tmatesoft.svnkit#svnkit-javahl16;1.8.5
[ivy:dependencytree] +- org.tmatesoft.svnkit#svnkit;1.8.5
[ivy:dependencytree] +- xpp3#xpp3;1.1.4c
[ivy:dependencytree] +- xpp3#xpp3_xpath;1.1.4c
[ivy:dependencytree] +- org.apache.jena#jena-core;3.1.0
[ivy:dependencytree] +- org.apache.jena#jena-arq;3.1.0
[ivy:dependencytree] +- org.apache.jena#jena-iri;3.1.0
[ivy:dependencytree] +- org.apache.jena#jena-base;3.1.0
[ivy:dependencytree] +- org.apache.jena#jena-shaded-guava;3.1.0
[ivy:dependencytree] +- xerces#xercesImpl;2.11.0
[ivy:dependencytree] +- xml-apis#xml-apis;1.4.01
[ivy:dependencytree] +- com.fasterxml.jackson.core#jackson-core;2.5.2
[ivy:dependencytree] +- com.fasterxml.jackson.core#jackson-databind;2.5.2
[ivy:dependencytree] +- com.fasterxml.jackson.core#jackson-annotations;2.5.2
[ivy:dependencytree] +- com.github.rjeschke#txtmark;0.11
[ivy:dependencytree] +- org.pegdown#pegdown;1.5.0
[ivy:dependencytree] +- net.sf.saxon#Saxon-HE;9.5.1-5
[ivy:dependencytree] +- org.slf4s#slf4s-api_2.11;1.7.13
[ivy:dependencytree] +- com.typesafe.scala-logging#scala-logging_2.12;3.5.0
[ivy:dependencytree] +- org.everit.json#org.everit.json.schema;1.3.0
[ivy:dependencytree] +- org.json#json;20160212
[ivy:dependencytree] +- com.google.code.javaparser#javaparser;1.0.11
[ivy:dependencytree] +- com.github.jsonld-java#jsonld-java;0.9.0
[ivy:dependencytree] +- com.github.jsonld-java#jsonld-java-jena;0.4.1
[ivy:dependencytree] +- org.scala-lang#scala-compiler;2.12.1
[ivy:dependencytree] +- org.scala-lang#scala-
Andy Stechishin (Mar 30 2017 at 22:48):
Don't know if it got everything after the ivy:dependencytree:
Publisher:
build-subprojects:
init:
build-project:
[echo] org.hl7.fhir.utilities: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.utilities/build.xml
init:
build-project:
[echo] org.hl7.fhir.dstu3: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.dstu3/build.xml
init:
build-project:
[echo] org.hl7.fhir.dstu2: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.dstu2/build.xml
init:
build-project:
[echo] org.hl7.fhir.dstu2: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.dstu2016may/build.xml
init:
build-project:
[echo] org.hl7.fhir.convertors: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.convertors/build.xml
init:
build-project:
[echo] org.hl7.fhir.validation: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.validation/build.xml
init:
build-project:
[echo] org.hl7.fhir.rdf: /Users/stechia/Documents/develop/HL7/fhir-build/implementations/java/org.hl7.fhir.rdf/build.xml
build-project:
[echo] org.hl7.fhir.tools: /Users/stechia/Documents/develop/HL7/fhir-build/tools/java/org.hl7.fhir.igtools/build.xml
build-project:
[echo] org.hl7.fhir.tools: /Users/stechia/Documents/develop/HL7/fhir-build/tools/java/org.hl7.fhir.tools.core/build.xml
build:
Publisher:
[java] Publish FHIR in folder /Users/stechia/Documents/develop/HL7/fhir-build @ Thu, Mar 30, 2017 16:45-0600 0.017 0sec 21MB
[java] Build local copy 0.006 0sec 21MB
[java] Detected Java version: 1.8.0_121 from /Library/Java/JavaVirtualMachines/jdk1.8.0_121.jdk/Contents/Home/jre on x86_64 0.0 0sec 21MB
[java] Checking Source for /Users/stechia/Documents/develop/HL7/fhir-build 6.588 6sec 92MB
[java] Version 3.0.0-11865
[java] Full Build 0.824 7sec 87MB
[java] Load Loinc 0.098 7sec 87MB
[java] Loading 0.697 8sec 181MB
[java] Load Common Bindings 0.037 8sec 181MB
[java] Load Resource Templates 1.991 10sec 965MB
[java] Load Resources 0.04 10sec 989MB
[java] Load Extras 5.91 16sec 527MB
[java] Load Code Systems 0.028 16sec 581MB
[java] Load Value Sets 0.056 16sec 581MB
[java] Load Profiles 0.01 16sec 581MB
[java] ...EHRS Functional model - Record Lifecycle Events 1.745 18sec 1725MB
[java] ...Clinical Quality Improvement Framework 0.087 18sec 1779MB
[java] Copy HTML templates 0.371 18sec 1833MB
[java] ...diagram uml-example 0.204 18sec 1833MB
[java] ...diagram primitives 0.001 18sec 1833MB
[java] ...diagram resources 0.002 18sec 1833MB
[java] ...vocab #1 0.001 18sec 1833MB
[java] ==============!! Running without terminology server !!==============
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-SecurityPolicy
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-ObligationPolicy
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-RefrainPolicy
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-PurposeOfUse
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-GeneralPurposeOfUse
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-ProvenanceEventCurrentState-AS
[java] Unable to resolve value set http://hl7.org/fhir/ValueSet/v3-ProvenanceEventCurrentState-DC
[java] ...code systems 10.755 29sec 476MB
[java] ...value sets 0.002 29sec 476MB
[java] Validating 0.361 29sec 801MB
[java] ERROR @ Contract: Resource Contract (FMM=1) cannot have an FMM level >1 (1) if it has warnings (src = ResourceValidator)
[java] Didn't publish FHIR due to errors @ Thu, Mar 30, 2017 16:46-0600 3.025 32sec 397MB
[java] ERROR: Unable to process warnings: Errors executing build. Details logged.
[java] This was a Full Build 0.388 33sec 400MB
[java] java.lang.Exception: Errors executing build. Details logged.
[java] at org.hl7.fhir.tools.publisher.Publisher.checkAllOk(Publisher.java:1641)
[java] at org.hl7.fhir.tools.publisher.Publisher.validate(Publisher.java:1627)
[java] at org.hl7.fhir.tools.publisher.Publisher.execute(Publisher.java:577)
[java] at org.hl7.fhir.tools.publisher.Publisher.main(Publisher.java:430)
[java] FHIR build failure @ Thu, Mar 30, 2017 16:46-0600 0.643 33sec 400MB
[java] Error: Errors executing build. Details logged.
[java] java.lang.Exception: Errors executing build. Details logged.
[java] at org.hl7.fhir.tools.publisher.Publisher.checkAllOk(Publisher.java:1641)
[java] at org.hl7.fhir.tools.publisher.Publisher.validate(Publisher.java:1627)
[java] at org.hl7.fhir.tools.publisher.Publisher.execute(Publisher.java:577)
[java] at org.hl7.fhir.tools.publisher.Publisher.main(Publisher.java:430)
BUILD FAILED
/Users/stechia/Documents/develop/HL7/fhir-build/build.xml:30: The following error occurred while executing this line:
/Users/stechia/Documents/develop/HL7/fhir-build/tools/java/org.hl7.fhir.tools.core/build.xml:145: Java returned: 1
Total time: 38 seconds
chipmunk-9:fhir-build stechia$
Andy Stechishin (Mar 30 2017 at 22:49):
What am I missing, I can't find an error message in there. I also looked in the suppressed_messages.txt file but nada
Grahame Grieve (Mar 30 2017 at 23:15):
@Lloyd McKenzie the xslt that processes errors and warnings is failing on Andy's machine. The build is logging the wrong exception so we don't see why that is happening. I've just corrected the logging to log the correct error
Grahame Grieve (Mar 30 2017 at 23:31):
Ansy's actual problem is: [java] java.io.FileNotFoundException: /Users/stechia/Documents/develop/HL7/fhir-build/publish/profiles-resources.xml
Lloyd McKenzie (Mar 30 2017 at 23:38):
Looks like this is a special case that hasn't come up before. Take a look in your publish directory to see if there's a warnings.xml file there. If not, you'll need to wait or a fix. Will try to have something to you in 2-3 hrs.
Andy Stechishin (Mar 31 2017 at 00:02):
There is warnings.xml in the publish directory and it is empty
Andy Stechishin (Mar 31 2017 at 00:08):
Understand on the timing, will look for your notice (here)
Lloyd McKenzie (Mar 31 2017 at 00:34):
Ok, I've committed a fix that should at least spit out the warnings. They won't be sorted or organized by WG because your build is failing before that information is available. I can't test because I don't know exactly where you're failing (and don't have that cause in my environment), so let me know if it works :)
Andy Stechishin (Mar 31 2017 at 00:36):
will try it out and get back to you in about 30 minutes or so (it is failing on new W5 mappings and columns is wrong order)
Andy Stechishin (Mar 31 2017 at 00:48):
OK, still doesn't send errors to console but it does dump things into warnings.xml
Andy Stechishin (Mar 31 2017 at 00:49):
Andy Stechishin (Mar 31 2017 at 00:49):
Andy Stechishin (Mar 31 2017 at 00:50):
would appear the file Grahame told you about is still not there
Lloyd McKenzie (Mar 31 2017 at 00:53):
Can you send me a source file that will trigger the problem?
Andy Stechishin (Mar 31 2017 at 00:53):
so I think the steps you need to follow are:
1. checkout the FHIR build to a new directory (not an update): svn co http://gforge.hl7.org/svn/fhir/trunk/build new-build
2. edit one of the FMM 1 resource into something that causes an error (like a W5 column out of order)
3. run publish.(sh|bat)
4. wait for errors that don't appear
Andy Stechishin (Mar 31 2017 at 00:54):
Andy Stechishin (Mar 31 2017 at 00:54):
Andy Stechishin (Mar 31 2017 at 00:55):
Andy Stechishin (Mar 31 2017 at 00:56):
ZUlip won't let me send the file, coming in email
Lloyd McKenzie (Mar 31 2017 at 02:24):
Ok, try again
Andy Stechishin (Mar 31 2017 at 04:34):
OK, dumps the errors, thanks
Andy Stechishin (Mar 31 2017 at 04:35):
Not sure if these errors are important to you:
[java] java.lang.Exception: Unable to create warnings file - one or more profiles-* files unavailable or invalid
[java] at org.hl7.fhir.tools.publisher.Publisher.processWarnings(Publisher.java:1665)
[java] at org.hl7.fhir.tools.publisher.Publisher.execute(Publisher.java:611)
[java] at org.hl7.fhir.tools.publisher.Publisher.main(Publisher.java:430)
[java] java.io.FileNotFoundException: /Users/stechia/Documents/develop/HL7/fhir-build/publish/profiles-resources.xml (No such file or directory)
Lloyd McKenzie (Mar 31 2017 at 04:56):
That's just to indicate that the dump is happening sooner than it should and why it's ugly
Andy Stechishin (Mar 31 2017 at 05:02):
Pretty is a nicety, getting the error is the important part, thanks again
Last updated: Apr 12 2022 at 19:14 UTC
