builder: comm-esr38-linux64-periodicupdate
slave: bld-linux64-spot-221
starttime: 1449313441.26
results: success (0)

========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.262393) =========
master: http://buildbot-master70.bb.releng.use1.mozilla.com:8001/
========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.262801) =========

========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.263088) =========
bash -c pwd
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'pwd']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
/builds/slave/c-esr38-l64-periodicupdate-000
program finished with exit code 0
elapsedTime=0.004010
basedir: '/builds/slave/c-esr38-l64-periodicupdate-000'
========= master_lag: 0.16 =========
========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.422631) =========

========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.422931) =========
========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.436288) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.436544) =========
rm -rf properties
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'properties']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
program finished with exit code 0
elapsedTime=0.003014
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.483650) =========

========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.483924) =========
script_repo_url: https://hg.mozilla.org/build/tools
========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.484273) =========

========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.484545) =========
rm -rf scripts
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['rm', '-rf', 'scripts']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
program finished with exit code 0
elapsedTime=0.002625
========= master_lag: 0.04 =========
========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:01.530768) =========

========= Started 'hg clone ...' (results: 0, elapsed: 3 secs) (at 2015-12-05 03:04:01.531071) =========
hg clone https://hg.mozilla.org/build/tools scripts
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/. (timeout 1320 secs)
 watching logfiles {}
 argv: ['hg', 'clone', 'https://hg.mozilla.org/build/tools', 'scripts']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
(ignoring URL on server that requires SNI)
(your Python is older than 2.7.9 and does not support modern and secure SSL/TLS; please consider upgrading your Python to a secure version)
downloading bundle https://s3-external-1.amazonaws.com/moz-hg-bundles-us-east-1/build/tools/fbe3196587fc2f83ea6c121ec07088973c2b6f47.stream-legacy.hg
warning: s3-external-1.amazonaws.com certificate with fingerprint d3:da:3a:bf:20:04:f2:b7:b0:e9:d8:2e:f5:0a:0c:86:61:f5:32:25 not verified (check hostfingerprints or web.cacerts config setting)
streaming all changes
1920 files to transfer, 25.8 MB of data
transferred 25.8 MB in 0.6 seconds (40.0 MB/sec)
finishing applying bundle; pulling
searching for changes
no changes found
updating to branch default
1125 files updated, 0 files merged, 0 files removed, 0 files unresolved
program finished with exit code 0
elapsedTime=3.163774
========= master_lag: 0.01 =========
========= Finished 'hg clone ...' (results: 0, elapsed: 3 secs) (at 2015-12-05 03:04:04.706408) =========

========= Started 'hg update ...' (results: 0, elapsed: 1 secs) (at 2015-12-05 03:04:04.706746) =========
hg update -C -r default
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/scripts (timeout 1200 secs)
 watching logfiles {}
 argv: ['hg', 'update', '-C', '-r', 'default']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000/scripts
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
0 files updated, 0 files merged, 0 files removed, 0 files unresolved
program finished with exit code 0
elapsedTime=1.221232
========= master_lag: 0.02 =========
========= Finished 'hg update ...' (results: 0, elapsed: 1 secs) (at 2015-12-05 03:04:05.943315) =========

========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:05.943642) =========
hg id -i
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/scripts (timeout 1200 secs)
 watching logfiles {}
 argv: ['hg', 'id', '-i']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000/scripts
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
fbe3196587fc
program finished with exit code 0
elapsedTime=0.365966
script_repo_revision: 'fbe3196587fc'
========= master_lag: 0.01 =========
========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:06.321640) =========

========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:06.321941) =========
TinderboxPrint: script_revlink: https://hg.mozilla.org/build/tools/rev/fbe3196587fc
========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-05 03:04:06.322299) =========

