builder: mozilla-aurora_ubuntu64_vm_test_pgo-luciddream slave: tst-linux64-spot-716 starttime: 1446116892.33 results: success (0) buildid: 20151029014646 builduid: e2197d6fa76745edba8eadefeb37f684 revision: 41fdefd640f368bccdeafe6446d42c0a5ad22797 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:12.327716) ========= master: http://buildbot-master53.bb.releng.usw2.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:12.328110) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:12.328421) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.026149 basedir: '/builds/slave/test' ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.044950) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.045279) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.091076) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.091515) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021136 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.155456) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.155769) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.156147) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.156453) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False --2015-10-29 04:08:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 11.3M=0.001s 2015-10-29 04:08:13 (11.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.376066 ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.568239) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.568550) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.072566 ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:13.900523) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2015-10-29 04:08:13.900846) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 41fdefd640f368bccdeafe6446d42c0a5ad22797 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 41fdefd640f368bccdeafe6446d42c0a5ad22797 --destination scripts --debug'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False 2015-10-29 04:08:21,567 truncating revision to first 12 chars 2015-10-29 04:08:21,568 Setting DEBUG logging. 2015-10-29 04:08:21,568 attempt 1/10 2015-10-29 04:08:21,568 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/41fdefd640f3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-10-29 04:08:21,901 unpacking tar archive at: mozilla-aurora-41fdefd640f3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.637143 ========= Finished 'bash -c ...' (results: 0, elapsed: 10 secs) (at 2015-10-29 04:08:24.601347) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 9 secs) (at 2015-10-29 04:08:24.601633) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 9 secs) (at 2015-10-29 04:08:34.579625) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:34.579891) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-10-29 04:08:34.580308) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/luciddream_unittest.py ...' (results: 0, elapsed: 6 mins, 57 secs) (at 2015-10-29 04:08:34.580597) ========= /tools/buildbot/bin/python scripts/scripts/luciddream_unittest.py --cfg luciddream/linux_config.py --blob-upload-branch mozilla-aurora --download-symbols ondemand in dir /builds/slave/test/. (timeout 1800 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/luciddream_unittest.py', '--cfg', 'luciddream/linux_config.py', '--blob-upload-branch', 'mozilla-aurora', '--download-symbols', 'ondemand'] environment: CCACHE_DIR=/builds/ccache CCACHE_UMASK=002 DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_HIDE_RESULTS_TABLE=1 MOZ_NODE_PATH=/usr/bin/node MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_FAIL_ON_TEST_ERRORS=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False 04:08:34 INFO - MultiFileLogger online at 20151029 04:08:34 in /builds/slave/test 04:08:34 INFO - Run as scripts/scripts/luciddream_unittest.py --cfg luciddream/linux_config.py --blob-upload-branch mozilla-aurora --download-symbols ondemand 04:08:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:34 INFO - {'append_to_log': False, 04:08:34 INFO - 'b2gdesktop_url': 'http://ftp.mozilla.org/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2', 04:08:34 INFO - 'base_work_dir': '/builds/slave/test', 04:08:34 INFO - 'blob_upload_branch': 'mozilla-aurora', 04:08:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:34 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:34 INFO - 'config_files': ('luciddream/linux_config.py',), 04:08:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:34 INFO - 'download_minidump_stackwalk': True, 04:08:34 INFO - 'download_symbols': 'ondemand', 04:08:34 INFO - 'emulator': 'arm', 04:08:34 INFO - 'exes': {'gittool.py': '%(abs_tools_dir)s/buildfarm/utils/gittool.py', 04:08:34 INFO - 'python': '/tools/buildbot/bin/python', 04:08:34 INFO - 'tooltool.py': '/tools/tooltool.py', 04:08:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:08:34 INFO - '/tools/misc-python/virtualenv.py')}, 04:08:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:34 INFO - 'gaia_branch': 'default', 04:08:34 INFO - 'gaia_repo': 'https://hg.mozilla.org/integration/gaia-central', 04:08:34 INFO - 'log_level': 'info', 04:08:34 INFO - 'log_to_console': True, 04:08:34 INFO - 'opt_config_files': (), 04:08:34 INFO - 'pip_index': False, 04:08:34 INFO - 'require_test_zip': True, 04:08:34 INFO - 'suite_definitions': {'luciddream-b2gdt': {'options': ('--startup-timeout=300', 04:08:34 INFO - '--log-raw=%(raw_log_file)s', 04:08:34 INFO - '--log-errorsummary=%(error_summary_file)s', 04:08:34 INFO - '--browser-path=%(browser_path)s', 04:08:34 INFO - '--b2g-desktop-path=%(fxos_desktop_path)s', 04:08:34 INFO - '--gaia-profile=%(gaia_profile)s', 04:08:34 INFO - '%(test_manifest)s')}, 04:08:34 INFO - 'luciddream-emulator': {'options': ('--startup-timeout=300', 04:08:34 INFO - '--log-raw=%(raw_log_file)s', 04:08:34 INFO - '--log-errorsummary=%(error_summary_file)s', 04:08:34 INFO - '--browser-path=%(browser_path)s', 04:08:34 INFO - '--b2gpath=%(emulator_path)s', 04:08:34 INFO - '%(test_manifest)s')}}, 04:08:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:34 INFO - 'vcs_share_base': '/builds/hg-shared', 04:08:34 INFO - 'virtualenv_modules': ('mozinstall',), 04:08:34 INFO - 'virtualenv_path': 'venv', 04:08:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:34 INFO - 'work_dir': 'build', 04:08:34 INFO - 'xre_path': 'xulrunner-sdk', 04:08:34 INFO - 'xre_url': 'https://api.pub.build.mozilla.org/tooltool/sha512/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462'} 04:08:34 INFO - ##### 04:08:34 INFO - ##### Running clobber step. 04:08:34 INFO - ##### 04:08:34 INFO - Running pre-action listener: _resource_record_pre_action 04:08:34 INFO - Running main action method: clobber 04:08:34 INFO - rmtree: /builds/slave/test/build 04:08:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:37 INFO - Running post-action listener: _resource_record_post_action 04:08:37 INFO - ##### 04:08:37 INFO - ##### Running read-buildbot-config step. 04:08:37 INFO - ##### 04:08:37 INFO - Running pre-action listener: _resource_record_pre_action 04:08:37 INFO - Running main action method: read_buildbot_config 04:08:37 INFO - Using buildbot properties: 04:08:37 INFO - { 04:08:37 INFO - "properties": { 04:08:37 INFO - "buildnumber": 24, 04:08:37 INFO - "product": "firefox", 04:08:37 INFO - "script_repo_revision": "production", 04:08:37 INFO - "branch": "mozilla-aurora", 04:08:37 INFO - "repository": "", 04:08:37 INFO - "buildername": "Ubuntu VM 12.04 x64 mozilla-aurora pgo test luciddream", 04:08:37 INFO - "buildid": "20151029014646", 04:08:37 INFO - "slavename": "tst-linux64-spot-716", 04:08:37 INFO - "pgo_build": "True", 04:08:37 INFO - "basedir": "/builds/slave/test", 04:08:37 INFO - "project": "", 04:08:37 INFO - "platform": "linux64", 04:08:37 INFO - "master": "http://buildbot-master53.bb.releng.usw2.mozilla.com:8201/", 04:08:37 INFO - "slavebuilddir": "test", 04:08:37 INFO - "scheduler": "tests-mozilla-aurora-ubuntu64_vm-pgo-unittest", 04:08:37 INFO - "repo_path": "releases/mozilla-aurora", 04:08:37 INFO - "moz_repo_path": "", 04:08:37 INFO - "stage_platform": "linux64-pgo", 04:08:37 INFO - "builduid": "e2197d6fa76745edba8eadefeb37f684", 04:08:37 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797" 04:08:37 INFO - }, 04:08:37 INFO - "sourcestamp": { 04:08:37 INFO - "repository": "", 04:08:37 INFO - "hasPatch": false, 04:08:37 INFO - "project": "", 04:08:37 INFO - "branch": "mozilla-aurora-linux64-pgo-unittest", 04:08:37 INFO - "changes": [ 04:08:37 INFO - { 04:08:37 INFO - "category": null, 04:08:37 INFO - "files": [ 04:08:37 INFO - { 04:08:37 INFO - "url": null, 04:08:37 INFO - "name": "https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2" 04:08:37 INFO - }, 04:08:37 INFO - { 04:08:37 INFO - "url": null, 04:08:37 INFO - "name": "https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json" 04:08:37 INFO - } 04:08:37 INFO - ], 04:08:37 INFO - "repository": "", 04:08:37 INFO - "rev": "41fdefd640f368bccdeafe6446d42c0a5ad22797", 04:08:37 INFO - "who": "jyavenard@mozilla.com", 04:08:37 INFO - "when": 1446116590, 04:08:37 INFO - "number": 6592948, 04:08:37 INFO - "comments": "Bug 1218157: Only ever read from cached data in NotifyDataArrived. r=cpearce a=lizzard\n\nThe logic of queuing NotifyDataArrived and read data there was fundamentally flawed as we would continually perform reads from the same MediaResource at two different ends.\nThis would cause repetitive seeks and data being removed from the media cache. Worse, a read in NotifyDataArrived would cause another NotifyDataArrived to be scheduled.\n\nAs range-request are extremely slow, it would result in stutters and constant interruptions.\n", 04:08:37 INFO - "project": "", 04:08:37 INFO - "at": "Thu 29 Oct 2015 04:03:10", 04:08:37 INFO - "branch": "mozilla-aurora-linux64-pgo-unittest", 04:08:37 INFO - "revlink": "", 04:08:37 INFO - "properties": [ 04:08:37 INFO - [ 04:08:37 INFO - "buildid", 04:08:37 INFO - "20151029014646", 04:08:37 INFO - "Change" 04:08:37 INFO - ], 04:08:37 INFO - [ 04:08:37 INFO - "builduid", 04:08:37 INFO - "e2197d6fa76745edba8eadefeb37f684", 04:08:37 INFO - "Change" 04:08:37 INFO - ], 04:08:37 INFO - [ 04:08:37 INFO - "pgo_build", 04:08:37 INFO - "True", 04:08:37 INFO - "Change" 04:08:37 INFO - ] 04:08:37 INFO - ], 04:08:37 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797" 04:08:37 INFO - } 04:08:37 INFO - ], 04:08:37 INFO - "revision": "41fdefd640f368bccdeafe6446d42c0a5ad22797" 04:08:37 INFO - } 04:08:37 INFO - } 04:08:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2. 04:08:37 INFO - Found a test packages url https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json. 04:08:37 INFO - Running post-action listener: _resource_record_post_action 04:08:37 INFO - ##### 04:08:37 INFO - ##### Running download-and-extract step. 04:08:37 INFO - ##### 04:08:37 INFO - Running pre-action listener: _resource_record_pre_action 04:08:37 INFO - Running main action method: download_and_extract 04:08:37 INFO - mkdir: /builds/slave/test/build/tests 04:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:37 INFO - https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 04:08:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json 04:08:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json 04:08:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 04:08:37 INFO - retry: Calling _download_file with args: ('http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/test_packages.json', '/builds/slave/test/build/test_packages.json'), kwargs: {}, attempt #1 04:08:37 INFO - Downloaded 1302 bytes. 04:08:37 INFO - Reading from file /builds/slave/test/build/test_packages.json 04:08:37 INFO - Using the following test package requirements: 04:08:37 INFO - {u'common': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'], 04:08:37 INFO - u'cppunittest': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'firefox-43.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:08:37 INFO - u'jittest': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'jsshell-linux-x86_64.zip'], 04:08:37 INFO - u'mochitest': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'firefox-43.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:08:37 INFO - u'mozbase': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'], 04:08:37 INFO - u'reftest': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'firefox-43.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:08:37 INFO - u'talos': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'firefox-43.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:08:37 INFO - u'web-platform': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'firefox-43.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:08:37 INFO - u'webapprt': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'], 04:08:37 INFO - u'xpcshell': [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', 04:08:37 INFO - u'firefox-43.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:08:37 INFO - Downloading packages: [u'firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:37 INFO - https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:08:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip 04:08:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip 04:08:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip 04:08:37 INFO - retry: Calling _download_file with args: (u'http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip', u'/builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'), kwargs: {}, attempt #1 04:08:39 INFO - Downloaded 22907380 bytes. 04:08:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 04:08:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip 04:08:39 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 04:08:42 INFO - Return code: 0 04:08:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:42 INFO - https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:08:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 04:08:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 04:08:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 04:08:42 INFO - retry: Calling _download_file with args: ('http://queue.taskcluster.net.proxxy1.srv.releng.usw2.mozilla.com/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2', '/builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2'), kwargs: {}, attempt #1 04:08:44 INFO - Downloaded 56990127 bytes. 04:08:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 04:08:44 INFO - mkdir: /builds/slave/test/properties 04:08:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:08:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:08:44 INFO - Contents: 04:08:44 INFO - build_url:https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 04:08:44 INFO - mkdir: /builds/slave/test/build/b2gdesktop 04:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:08:44 INFO - http://ftp.mozilla.org/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2 matches http://ftp.mozilla.org 04:08:44 INFO - URL Candidate: http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2 04:08:44 INFO - trying http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2 04:08:44 INFO - Downloading http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2 to /builds/slave/test/build/b2g-37.0.multi.linux-i686.tar.bz2 04:08:44 INFO - retry: Calling _download_file with args: ('http://ftp.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub/mozilla.org/b2g/nightly/2015/03/2015-03-09-00-25-06-mozilla-b2g37_v2_2/b2g-37.0.multi.linux-i686.tar.bz2', '/builds/slave/test/build/b2g-37.0.multi.linux-i686.tar.bz2'), kwargs: {}, attempt #1 04:08:47 INFO - Downloaded 99829313 bytes. 04:08:47 INFO - Running post-action listener: _resource_record_post_action 04:08:47 INFO - Running post-action listener: _set_extra_try_arguments 04:08:47 INFO - ##### 04:08:47 INFO - ##### Running pull step. 04:08:47 INFO - ##### 04:08:47 INFO - Running pre-action listener: _resource_record_pre_action 04:08:47 INFO - Running main action method: pull 04:08:47 INFO - dest: /builds/slave/test/gaia 04:08:47 INFO - retry: Calling load_json_from_url with args: ('https://hg.mozilla.org/releases/mozilla-aurora/raw-file/41fdefd640f368bccdeafe6446d42c0a5ad22797/b2g/config/gaia.json',), kwargs: {}, attempt #1 04:08:48 INFO - rmtree: /builds/slave/test/gaia 04:08:48 INFO - Running command: ['git', 'clone', 'https://git.mozilla.org/releases/gaia.git'] in /builds/slave/test 04:08:48 INFO - Copy/paste: git clone https://git.mozilla.org/releases/gaia.git 04:08:48 INFO - Calling ['git', 'clone', 'https://git.mozilla.org/releases/gaia.git'] with output_timeout 1760 04:08:48 INFO - Cloning into 'gaia'... 04:10:48 INFO - Return code: 0 04:10:48 INFO - Running command: ['git', 'status'] in /builds/slave/test/gaia 04:10:48 INFO - Copy/paste: git status 04:10:48 INFO - # On branch master 04:10:49 INFO - nothing to commit (working directory clean) 04:10:49 INFO - Return code: 0 04:10:49 INFO - Running command: ['git', 'checkout', '2d370fa35c1a0ee2a637e3772c0843586a5f96c9'] in /builds/slave/test/gaia 04:10:49 INFO - Copy/paste: git checkout 2d370fa35c1a0ee2a637e3772c0843586a5f96c9 04:10:51 INFO - Note: checking out '2d370fa35c1a0ee2a637e3772c0843586a5f96c9'. 04:10:51 INFO - You are in 'detached HEAD' state. You can look around, make experimental 04:10:51 INFO - changes and commit them, and you can discard any commits you make in this 04:10:51 INFO - state without impacting any branches by performing another checkout. 04:10:51 INFO - If you want to create a new branch to retain commits you create, you may 04:10:51 INFO - do so (now or later) by using -b with the checkout command again. Example: 04:10:51 INFO - git checkout -b new_branch_name 04:10:51 INFO - HEAD is now at 2d370fa... Merge pull request #31686 from hfiguiere/bug1201705-playlist-tab-test 04:10:51 INFO - Return code: 0 04:10:51 INFO - Running command: ['git', 'rev-parse', 'HEAD'] in /builds/slave/test/gaia 04:10:51 INFO - Copy/paste: git rev-parse HEAD 04:10:51 INFO - 2d370fa35c1a0ee2a637e3772c0843586a5f96c9 04:10:51 INFO - Return code: 0 04:10:51 INFO - Running command: ['git', 'branch'] in /builds/slave/test/gaia 04:10:51 INFO - Copy/paste: git branch 04:10:51 INFO - * (no branch) 04:10:51 INFO - master 04:10:51 INFO - Return code: 0 04:10:51 INFO - Pull has nothing to do! 04:10:51 INFO - Running post-action listener: _resource_record_post_action 04:10:51 INFO - ##### 04:10:51 INFO - ##### Running create-virtualenv step. 04:10:51 INFO - ##### 04:10:51 INFO - Running pre-action listener: _install_mozbase 04:10:51 INFO - Running pre-action listener: _pre_create_virtualenv 04:10:51 INFO - Running pre-action listener: _resource_record_pre_action 04:10:51 INFO - Running main action method: create_virtualenv 04:10:51 INFO - Creating virtualenv /builds/slave/test/build/venv 04:10:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:10:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:10:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:10:51 INFO - Using real prefix '/usr' 04:10:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:10:55 INFO - Installing distribute.............................................................................................................................................................................................done. 04:10:58 INFO - Installing pip.................done. 04:10:58 INFO - Return code: 0 04:10:58 INFO - Installing mozinstall into virtualenv /builds/slave/test/build/venv 04:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:10:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:10:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:10:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozinstall']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:10:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozinstall'] in /builds/slave/test/build 04:10:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozinstall 04:10:58 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:10:58 INFO - 'CCACHE_UMASK': '002', 04:10:58 INFO - 'DISPLAY': ':0', 04:10:58 INFO - 'HOME': '/home/cltbld', 04:10:58 INFO - 'LANG': 'en_US.UTF-8', 04:10:58 INFO - 'LOGNAME': 'cltbld', 04:10:58 INFO - 'MAIL': '/var/mail/cltbld', 04:10:58 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:10:58 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:10:58 INFO - 'MOZ_NO_REMOTE': '1', 04:10:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:10:58 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:10:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:10:58 INFO - 'PWD': '/builds/slave/test', 04:10:58 INFO - 'SHELL': '/bin/bash', 04:10:58 INFO - 'SHLVL': '1', 04:10:58 INFO - 'TERM': 'linux', 04:10:58 INFO - 'TMOUT': '86400', 04:10:58 INFO - 'USER': 'cltbld', 04:10:58 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:10:58 INFO - '_': '/tools/buildbot/bin/python'} 04:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:10:59 INFO - Downloading/unpacking mozinstall 04:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:10:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:10:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:02 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 04:11:02 INFO - Downloading mozInstall-1.12.tar.gz 04:11:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2FmozInstall-1.12.tar.gz 04:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozinstall/setup.py) egg_info for package mozinstall 04:11:02 INFO - Downloading/unpacking mozinfo>=0.7 (from mozinstall) 04:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:03 INFO - Downloading mozinfo-0.8.tar.gz 04:11:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozinfo-0.8.tar.gz 04:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozinfo/setup.py) egg_info for package mozinfo 04:11:03 INFO - Downloading/unpacking mozfile>=1.0 (from mozinstall) 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:03 INFO - Downloading mozfile-1.2.tar.gz 04:11:03 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozfile-1.2.tar.gz 04:11:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozfile/setup.py) egg_info for package mozfile 04:11:03 INFO - Installing collected packages: mozinstall, mozinfo, mozfile 04:11:03 INFO - Running setup.py install for mozinstall 04:11:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:11:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:11:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:11:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:11:03 INFO - Running setup.py install for mozinfo 04:11:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:11:04 INFO - Running setup.py install for mozfile 04:11:04 INFO - Successfully installed mozinstall mozinfo mozfile 04:11:04 INFO - Cleaning up... 04:11:04 INFO - Return code: 0 04:11:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:11:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:11:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:11:04 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:04 INFO - 'CCACHE_UMASK': '002', 04:11:04 INFO - 'DISPLAY': ':0', 04:11:04 INFO - 'HOME': '/home/cltbld', 04:11:04 INFO - 'LANG': 'en_US.UTF-8', 04:11:04 INFO - 'LOGNAME': 'cltbld', 04:11:04 INFO - 'MAIL': '/var/mail/cltbld', 04:11:04 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:04 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:04 INFO - 'MOZ_NO_REMOTE': '1', 04:11:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:04 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:04 INFO - 'PWD': '/builds/slave/test', 04:11:04 INFO - 'SHELL': '/bin/bash', 04:11:04 INFO - 'SHLVL': '1', 04:11:04 INFO - 'TERM': 'linux', 04:11:04 INFO - 'TMOUT': '86400', 04:11:04 INFO - 'USER': 'cltbld', 04:11:04 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:04 INFO - '_': '/tools/buildbot/bin/python'} 04:11:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:04 INFO - Downloading/unpacking psutil>=0.7.1 04:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:08 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 04:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:11:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:11:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:11:08 INFO - Installing collected packages: psutil 04:11:08 INFO - Running setup.py install for psutil 04:11:08 INFO - building 'psutil._psutil_linux' extension 04:11:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:11:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:11:09 INFO - building 'psutil._psutil_posix' extension 04:11:09 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:11:09 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:11:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:11:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:11:09 INFO - Successfully installed psutil 04:11:09 INFO - Cleaning up... 04:11:09 INFO - Return code: 0 04:11:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:11:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:11:09 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:09 INFO - 'CCACHE_UMASK': '002', 04:11:09 INFO - 'DISPLAY': ':0', 04:11:09 INFO - 'HOME': '/home/cltbld', 04:11:09 INFO - 'LANG': 'en_US.UTF-8', 04:11:09 INFO - 'LOGNAME': 'cltbld', 04:11:09 INFO - 'MAIL': '/var/mail/cltbld', 04:11:09 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:09 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:09 INFO - 'MOZ_NO_REMOTE': '1', 04:11:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:09 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:09 INFO - 'PWD': '/builds/slave/test', 04:11:09 INFO - 'SHELL': '/bin/bash', 04:11:09 INFO - 'SHLVL': '1', 04:11:09 INFO - 'TERM': 'linux', 04:11:09 INFO - 'TMOUT': '86400', 04:11:09 INFO - 'USER': 'cltbld', 04:11:09 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:09 INFO - '_': '/tools/buildbot/bin/python'} 04:11:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:11:14 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 04:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:11:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:11:14 INFO - Installing collected packages: mozsystemmonitor 04:11:14 INFO - Running setup.py install for mozsystemmonitor 04:11:14 INFO - Successfully installed mozsystemmonitor 04:11:14 INFO - Cleaning up... 04:11:14 INFO - Return code: 0 04:11:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:11:14 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:14 INFO - 'CCACHE_UMASK': '002', 04:11:14 INFO - 'DISPLAY': ':0', 04:11:14 INFO - 'HOME': '/home/cltbld', 04:11:14 INFO - 'LANG': 'en_US.UTF-8', 04:11:14 INFO - 'LOGNAME': 'cltbld', 04:11:14 INFO - 'MAIL': '/var/mail/cltbld', 04:11:14 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:14 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:14 INFO - 'MOZ_NO_REMOTE': '1', 04:11:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:14 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:14 INFO - 'PWD': '/builds/slave/test', 04:11:14 INFO - 'SHELL': '/bin/bash', 04:11:14 INFO - 'SHLVL': '1', 04:11:14 INFO - 'TERM': 'linux', 04:11:14 INFO - 'TMOUT': '86400', 04:11:14 INFO - 'USER': 'cltbld', 04:11:14 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:14 INFO - '_': '/tools/buildbot/bin/python'} 04:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:14 INFO - Downloading/unpacking blobuploader==1.2.4 04:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:18 INFO - Downloading blobuploader-1.2.4.tar.gz 04:11:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 04:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:11:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:18 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 04:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:11:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:19 INFO - Downloading docopt-0.6.1.tar.gz 04:11:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 04:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:11:19 INFO - Installing collected packages: blobuploader, requests, docopt 04:11:19 INFO - Running setup.py install for blobuploader 04:11:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:11:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:11:20 INFO - Running setup.py install for requests 04:11:20 INFO - Running setup.py install for docopt 04:11:21 INFO - Successfully installed blobuploader requests docopt 04:11:21 INFO - Cleaning up... 04:11:21 INFO - Return code: 0 04:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:21 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:21 INFO - 'CCACHE_UMASK': '002', 04:11:21 INFO - 'DISPLAY': ':0', 04:11:21 INFO - 'HOME': '/home/cltbld', 04:11:21 INFO - 'LANG': 'en_US.UTF-8', 04:11:21 INFO - 'LOGNAME': 'cltbld', 04:11:21 INFO - 'MAIL': '/var/mail/cltbld', 04:11:21 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:21 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:21 INFO - 'MOZ_NO_REMOTE': '1', 04:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:21 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:21 INFO - 'PWD': '/builds/slave/test', 04:11:21 INFO - 'SHELL': '/bin/bash', 04:11:21 INFO - 'SHLVL': '1', 04:11:21 INFO - 'TERM': 'linux', 04:11:21 INFO - 'TMOUT': '86400', 04:11:21 INFO - 'USER': 'cltbld', 04:11:21 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:21 INFO - '_': '/tools/buildbot/bin/python'} 04:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:21 INFO - Running setup.py (path:/tmp/pip-1gcX6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:21 INFO - Running setup.py (path:/tmp/pip-pJjkjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:22 INFO - Running setup.py (path:/tmp/pip-OuopkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:22 INFO - Running setup.py (path:/tmp/pip-ET9Sdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:22 INFO - Running setup.py (path:/tmp/pip-c63gq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:22 INFO - Running setup.py (path:/tmp/pip-MMCKKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:22 INFO - Running setup.py (path:/tmp/pip-ywGXEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:22 INFO - Running setup.py (path:/tmp/pip-FUKO3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:22 INFO - Running setup.py (path:/tmp/pip-jKHDFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:23 INFO - Running setup.py (path:/tmp/pip-GQol5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:23 INFO - Running setup.py (path:/tmp/pip-O6UGDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:23 INFO - Running setup.py (path:/tmp/pip-WZVNpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:23 INFO - Running setup.py (path:/tmp/pip-z_2hTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:23 INFO - Running setup.py (path:/tmp/pip-Afuzjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:23 INFO - Running setup.py (path:/tmp/pip-t8EUCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:23 INFO - Running setup.py (path:/tmp/pip-nSHp9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:24 INFO - Running setup.py (path:/tmp/pip-UVPAI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozhttpd, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:11:24 INFO - Running setup.py install for manifestparser 04:11:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:11:24 INFO - Running setup.py install for mozcrash 04:11:24 INFO - Running setup.py install for mozdebug 04:11:24 INFO - Running setup.py install for mozdevice 04:11:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:11:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:11:25 INFO - Running setup.py install for mozhttpd 04:11:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:11:25 INFO - Running setup.py install for mozleak 04:11:25 INFO - Running setup.py install for mozlog 04:11:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:11:25 INFO - Running setup.py install for moznetwork 04:11:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:11:26 INFO - Running setup.py install for mozprocess 04:11:26 INFO - Running setup.py install for mozprofile 04:11:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:11:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:11:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:11:26 INFO - Running setup.py install for mozrunner 04:11:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:11:26 INFO - Running setup.py install for mozscreenshot 04:11:27 INFO - Running setup.py install for moztest 04:11:27 INFO - Running setup.py install for mozversion 04:11:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:11:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozhttpd mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:11:27 INFO - Cleaning up... 04:11:27 INFO - Return code: 0 04:11:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:27 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:27 INFO - 'CCACHE_UMASK': '002', 04:11:27 INFO - 'DISPLAY': ':0', 04:11:27 INFO - 'HOME': '/home/cltbld', 04:11:27 INFO - 'LANG': 'en_US.UTF-8', 04:11:27 INFO - 'LOGNAME': 'cltbld', 04:11:27 INFO - 'MAIL': '/var/mail/cltbld', 04:11:27 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:27 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:27 INFO - 'MOZ_NO_REMOTE': '1', 04:11:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:27 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:27 INFO - 'PWD': '/builds/slave/test', 04:11:27 INFO - 'SHELL': '/bin/bash', 04:11:27 INFO - 'SHLVL': '1', 04:11:27 INFO - 'TERM': 'linux', 04:11:27 INFO - 'TMOUT': '86400', 04:11:27 INFO - 'USER': 'cltbld', 04:11:27 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:27 INFO - '_': '/tools/buildbot/bin/python'} 04:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:28 INFO - Running setup.py (path:/tmp/pip-TIWLqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:28 INFO - Running setup.py (path:/tmp/pip-Sj9TcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:28 INFO - Running setup.py (path:/tmp/pip-fZISnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:28 INFO - Running setup.py (path:/tmp/pip-dn11gB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:28 INFO - Running setup.py (path:/tmp/pip-eZLQtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:11:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:28 INFO - Running setup.py (path:/tmp/pip-1ntim7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:29 INFO - Running setup.py (path:/tmp/pip-xcdXbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:29 INFO - Running setup.py (path:/tmp/pip-QbfMW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:29 INFO - Running setup.py (path:/tmp/pip-xm__Jy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:29 INFO - Running setup.py (path:/tmp/pip-6w8JCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:29 INFO - Running setup.py (path:/tmp/pip-UM_AUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:29 INFO - Running setup.py (path:/tmp/pip-kMgwNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:11:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:30 INFO - Running setup.py (path:/tmp/pip-UCAFse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:30 INFO - Running setup.py (path:/tmp/pip-pNVkKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:30 INFO - Running setup.py (path:/tmp/pip-S0kh5g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:30 INFO - Running setup.py (path:/tmp/pip-iVfFsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:11:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:30 INFO - Running setup.py (path:/tmp/pip-YyfbAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com has it available 04:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:11:33 INFO - Downloading blessings-1.5.1.tar.gz 04:11:33 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 04:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:11:34 INFO - Installing collected packages: blessings 04:11:34 INFO - Running setup.py install for blessings 04:11:34 INFO - Successfully installed blessings 04:11:34 INFO - Cleaning up... 04:11:34 INFO - Return code: 0 04:11:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:11:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/marionette_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:34 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:34 INFO - 'CCACHE_UMASK': '002', 04:11:34 INFO - 'DISPLAY': ':0', 04:11:34 INFO - 'HOME': '/home/cltbld', 04:11:34 INFO - 'LANG': 'en_US.UTF-8', 04:11:34 INFO - 'LOGNAME': 'cltbld', 04:11:34 INFO - 'MAIL': '/var/mail/cltbld', 04:11:34 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:34 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:34 INFO - 'MOZ_NO_REMOTE': '1', 04:11:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:34 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:34 INFO - 'PWD': '/builds/slave/test', 04:11:34 INFO - 'SHELL': '/bin/bash', 04:11:34 INFO - 'SHLVL': '1', 04:11:34 INFO - 'TERM': 'linux', 04:11:34 INFO - 'TMOUT': '86400', 04:11:34 INFO - 'USER': 'cltbld', 04:11:34 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:34 INFO - '_': '/tools/buildbot/bin/python'} 04:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:35 INFO - Running setup.py (path:/tmp/pip-7WXWAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:35 INFO - Running setup.py (path:/tmp/pip-oeScLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:35 INFO - Running setup.py (path:/tmp/pip-0YZI79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:35 INFO - Running setup.py (path:/tmp/pip-kitwvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:35 INFO - Running setup.py (path:/tmp/pip-E6yRRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:35 INFO - Running setup.py (path:/tmp/pip-B4re7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:11:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:35 INFO - Running setup.py (path:/tmp/pip-Ln_8KI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:36 INFO - Running setup.py (path:/tmp/pip-rWuokD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:36 INFO - Running setup.py (path:/tmp/pip-OZ6eec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:36 INFO - Running setup.py (path:/tmp/pip-Uwq0J9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:36 INFO - Running setup.py (path:/tmp/pip-tkdJKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:36 INFO - Running setup.py (path:/tmp/pip-oHZlZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:36 INFO - Running setup.py (path:/tmp/pip-E_6avR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:11:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:36 INFO - Running setup.py (path:/tmp/pip-1QmV66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:37 INFO - Running setup.py (path:/tmp/pip-ImwfbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:37 INFO - Running setup.py (path:/tmp/pip-hNQxYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:37 INFO - Running setup.py (path:/tmp/pip-eXLUJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/marionette/transport 04:11:37 INFO - Running setup.py (path:/tmp/pip-OhPbQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/transport 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/marionette/driver 04:11:37 INFO - Running setup.py (path:/tmp/pip-t0C6Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/driver 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 04:11:37 INFO - Running setup.py (path:/tmp/pip-VvTjNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 04:11:37 INFO - Unpacking /builds/slave/test/build/tests/marionette 04:11:38 INFO - Running setup.py (path:/tmp/pip-pi1W1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette 04:11:38 INFO - warning: no files found matching '*.js' under directory 'marionette/touch' 04:11:38 INFO - Installing collected packages: marionette-transport, marionette-driver, browsermob-proxy, marionette-client 04:11:38 INFO - Running setup.py install for marionette-transport 04:11:38 INFO - Running setup.py install for marionette-driver 04:11:38 INFO - Running setup.py install for browsermob-proxy 04:11:39 INFO - Running setup.py install for marionette-client 04:11:39 INFO - warning: no files found matching '*.js' under directory 'marionette/touch' 04:11:39 INFO - Installing marionette script to /builds/slave/test/build/venv/bin 04:11:39 INFO - Successfully installed marionette-transport marionette-driver browsermob-proxy marionette-client 04:11:39 INFO - Cleaning up... 04:11:39 INFO - Return code: 0 04:11:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/marionette_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:11:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/marionette_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:11:39 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:39 INFO - 'CCACHE_UMASK': '002', 04:11:39 INFO - 'DISPLAY': ':0', 04:11:39 INFO - 'HOME': '/home/cltbld', 04:11:39 INFO - 'LANG': 'en_US.UTF-8', 04:11:39 INFO - 'LOGNAME': 'cltbld', 04:11:39 INFO - 'MAIL': '/var/mail/cltbld', 04:11:39 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:39 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:39 INFO - 'MOZ_NO_REMOTE': '1', 04:11:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:39 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:39 INFO - 'PWD': '/builds/slave/test', 04:11:39 INFO - 'SHELL': '/bin/bash', 04:11:39 INFO - 'SHLVL': '1', 04:11:39 INFO - 'TERM': 'linux', 04:11:39 INFO - 'TMOUT': '86400', 04:11:39 INFO - 'USER': 'cltbld', 04:11:39 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:39 INFO - '_': '/tools/buildbot/bin/python'} 04:11:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:11:40 INFO - Running setup.py (path:/tmp/pip-6kbUbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:11:40 INFO - Running setup.py (path:/tmp/pip-y52Ysz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:11:40 INFO - Running setup.py (path:/tmp/pip-YyIhc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:11:40 INFO - Running setup.py (path:/tmp/pip-HeRLMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.46 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:11:40 INFO - Running setup.py (path:/tmp/pip-mdxqGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:11:40 INFO - Running setup.py (path:/tmp/pip-2dAOyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:11:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:11:40 INFO - Running setup.py (path:/tmp/pip-zilVjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.8 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:11:41 INFO - Running setup.py (path:/tmp/pip-YQmeYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:11:41 INFO - Running setup.py (path:/tmp/pip-GOpX8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:11:41 INFO - Running setup.py (path:/tmp/pip-pxUEJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:11:41 INFO - Running setup.py (path:/tmp/pip-ULYzQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:11:41 INFO - Running setup.py (path:/tmp/pip-v67wuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:11:41 INFO - Running setup.py (path:/tmp/pip-uoL5p4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:11:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:11:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:11:42 INFO - Running setup.py (path:/tmp/pip-WDK2fi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.10 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:42 INFO - Running setup.py (path:/tmp/pip-Ow0ShT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:11:42 INFO - Running setup.py (path:/tmp/pip-oa24YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:11:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:11:42 INFO - Running setup.py (path:/tmp/pip-UkvEni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:11:42 INFO - Unpacking /builds/slave/test/build/tests/marionette/transport 04:11:42 INFO - Running setup.py (path:/tmp/pip-ClANmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/transport 04:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-transport==0.7 from file:///builds/slave/test/build/tests/marionette/transport in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 2)) 04:11:42 INFO - Unpacking /builds/slave/test/build/tests/marionette/driver 04:11:42 INFO - Running setup.py (path:/tmp/pip-HFunR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/driver 04:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-driver==0.13 from file:///builds/slave/test/build/tests/marionette/driver in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 3)) 04:11:42 INFO - Unpacking /builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 04:11:42 INFO - Running setup.py (path:/tmp/pip-SlvTno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): browsermob-proxy==0.6.0 from file:///builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 4)) 04:11:43 INFO - Unpacking /builds/slave/test/build/tests/marionette 04:11:43 INFO - Running setup.py (path:/tmp/pip-ZauWUl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette 04:11:43 INFO - warning: no files found matching '*.js' under directory 'marionette/touch' 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-client==0.19 from file:///builds/slave/test/build/tests/marionette in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 5)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.46->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): requests>=1.1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from browsermob-proxy==0.6.0->-r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 4)) 04:11:43 INFO - Cleaning up... 04:11:43 INFO - Return code: 0 04:11:43 INFO - Installing luciddream into virtualenv /builds/slave/test/build/venv 04:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:11:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:11:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:11:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub 04:11:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/luciddream/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '/builds/slave/test/build/tests/luciddream']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a161bab0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x7f41a1679b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1dea2a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de6400>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1de80c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1de8530>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'TMOUT': '86400', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'HOME': '/home/cltbld', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'DISPLAY': ':0', 'CCACHE_UMASK': '002', 'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NODE_PATH': '/usr/bin/node', 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 'SHLVL': '1', 'NO_FAIL_ON_TEST_ERRORS': '1', 'MOZ_NO_REMOTE': '1', 'MOZ_HIDE_RESULTS_TABLE': '1', 'MAIL': '/var/mail/cltbld', '_': '/tools/buildbot/bin/python', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'CCACHE_DIR': '/builds/ccache'}}, attempt #1 04:11:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/luciddream/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '/builds/slave/test/build/tests/luciddream'] in /builds/slave/test/build 04:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/luciddream/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.usw2.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub /builds/slave/test/build/tests/luciddream 04:11:43 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:11:43 INFO - 'CCACHE_UMASK': '002', 04:11:43 INFO - 'DISPLAY': ':0', 04:11:43 INFO - 'HOME': '/home/cltbld', 04:11:43 INFO - 'LANG': 'en_US.UTF-8', 04:11:43 INFO - 'LOGNAME': 'cltbld', 04:11:43 INFO - 'MAIL': '/var/mail/cltbld', 04:11:43 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:11:43 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:11:43 INFO - 'MOZ_NO_REMOTE': '1', 04:11:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:11:43 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:11:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:11:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:11:43 INFO - 'PWD': '/builds/slave/test', 04:11:43 INFO - 'SHELL': '/bin/bash', 04:11:43 INFO - 'SHLVL': '1', 04:11:43 INFO - 'TERM': 'linux', 04:11:43 INFO - 'TMOUT': '86400', 04:11:43 INFO - 'USER': 'cltbld', 04:11:43 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:11:43 INFO - '_': '/tools/buildbot/bin/python'} 04:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:11:44 INFO - Unpacking ./tests/luciddream 04:11:44 INFO - Running setup.py (path:/tmp/pip-U1Kxr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/luciddream 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-client>=0.8.5 in ./venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in ./venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/luciddream/requirements.txt (line 2)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-driver>=0.13 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): browsermob-proxy>=0.6.0 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser>=1.1 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.9 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.44 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.21 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.5 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.7 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest>=0.7 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.1 in ./venv/lib/python2.7/site-packages (from marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in ./venv/lib/python2.7/site-packages (from mozlog>=3.0->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 2)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-transport==0.7 in ./venv/lib/python2.7/site-packages (from marionette-driver>=0.13->marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): requests>=1.1.0 in ./venv/lib/python2.7/site-packages (from browsermob-proxy>=0.6.0->marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=0.12 in ./venv/lib/python2.7/site-packages (from mozinfo>=0.8->marionette-client>=0.8.5->-r /builds/slave/test/build/tests/luciddream/requirements.txt (line 1)) 04:11:44 INFO - Installing collected packages: luciddream 04:11:44 INFO - Running setup.py install for luciddream 04:11:44 INFO - Installing runluciddream script to /builds/slave/test/build/venv/bin 04:11:44 INFO - Successfully installed luciddream 04:11:44 INFO - Cleaning up... 04:11:44 INFO - Return code: 0 04:11:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:11:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:44 INFO - Reading from file tmpfile_stdout 04:11:45 INFO - Current package versions: 04:11:45 INFO - argparse == 1.2.1 04:11:45 INFO - blessings == 1.5.1 04:11:45 INFO - blobuploader == 1.2.4 04:11:45 INFO - browsermob-proxy == 0.6.0 04:11:45 INFO - docopt == 0.6.1 04:11:45 INFO - luciddream == 0.1 04:11:45 INFO - manifestparser == 1.1 04:11:45 INFO - marionette-client == 0.19 04:11:45 INFO - marionette-driver == 0.13 04:11:45 INFO - marionette-transport == 0.7 04:11:45 INFO - mozInstall == 1.12 04:11:45 INFO - mozcrash == 0.16 04:11:45 INFO - mozdebug == 0.1 04:11:45 INFO - mozdevice == 0.46 04:11:45 INFO - mozfile == 1.2 04:11:45 INFO - mozhttpd == 0.7 04:11:45 INFO - mozinfo == 0.8 04:11:45 INFO - mozleak == 0.1 04:11:45 INFO - mozlog == 3.0 04:11:45 INFO - moznetwork == 0.27 04:11:45 INFO - mozprocess == 0.22 04:11:45 INFO - mozprofile == 0.27 04:11:45 INFO - mozrunner == 6.10 04:11:45 INFO - mozscreenshot == 0.1 04:11:45 INFO - mozsystemmonitor == 0.0 04:11:45 INFO - moztest == 0.7 04:11:45 INFO - mozversion == 1.4 04:11:45 INFO - psutil == 3.1.1 04:11:45 INFO - requests == 1.2.3 04:11:45 INFO - wsgiref == 0.1.2 04:11:45 INFO - Running post-action listener: _resource_record_post_action 04:11:45 INFO - Running post-action listener: _start_resource_monitoring 04:11:45 INFO - Starting resource monitoring. 04:11:45 INFO - ##### 04:11:45 INFO - ##### Running install step. 04:11:45 INFO - ##### 04:11:45 INFO - Running pre-action listener: _resource_record_pre_action 04:11:45 INFO - Running main action method: install 04:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:11:45 INFO - Reading from file tmpfile_stdout 04:11:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:11:45 INFO - Reading from file tmpfile_stdout 04:11:45 INFO - Output received: 04:11:45 INFO - Usage: mozinstall [options] installer 04:11:45 INFO - Options: 04:11:45 INFO - -h, --help show this help message and exit 04:11:45 INFO - -d DEST, --destination=DEST 04:11:45 INFO - Directory to install application into. [default: 04:11:45 INFO - "/builds/slave/test"] 04:11:45 INFO - --app=APP Application being installed. [default: firefox] 04:11:45 INFO - mkdir: /builds/slave/test/build/application 04:11:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:12:11 INFO - Reading from file tmpfile_stdout 04:12:11 INFO - Output received: 04:12:11 INFO - /builds/slave/test/build/application/firefox/firefox 04:12:11 INFO - Detecting whether we're running mozinstall >=1.0... 04:12:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '--app', 'b2g', '-h'] 04:12:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall --app b2g -h 04:12:12 INFO - Reading from file tmpfile_stdout 04:12:12 INFO - Output received: 04:12:12 INFO - Usage: mozinstall [options] installer 04:12:12 INFO - Options: 04:12:12 INFO - -h, --help show this help message and exit 04:12:12 INFO - -d DEST, --destination=DEST 04:12:12 INFO - Directory to install application into. [default: 04:12:12 INFO - "/builds/slave/test"] 04:12:12 INFO - --app=APP Application being installed. [default: firefox] 04:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '--app', 'b2g', '/builds/slave/test/build/b2g-37.0.multi.linux-i686.tar.bz2', '--destination', '/builds/slave/test/build/b2gdesktop'] 04:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall --app b2g /builds/slave/test/build/b2g-37.0.multi.linux-i686.tar.bz2 --destination /builds/slave/test/build/b2gdesktop 04:12:53 INFO - Reading from file tmpfile_stdout 04:12:53 INFO - Output received: 04:12:53 INFO - /builds/slave/test/build/b2gdesktop/b2g/b2g 04:12:53 INFO - Running post-action listener: _resource_record_post_action 04:12:53 INFO - ##### 04:12:53 INFO - ##### Running run-tests step. 04:12:53 INFO - ##### 04:12:53 INFO - Running pre-action listener: _resource_record_pre_action 04:12:53 INFO - Running main action method: run_tests 04:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:12:53 INFO - trying https://api.pub.build.mozilla.org/tooltool/sha512/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462 04:12:53 INFO - Downloading https://api.pub.build.mozilla.org/tooltool/sha512/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462 to /builds/slave/test/gaia/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462 04:12:53 INFO - retry: Calling _download_file with args: ('https://api.pub.build.mozilla.org/tooltool/sha512/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462', '/builds/slave/test/gaia/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462'), kwargs: {}, attempt #1 04:12:54 INFO - Downloaded 38304247 bytes. 04:12:54 INFO - mkdir: /builds/slave/test/gaia/xulrunner-sdk 04:12:54 INFO - Running command: ['unzip', '-q', '-o', '/builds/slave/test/gaia/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462'] in /builds/slave/test/gaia/xulrunner-sdk 04:12:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/gaia/dc9503b21c87b5a469118746f99e4f41d73888972ce735fa10a80f6d218086da0e3da525d9a4cd8e4ea497ec199fef720e4a525873d77a1af304ac505e076462 04:12:56 INFO - Return code: 0 04:12:56 INFO - Sending environment as make vars because of bug 1028816 04:12:56 INFO - Running command: ['make', 'USE_LOCAL_XULRUNNER_SDK=1', 'XULRUNNER_DIRECTORY=xulrunner-sdk', 'DESKTOP=0', 'DESKTOP_SHIMS=1', 'DEBUG=0', 'NOFTU=0'] in /builds/slave/test/gaia 04:12:56 INFO - Copy/paste: make USE_LOCAL_XULRUNNER_SDK=1 XULRUNNER_DIRECTORY=xulrunner-sdk DESKTOP=0 DESKTOP_SHIMS=1 DEBUG=0 NOFTU=0 04:12:57 INFO - Test SDK directory: xulrunner-sdk 04:12:57 INFO - test -f /builds/slave/test/gaia/xulrunner-sdk/bin/xpcshell 04:13:00 INFO - [rebuild] rebuildAppDirs: ["/builds/slave/test/gaia/apps/email","/builds/slave/test/gaia/apps/verticalhome","/builds/slave/test/gaia/apps/system","/builds/slave/test/gaia/apps/gallery","/builds/slave/test/gaia/apps/clock","/builds/slave/test/gaia/apps/bluetooth","/builds/slave/test/gaia/apps/pdfjs","/builds/slave/test/gaia/apps/operatorvariant","/builds/slave/test/gaia/apps/findmydevice","/builds/slave/test/gaia/apps/callscreen","/builds/slave/test/gaia/apps/settings","/builds/slave/test/gaia/apps/marketplace.firefox.com","/builds/slave/test/gaia/apps/sharedtest","/builds/slave/test/gaia/apps/video","/builds/slave/test/gaia/apps/bookmark","/builds/slave/test/gaia/apps/homescreen","/builds/slave/test/gaia/apps/ftu","/builds/slave/test/gaia/apps/fl","/builds/slave/test/gaia/apps/emergency-call","/builds/slave/test/gaia/apps/sms","/builds/slave/test/gaia/apps/costcontrol","/builds/slave/test/gaia/apps/network-alerts","/builds/slave/test/gaia/apps/download","/builds/slave/test/gaia/apps/fm","/builds/slave/test/gaia/apps/default_theme","/builds/slave/test/gaia/apps/search","/builds/slave/test/gaia/apps/sync","/builds/slave/test/gaia/apps/wallpaper","/builds/slave/test/gaia/apps/music","/builds/slave/test/gaia/apps/ringtones","/builds/slave/test/gaia/apps/privacy-panel","/builds/slave/test/gaia/apps/camera","/builds/slave/test/gaia/apps/calendar","/builds/slave/test/gaia/apps/communications","/builds/slave/test/gaia/apps/keyboard","/builds/slave/test/gaia/apps/collection","/builds/slave/test/gaia/apps/wappush","/builds/slave/test/gaia/hosted_apps/bugzillalite","/builds/slave/test/gaia/hosted_apps/twitter","/builds/slave/test/gaia/hosted_apps/facebook","/builds/slave/test/gaia/dev_apps/marketplace-dev.allizom.org","/builds/slave/test/gaia/dev_apps/in_app_pay_test","/builds/slave/test/gaia/dev_apps/marketplace.allizom.org","/builds/slave/test/gaia/dev_apps/mochitest","/builds/slave/test/gaia/dev_apps/ds-test","/builds/slave/test/gaia/dev_apps/geoloc","/builds/slave/test/gaia/dev_apps/l20n-app","/builds/slave/test/gaia/dev_apps/membuster","/builds/slave/test/gaia/dev_apps/test-ime","/builds/slave/test/gaia/dev_apps/share-receiver","/builds/slave/test/gaia/dev_apps/template","/builds/slave/test/gaia/dev_apps/test-agent","/builds/slave/test/gaia/dev_apps/test-container","/builds/slave/test/gaia/dev_apps/test-iac-publisher","/builds/slave/test/gaia/dev_apps/test-iac-subscriber","/builds/slave/test/gaia/dev_apps/test-otasp","/builds/slave/test/gaia/dev_apps/test-receiver-1","/builds/slave/test/gaia/dev_apps/test-receiver-2","/builds/slave/test/gaia/dev_apps/test-receiver-inline","/builds/slave/test/gaia/dev_apps/test-startup-limit","/builds/slave/test/gaia/dev_apps/test-wappush","/builds/slave/test/gaia/dev_apps/uitest","/builds/slave/test/gaia/dev_apps/uitest-privileged","/builds/slave/test/gaia/dev_apps/contacts-manager","/builds/slave/test/gaia/dev_apps/contacts-ds-provider1","/builds/slave/test/gaia/dev_apps/contacts-ds-provider2","/builds/slave/test/gaia/dev_apps/mobile-wallet","/builds/slave/test/gaia/dev_apps/theme-test-1","/builds/slave/test/gaia/dev_apps/theme-test-2","/builds/slave/test/gaia/dev_apps/theme-test-3","/builds/slave/test/gaia/dev_apps/nfc-api-test","/builds/slave/test/gaia/dev_apps/music-nga","/builds/slave/test/gaia/apps/settings/test/fixtures/addon1","/builds/slave/test/gaia/apps/settings/test/fixtures/addon2","/builds/slave/test/gaia/apps/settings/test/fixtures/addon3","/builds/slave/test/gaia/shared"] 04:13:00 INFO - [svoperapps.js] PROFILE_DIR, GAIA_DISTRIBUTION_DIR, VARIANT_PATH are all required 04:13:00 INFO - [settings.js] Writing settings file: /builds/slave/test/gaia/profile/settings.json 04:13:00 INFO - [settings.js] Writing settings file: /builds/slave/test/gaia/profile/defaults/settings.json 04:13:09 INFO - 2015-10-29 04:13:09: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c0b4 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5f6343c0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5d747370 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x61c56140 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2f 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c42f 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x60ffb8a0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1cc 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1c8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1c8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c14c 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1a8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c0e8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5d776000 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1c8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1c8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c0c4 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x20 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5d776910 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c012390 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c0091c4 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x30 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x60b437d0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c170 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xf5d52ce2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c500 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c174 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c011850 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x12 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c170 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c160 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c160 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c16c 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c8bf2d8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c490 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe31f8c 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5d715014 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c398 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c8bf3c8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c47c 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c3a4 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5d700300 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c158 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c8bf3c8 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c400 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x1c9 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x60b437f4 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xf5d52ce2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0xffe2c1d0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c8bf418 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:09 INFO - 2015-10-29 04:13:09: basic_code_modules.cc:88: INFO: No module at 0x5c8bf2f8 04:13:10 INFO - [app] building email app... 04:13:10 INFO - [app] building verticalhome app... 04:13:10 INFO - [app] building system app... 04:13:10 INFO - [app] building gallery app... 04:13:10 INFO - [app] building clock app... 04:13:10 INFO - [app] building bluetooth app... 04:13:20 INFO - require.js optimize ok 04:13:20 INFO - [PREPROCESSOR] remove file: /builds/slave/test/gaia/build_stage/system/js/sync_manager.js 04:13:20 INFO - [PREPROCESSOR] remove file: /builds/slave/test/gaia/build_stage/system/js/sync_state_machine.js 04:13:20 INFO - [PREPROCESSOR] remove file: /builds/slave/test/gaia/build_stage/system/test/unit/sync_manager_test.js 04:13:20 INFO - [PREPROCESSOR] remove file: /builds/slave/test/gaia/build_stage/system/test/unit/sync_state_machine_test.js 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5aab2813 04:13:23 INFO - 2015-10-29 04:13:23: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5aca75ff 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2e470 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2e470 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d5ec 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c47c 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2e494 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2e268 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2e470 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d501 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d578 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac2e264 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d740 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d740 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x10 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x8 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d728 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d630 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d5d0 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d728 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d614 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d740 04:13:23 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac2e268 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d614 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5c3a6440 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5d7b64e0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2daa8 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x3 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d600 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2db90 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c494 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2db20 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffffff81 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d800 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xb900a8 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2da84 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xfffffff6 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c4a8 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d630 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x8 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5aca7600 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac3d058 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d67c 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac47130 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d68c 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac17178 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c488 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d730 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d728 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d6b8 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d728 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x5d7ac5e0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0xffe2d7bc 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:24 INFO - 2015-10-29 04:13:23: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:25 INFO - [app] building operatorvariant app... 04:13:25 INFO - [app] building findmydevice app... 04:13:26 INFO - [app] building settings app... 04:13:28 INFO - [cmd] /usr/bin/git --git-dir=/builds/slave/test/gaia/.git log -1 --format=%H%n%ct HEAD 04:13:28 INFO - [settings-app-build] Writing git commit information to: /builds/slave/test/gaia/build_stage/settings/resources/gaia_commit.txt 04:13:28 INFO - [operatorvariant] /builds/slave/test/gaia/distribution/variant.json not found. Single variant resources will not be added. 04:13:31 INFO - require.js optimize ok 04:13:33 INFO - [app] building homescreen app... 04:13:33 INFO - [app] building ftu app... 04:13:36 INFO - 2015-10-29 04:13:36: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b350 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x48 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b358 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b35c 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b338 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b3b8 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b338 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d6246ab 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x500 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffffff87 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d73d6c8 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d6248cb 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xa00 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x60107040 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x48 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5c961840 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5de8a7d0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffffff85 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffffff85 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5de8a7d0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5c961840 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5eb54eb0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d7131a0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d624516 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b3a8 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffb8b394 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d6246ab 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5d6244f3 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x4c0 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x1 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5de77720 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffffff88 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5c961840 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffffff88 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0x5de8b320 04:13:36 INFO - 2015-10-29 04:13:36: basic_code_modules.cc:88: INFO: No module at 0xffffff85 04:13:38 INFO - [app] building sms app... 04:13:38 INFO - [app] building costcontrol app... 04:13:40 INFO - 2015-10-29 04:13:40: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xff2 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613ca882 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x177 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x102 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x880 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613ca000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x200a0000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613da000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x7fff 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60172800 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x780 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x2f7 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd556cc 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: stackwalker.cc:125: INFO: Couldn't load symbols for: | 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ab000c0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x3fff 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c70 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60172800 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c24 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c24 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60172800 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c70 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd556cc 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c00 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c24 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c24 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd5575c 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c70 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c70 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c8c 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613fa000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613fb000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1940 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c00 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c70 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1960 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1960 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a8c70 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd55758 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x61470de0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613fa000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1940 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1940 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613fa000 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:40 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60a99c00 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x613fa000 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1000 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x2 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60c46400 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1940 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a1940 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x61470e08 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60bc8770 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a2f00 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x9 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x16938f 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60ce8a40 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd558bc 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x673e5b18 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd55800 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x614a2f00 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd55af8 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x61521820 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd55a20 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xda8590 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd559e0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x10 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x8 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5c0fd888 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac4c400 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd55998 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac2e284 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd558c8 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60c46400 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac2e288 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x673e5b18 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x9 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x61470e08 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x1a4 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd55998 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac2e284 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0xffd558c8 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x60c46400 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5ac2e288 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x673e5b18 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x9 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x61470e08 04:13:41 INFO - 2015-10-29 04:13:40: basic_code_modules.cc:88: INFO: No module at 0x0 04:13:48 INFO - [app] building search app... 04:13:49 INFO - [app] building wallpaper app... 04:13:49 INFO - [app] building music app... 04:13:56 INFO - [app] building privacy-panel app... 04:13:56 INFO - [app] building camera app... 04:13:56 INFO - [app] building calendar app... 04:13:57 INFO - [app] building communications app... 04:13:57 INFO - [app] building keyboard app... 04:13:57 INFO - [app] building collection app... 04:13:57 INFO - [app] building wappush app... 04:14:01 INFO - Will run rjs optimizer... 04:14:02 INFO - [keyboard-load-layouts] The enabled layouts are set to: en, pt-BR, es, de, fr, fr-CA, pl, ko, zh-Hans-Pinyin, en-Dvorak 04:14:02 INFO - [keyboard-load-layouts] The downloadable layouts are set to: ar, pt-BR, bn-Probhat, dz-BT, sk, te, ko, ru, gv, hu, ca, en-Africa, sr-Latn, tr-Q, sq, ta, bs, bg-BDS, nb, pt-PT, wo, ro, fr, tr-F, pl, es, my, af, vi-Qwerty, en-Colemak, gd, el, ga, ff, bn-Avro, mk, en, cs-qwerty, lt, he, eo, th, de, fr-CH, gl, cy, sr-Cyrl, es-Americas, en-GB, hr, lv, nl, sv, uk, it, cs, en-Dvorak, fy, bg-Pho-Ban, fr-Dvorak-bepo, ig, fr-CA, vi-Typewriter, hi, eu, en-Neo, da, vi-Telex, bg-Pho-Trad 04:14:15 INFO - require.js optimize ok 04:14:15 INFO - [cmd] /usr/bin/git --git-dir=/builds/slave/test/gaia/.git log --format=%H HEAD -1 04:14:15 INFO - [privacy-panel-app-build] Last commit: 2d370fa35c1a0ee2a637e3772c0843586a5f96c9 04:14:18 INFO - 2015-10-29 04:14:18: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f8e8c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: stackwalker.cc:125: INFO: Couldn't load symbols for: | 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ab000c0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xbffd 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x3fff 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c70 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5eedb800 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c24 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c24 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5eedb800 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c70 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f8e8c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c00 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c24 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c24 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f8f1c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c70 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c70 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c8c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f109000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f10a000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69c0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c00 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c70 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x63000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69e0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x63000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69e0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb4c70 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f8f18 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ef8ce80 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f109000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69c0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x63000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69c0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f109000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x63000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5e365c00 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f109000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x2 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ee716e0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69c0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efb69c0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ef8cea8 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ee833f0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efac0a0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x9 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x15ec1d 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ee7a280 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f907c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x696afb30 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f9000 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5efac0a0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f92b8 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f054b00 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f91e0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xda8590 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f91a0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x10 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x8 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5c0fd888 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac4c400 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f9158 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac2e284 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f9088 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ee716e0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac2e288 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x696afb30 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x9 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ef8cea8 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1a4 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f9158 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac2e284 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0xff8f9088 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ee716e0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ac2e288 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x696afb30 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x9 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5ef8cea8 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x5f054b00 04:14:18 INFO - 2015-10-29 04:14:18: basic_code_modules.cc:88: INFO: No module at 0x1 04:14:26 INFO - 2015-10-29 04:14:26: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xc86 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cd4d2a 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xcc1b 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x7d2e 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x102 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x3d8 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cc5000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x48590000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cd5000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x7fff 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cc2000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xfc28 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1b69 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c5fc 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: stackwalker.cc:125: INFO: Couldn't load symbols for: | 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ab000c0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xfffd 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x3fff 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe710 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cc2000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a824 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a824 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cc2000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe710 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c5fc 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a800 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a824 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a824 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c68c 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe710 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe710 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe72c 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62ce5000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62ce6000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffcc0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a800 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe710 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x3f000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffce0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x3f000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffce0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62cbe710 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c688 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x600d2950 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62ce5000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffcc0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x3f000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffcc0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62ce5000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x3f000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ec4a800 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x62ce5000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1000 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x2 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63cf45c0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffcc0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dffcc0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x600d2978 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x600a48b0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dfe160 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x9 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x53070f 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x626ecc00 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c7ec 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x69d2ae10 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c700 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63dfe160 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac4c46c 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2ca28 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5e76c9a0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x930897 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c950 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2cb84 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5c0fd888 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac4c460 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c8c8 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac2e284 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c7f8 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x63cf45c0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac2e288 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x69d2ae10 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5233c 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x9 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x600d2978 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x0 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x1a4 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c8c8 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0x5ac2e284 04:14:26 INFO - 2015-10-29 04:14:26: basic_code_modules.cc:88: INFO: No module at 0xffe2c7f8 04:14:31 INFO - 2015-10-29 04:14:31: stackwalker.cc:125: INFO: Couldn't load symbols for: /builds/slave/test/gaia/xulrunner-sdk/bin/libxul.so|E19CE98E888C7216ED7BAE0EED112F6B0 04:14:39 INFO - r.js optimize ok 04:14:44 INFO - [app] building music-nga app... 04:14:49 INFO - [OK] rjs optimize 04:14:59 INFO - Finished: test ui config file: ./dev_apps/test-agent/config.json 04:15:00 INFO - Finished: Generating extensions 04:15:00 INFO - test -d .git && cp tools/pre-commit .git/hooks/pre-commit && chmod +x .git/hooks/pre-commit || true 04:15:00 INFO - [DEBUG_LOG]: LD_LIBRARY_PATH= 04:15:00 INFO - Profile Ready: please run [b2g|firefox] -profile /builds/slave/test/gaia/profile 04:15:00 INFO - Return code: 0 04:15:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/luciddream/luciddream/runluciddream.py', '--startup-timeout=300', '--log-raw=/builds/slave/test/build/luciddream_raw.log', '--log-errorsummary=/builds/slave/test/build/luciddream_errorsummary.log', '--browser-path=/builds/slave/test/build/application/firefox/firefox', '--b2g-desktop-path=/builds/slave/test/build/b2gdesktop/b2g/b2g', '--gaia-profile=/builds/slave/test/gaia/profile', '/builds/slave/test/build/tests/luciddream/example-tests/luciddream.ini'] in /builds/slave/test/build 04:15:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/luciddream/luciddream/runluciddream.py --startup-timeout=300 --log-raw=/builds/slave/test/build/luciddream_raw.log --log-errorsummary=/builds/slave/test/build/luciddream_errorsummary.log --browser-path=/builds/slave/test/build/application/firefox/firefox --b2g-desktop-path=/builds/slave/test/build/b2gdesktop/b2g/b2g --gaia-profile=/builds/slave/test/gaia/profile /builds/slave/test/build/tests/luciddream/example-tests/luciddream.ini 04:15:00 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 04:15:00 INFO - 'CCACHE_UMASK': '002', 04:15:00 INFO - 'DISPLAY': ':0', 04:15:00 INFO - 'HOME': '/home/cltbld', 04:15:00 INFO - 'LANG': 'en_US.UTF-8', 04:15:00 INFO - 'LOGNAME': 'cltbld', 04:15:00 INFO - 'MAIL': '/var/mail/cltbld', 04:15:00 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 04:15:00 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 04:15:00 INFO - 'MOZ_NO_REMOTE': '1', 04:15:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:15:00 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 04:15:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:15:00 INFO - 'PWD': '/builds/slave/test', 04:15:00 INFO - 'SHELL': '/bin/bash', 04:15:00 INFO - 'SHLVL': '1', 04:15:00 INFO - 'TERM': 'linux', 04:15:00 INFO - 'TMOUT': '86400', 04:15:00 INFO - 'USER': 'cltbld', 04:15:00 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871', 04:15:00 INFO - '_': '/tools/buildbot/bin/python'} 04:15:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/luciddream/luciddream/runluciddream.py', '--startup-timeout=300', '--log-raw=/builds/slave/test/build/luciddream_raw.log', '--log-errorsummary=/builds/slave/test/build/luciddream_errorsummary.log', '--browser-path=/builds/slave/test/build/application/firefox/firefox', '--b2g-desktop-path=/builds/slave/test/build/b2gdesktop/b2g/b2g', '--gaia-profile=/builds/slave/test/gaia/profile', '/builds/slave/test/build/tests/luciddream/example-tests/luciddream.ini'] with output_timeout 1000 04:15:21 INFO - starting httpd 04:15:21 INFO - running httpd on http://127.0.0.1:42718/ 04:15:21 INFO - mozversion application_buildid: 20150309002506 04:15:21 INFO - mozversion application_changeset: 91b7aa6a3243 04:15:21 INFO - mozversion application_display_name: B2G 04:15:21 INFO - mozversion application_id: {3c2e2abc-06d4-11e1-ac3b-374f68613e61} 04:15:21 INFO - mozversion application_name: B2G 04:15:21 INFO - mozversion application_remotingname: b2g 04:15:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-b2g37_v2_2 04:15:21 INFO - mozversion application_vendor: Mozilla 04:15:21 INFO - mozversion application_version: 37.0 04:15:21 INFO - mozversion gaia_date: 1425889715 04:15:21 INFO - mozversion platform_buildid: 20150309002506 04:15:21 INFO - mozversion platform_changeset: 91b7aa6a3243 04:15:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-b2g37_v2_2 04:15:21 INFO - mozversion platform_version: 37.0 04:15:21 INFO - SUITE-START | Running 1 tests 04:15:21 INFO - TEST-START | test_sample.py TestSample.test_js 04:15:23 INFO - TEST-PASS | test_sample.py TestSample.test_js | took 1509ms 04:15:23 INFO - TEST-START | test_sample.py TestSample.test_sample 04:15:23 INFO - TEST-PASS | test_sample.py TestSample.test_sample | took 114ms 04:15:23 INFO - SUMMARY 04:15:23 INFO - ------- 04:15:23 INFO - passed: 2 04:15:23 INFO - failed: 0 04:15:23 INFO - todo: 0 04:15:23 INFO - SUITE-END | took 1s 04:15:24 INFO - Return code: 0 04:15:24 INFO - TinderboxPrint: luciddream: 2/0/0 04:15:24 INFO - Luciddream exited with return code 0: success 04:15:24 INFO - # TBPL SUCCESS # 04:15:24 INFO - mkdir: /builds/slave/test/build/logs 04:15:24 INFO - Copying /builds/slave/test/build/luciddream_errorsummary.log to /builds/slave/test/build/logs/luciddream_errorsummary.log 04:15:24 INFO - Copying /builds/slave/test/build/gecko.log to /builds/slave/test/build/logs/gecko.log 04:15:24 INFO - Copying /builds/slave/test/build/luciddream_raw.log to /builds/slave/test/build/logs/luciddream_raw.log 04:15:24 INFO - Copying /builds/slave/test/build/firefox.log to /builds/slave/test/build/logs/firefox.log 04:15:24 INFO - Running post-action listener: _resource_record_post_action 04:15:24 INFO - Running post-run listener: _resource_record_post_run 04:15:25 INFO - Total resource usage - Wall time: 218s; CPU: 100.0%; Read bytes: 221786112; Write bytes: 764280832; Read time: 9448; Write time: 761696 04:15:25 INFO - install - Wall time: 68s; CPU: 100.0%; Read bytes: 0; Write bytes: 155934720; Read time: 0; Write time: 171356 04:15:25 INFO - run-tests - Wall time: 151s; CPU: 100.0%; Read bytes: 221249536; Write bytes: 608272384; Read time: 9116; Write time: 590332 04:15:25 INFO - Running post-run listener: _upload_blobber_files 04:15:25 INFO - Blob upload gear active. 04:15:25 INFO - Preparing to upload files from /builds/slave/test/build/logs. 04:15:25 INFO - Files from /builds/slave/test/build/logs are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'mozilla-aurora', '-d', '/builds/slave/test/build/logs', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b mozilla-aurora -d /builds/slave/test/build/logs --output-manifest /builds/slave/test/build/uploaded_files.json 04:15:25 INFO - (blobuploader) - INFO - Open directory for files ... 04:15:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/logs/luciddream_errorsummary.log ... 04:15:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:26 INFO - (blobuploader) - INFO - TinderboxPrint: luciddream_errorsummary.log: uploaded 04:15:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:26 INFO - (blobuploader) - INFO - Done attempting. 04:15:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/logs/gecko.log ... 04:15:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:27 INFO - (blobuploader) - INFO - TinderboxPrint: gecko.log: uploaded 04:15:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:27 INFO - (blobuploader) - INFO - Done attempting. 04:15:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/logs/luciddream_raw.log ... 04:15:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:28 INFO - (blobuploader) - INFO - TinderboxPrint: luciddream_raw.log: uploaded 04:15:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:28 INFO - (blobuploader) - INFO - Done attempting. 04:15:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/logs/firefox.log ... 04:15:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:15:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:15:30 INFO - (blobuploader) - INFO - TinderboxPrint: firefox.log: uploaded 04:15:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:15:30 INFO - (blobuploader) - INFO - Done attempting. 04:15:30 INFO - (blobuploader) - INFO - Iteration through files over. 04:15:30 INFO - Return code: 0 04:15:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:15:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:15:30 INFO - Setting buildbot property blobber_files to {"firefox.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/440adb9123aab4a520a0c364713368a596d39667ba8536882c7cc5a886f4ebfc108a52fa38ceb53497b230241d401d0f10b179b24547219eb67767a0981ded6b", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/a471cfc6ab7a1c245824acc2fd45a0ec26d2acb750aaa7aaa66fb99ba1383d8a00f3d973960d4ba1fb086c5082ac2a38b56d6b7b7249e745e6a5203a1f64dae4", "luciddream_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/c81ee9bb74978b3577df1f107794056a664a4efb58277b8d2fd9f00b3b0aabdc384c09d36bcca99242bc1c0b8cabc56394258b796496c328207a1d0926ebcf6e", "luciddream_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/0f195d6b8b6f421edbf33b67febc191fd1518faf7d5a2900cfa934d765c451d0247cac8f0b116d5e833eaec68df3308c14a320a1a8a019ba1a5fcc0e04b184c2"} 04:15:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:15:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:15:30 INFO - Contents: 04:15:30 INFO - blobber_files:{"firefox.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/440adb9123aab4a520a0c364713368a596d39667ba8536882c7cc5a886f4ebfc108a52fa38ceb53497b230241d401d0f10b179b24547219eb67767a0981ded6b", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/a471cfc6ab7a1c245824acc2fd45a0ec26d2acb750aaa7aaa66fb99ba1383d8a00f3d973960d4ba1fb086c5082ac2a38b56d6b7b7249e745e6a5203a1f64dae4", "luciddream_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/c81ee9bb74978b3577df1f107794056a664a4efb58277b8d2fd9f00b3b0aabdc384c09d36bcca99242bc1c0b8cabc56394258b796496c328207a1d0926ebcf6e", "luciddream_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/0f195d6b8b6f421edbf33b67febc191fd1518faf7d5a2900cfa934d765c451d0247cac8f0b116d5e833eaec68df3308c14a320a1a8a019ba1a5fcc0e04b184c2"} 04:15:30 INFO - Copying logs to upload dir... 04:15:30 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=415.516842 ========= Finished '/tools/buildbot/bin/python scripts/scripts/luciddream_unittest.py ...' (results: 0, elapsed: 6 mins, 57 secs) (at 2015-10-29 04:15:31.703546) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-10-29 04:15:31.704506) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False blobber_files:{"firefox.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/440adb9123aab4a520a0c364713368a596d39667ba8536882c7cc5a886f4ebfc108a52fa38ceb53497b230241d401d0f10b179b24547219eb67767a0981ded6b", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/a471cfc6ab7a1c245824acc2fd45a0ec26d2acb750aaa7aaa66fb99ba1383d8a00f3d973960d4ba1fb086c5082ac2a38b56d6b7b7249e745e6a5203a1f64dae4", "luciddream_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/c81ee9bb74978b3577df1f107794056a664a4efb58277b8d2fd9f00b3b0aabdc384c09d36bcca99242bc1c0b8cabc56394258b796496c328207a1d0926ebcf6e", "luciddream_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/0f195d6b8b6f421edbf33b67febc191fd1518faf7d5a2900cfa934d765c451d0247cac8f0b116d5e833eaec68df3308c14a320a1a8a019ba1a5fcc0e04b184c2"} build_url:https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.038107 build_url: 'https://queue.taskcluster.net/v1/task/eZRTgdcURW6p-nqY4yFlSQ/artifacts/public/build/firefox-43.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"firefox.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/440adb9123aab4a520a0c364713368a596d39667ba8536882c7cc5a886f4ebfc108a52fa38ceb53497b230241d401d0f10b179b24547219eb67767a0981ded6b", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/a471cfc6ab7a1c245824acc2fd45a0ec26d2acb750aaa7aaa66fb99ba1383d8a00f3d973960d4ba1fb086c5082ac2a38b56d6b7b7249e745e6a5203a1f64dae4", "luciddream_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/c81ee9bb74978b3577df1f107794056a664a4efb58277b8d2fd9f00b3b0aabdc384c09d36bcca99242bc1c0b8cabc56394258b796496c328207a1d0926ebcf6e", "luciddream_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-aurora/sha512/0f195d6b8b6f421edbf33b67febc191fd1518faf7d5a2900cfa934d765c451d0247cac8f0b116d5e833eaec68df3308c14a320a1a8a019ba1a5fcc0e04b184c2"}' ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2015-10-29 04:15:31.787595) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:15:31.788035) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: HOME=/home/cltbld LANG=en_US.UTF-8 LOGNAME=cltbld MAIL=/var/mail/cltbld NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9ca12473fbb1d023794ffd180000023c-1446116604.842206-1402100871 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022194 ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-10-29 04:15:31.858857) ========= ========= Started reboot slave lost (results: 0, elapsed: 5 secs) (at 2015-10-29 04:15:31.859301) ========= ========= Finished reboot slave lost (results: 0, elapsed: 5 secs) (at 2015-10-29 04:15:37.090175) =========