========= Started 'bash scripts/scripts/periodic_file_updates/periodic_file_updates.sh ...' (results: 0, elapsed: 1 mins, 57 secs) (at 2015-12-05 03:04:06.322574) =========
bash scripts/scripts/periodic_file_updates/periodic_file_updates.sh -b releases/comm-esr38 --bundle https://ftp-ssl.mozilla.org/pub/mozilla.org/thunderbird/bundles/comm-esr38.hg -p thunderbird -u tbirdbld -k '~cltbld/.ssh/tbirdbld_dsa' -a --blocklist
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/. (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', 'scripts/scripts/periodic_file_updates/periodic_file_updates.sh', '-b', 'releases/comm-esr38', '--bundle', 'https://ftp-ssl.mozilla.org/pub/mozilla.org/thunderbird/bundles/comm-esr38.hg', '-p', 'thunderbird', '-u', 'tbirdbld', '-k', '~cltbld/.ssh/tbirdbld_dsa', '-a', '--blocklist']
 environment:
  CCACHE_COMPRESS=1
  CCACHE_DIR=/builds/ccache
  CCACHE_HASHDIR=
  CCACHE_UMASK=002
  DISPLAY=:2
  G_BROKEN_FILENAMES=1
  HG_SHARE_BASE_DIR=/builds/hg-shared
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LC_ALL=C
  LD_LIBRARY_PATH=/tools/gcc-4.3.3/installed/lib64
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  MOZ_AUTOMATION=1
  MOZ_CRASHREPORTER_NO_REPORT=1
  MOZ_OBJDIR=objdir-tb
  MOZ_SYMBOLS_EXTRA_BUILDID=linux64
  PATH=/tools/buildbot/bin:/usr/local/bin:/usr/lib64/ccache:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/tools/git/bin:/tools/python27/bin:/tools/python27-mercurial/bin:/home/cltbld/bin
  POST_SYMBOL_UPLOAD_CMD=/usr/local/bin/post-symbol-upload.py
  PROPERTIES_FILE=/builds/slave/c-esr38-l64-periodicupdate-000/buildprops.json
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000
  SHELL=/bin/bash
  SHLVL=1
  SYMBOL_SERVER_HOST=symbolpush.mozilla.org
  SYMBOL_SERVER_PATH=/mnt/netapp/breakpad/symbols_tbrd/
  SYMBOL_SERVER_SSH_KEY=/home/mock_mozilla/.ssh/tbirdbld_dsa
  SYMBOL_SERVER_USER=tbirdbld
  TERM=linux
  TINDERBOX_OUTPUT=1
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
2015-12-05 03:04:07 URL:http://hg.mozilla.org/releases/comm-esr38/raw-file/default/mail/config/version.txt [7/7] -> "version.txt" [1]
INFO: parsed version is 38.4.0
INFO: wget -nv --no-check-certificate -O blocklist_amo.xml https://blocklist.addons.mozilla.org/blocklist/3/%7B3550f703-e582-4d05-9a08-453d09bdfdc6%7D/38.4.0/Thunderbird/20090105024647/blocklist-sync/en-US/nightly/blocklist-sync/default/default/
2015-12-05 03:04:08 URL:https://blocklist.addons.mozilla.org/blocklist/3/%7B3550f703-e582-4d05-9a08-453d09bdfdc6%7D/38.4.0/Thunderbird/20090105024647/blocklist-sync/en-US/nightly/blocklist-sync/default/default/ [150417] -> "blocklist_amo.xml" [1]
INFO: wget -nv -O blocklist_hg.xml http://hg.mozilla.org/releases/comm-esr38/raw-file/default/mail/app/blocklist.xml
2015-12-05 03:04:09 URL:http://hg.mozilla.org/releases/comm-esr38/raw-file/default/mail/app/blocklist.xml [148263/148263] -> "blocklist_hg.xml" [1]
INFO: diffing in-tree blocklist against the blocklist from AMO...
INFO: AMO blocklist differs from the one in-tree.
INFO: cloning from hg: scripts/scripts/periodic_file_updates/../../buildfarm/utils/hgtool.py --branch default --bundle https://ftp-ssl.mozilla.org/pub/mozilla.org/thunderbird/bundles/comm-esr38.hg http://hg.mozilla.org/releases/comm-esr38 comm-esr38
Reporting hg version in use
command: START
command: hg -q version
command: cwd: .
command: output:
Mercurial Distributed SCM (version 3.2.1)
command: END (0.29s elapsed)

Checking if share extension works
command: START
command: hg help share
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000
command: env: {'HGPLAIN': '1'}
command: END (0.50s elapsed)

Updating shared repo
Reporting hg version in use
command: START
command: hg -q version
command: cwd: .
command: output:
Mercurial Distributed SCM (version 3.2.1)
command: END (0.29s elapsed)

Attempting to initialize clone with bundles
command: START
command: hg init /builds/hg-shared/releases/comm-esr38
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000
command: output:
command: END (0.31s elapsed)

Trying to use bundle https://ftp-ssl.mozilla.org/pub/mozilla.org/thunderbird/bundles/comm-esr38.hg
command: START
command: hg unbundle --traceback https://ftp-ssl.mozilla.org/pub/mozilla.org/thunderbird/bundles/comm-esr38.hg
command: cwd: /builds/hg-shared/releases/comm-esr38
command: env: {'HGPLAIN': '1'}
Hit exception running hg:
Traceback (most recent call last):
  File "scripts/scripts/periodic_file_updates/../../buildfarm/utils/../../lib/python/util/hg.py", line 99, in get_hg_output
    return get_output(['hg'] + cmd, timeout=timeout, env=env, **kwargs)
  File "scripts/scripts/periodic_file_updates/../../buildfarm/utils/../../lib/python/util/commands.py", line 207, in get_output
    raise error
CalledProcessError: Command '['hg', 'unbundle', '--traceback', 'https://ftp-ssl.mozilla.org/pub/mozilla.org/thunderbird/bundles/comm-esr38.hg']' returned non-zero exit status 255
Using bundles failed; falling back to clone
command: START
command: hg clone --traceback -U http://hg.mozilla.org/releases/comm-esr38 /builds/hg-shared/releases/comm-esr38
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000
command: env: {'HGPLAIN': '1'}
command: END (53.33s elapsed)

command: output:
requesting all changes
adding changesets
adding manifests
adding file changes
added 22213 changesets with 80477 changes to 14461 files (+255 heads)

Trying to share /builds/hg-shared/releases/comm-esr38 to /builds/slave/c-esr38-l64-periodicupdate-000/comm-esr38
command: START
command: hg share -U /builds/hg-shared/releases/comm-esr38 /builds/slave/c-esr38-l64-periodicupdate-000/comm-esr38
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000
command: output:
command: END (0.32s elapsed)

command: START
command: hg branch
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000/comm-esr38
command: env: {'HGPLAIN': '1'}
command: END (0.50s elapsed)

command: output:
default

command: START
command: hg update -C
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000/comm-esr38
command: output:
8771 files updated, 0 files merged, 0 files removed, 0 files unresolved
command: END (3.36s elapsed)

command: START
command: hg parent --template {node|short}
command: cwd: /builds/slave/c-esr38-l64-periodicupdate-000/comm-esr38
command: env: {'HGPLAIN': '1'}
command: END (0.50s elapsed)

command: output:
16263412f56b
Got revision 16263412f56b
INFO: pulling from hg: hg -R comm-esr38 pull
pulling from http://hg.mozilla.org/releases/comm-esr38
searching for changes
no changes found
INFO: updating to default: hg -R comm-esr38 update -C default
0 files updated, 0 files merged, 0 files removed, 0 files unresolved
INFO: committing blocklist changes: hg -R comm-esr38 commit -u "tbirdbld" -m "No bug, Automated blocklist update from host bld-linux64-spot-221 - a=blocklist-update"
INFO: hg commit succeeded for blocklist files.
hg -R comm-esr38 push -e "ssh -l tbirdbld -i ~cltbld/.ssh/tbirdbld_dsa" ssh://hg.mozilla.org/releases/comm-esr38
pushing to ssh://hg.mozilla.org/releases/comm-esr38
searching for changes
remote: adding changesets
remote: adding manifests
remote: adding file changes
remote: added 1 changesets with 1 changes to 1 files
remote: recorded push in pushlog
remote: legacy replication of changegroup disabled because vcsreplicator is loaded
remote: 
remote: View your change here:
remote:   https://hg.mozilla.org/releases/comm-esr38/rev/d7bccd1f9080
remote: 
remote: Follow the progress of your build on Treeherder:
remote:   https://treeherder.mozilla.org/#/jobs?repo=comm-esr38&revision=d7bccd1f9080
remote: recorded changegroup in replication log in 0.006s
INFO: hg push succeeded.
program finished with exit code 0
elapsedTime=70.542875
========= master_lag: 46.92 =========
========= Finished 'bash scripts/scripts/periodic_file_updates/periodic_file_updates.sh ...' (results: 0, elapsed: 1 mins, 57 secs) (at 2015-12-05 03:06:03.784768) =========

========= Started no change (results: 0, elapsed: 0 secs) (at 2015-12-05 03:06:03.785716) =========
bash -c 'for file in `ls -1`; do cat $file; done'
 in dir /builds/slave/c-esr38-l64-periodicupdate-000/properties (timeout 1200 secs)
 watching logfiles {}
 argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done']
 environment:
  CCACHE_HASHDIR=
  G_BROKEN_FILENAMES=1
  HISTCONTROL=ignoredups
  HISTSIZE=1000
  HOME=/home/cltbld
  HOSTNAME=bld-linux64-spot-221.build.releng.use1.mozilla.com
  LANG=en_US.UTF-8
  LESSOPEN=|/usr/bin/lesspipe.sh %s
  LOGNAME=cltbld
  MAIL=/var/spool/mail/cltbld
  PATH=/usr/local/bin:/usr/lib64/ccache:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cltbld/bin
  PWD=/builds/slave/c-esr38-l64-periodicupdate-000/properties
  SHELL=/bin/bash
  SHLVL=1
  TERM=linux
  TMOUT=86400
  USER=cltbld
  _=/tools/buildbot/bin/python
 using PTY: False
program finished with exit code 0
elapsedTime=0.005100

========= master_lag: 0.04 =========
========= Finished no change (results: 0, elapsed: 0 secs) (at 2015-12-05 03:06:03.827793) =========

========= Total master_lag: 47.24 =========