builder: mozilla-inbound_ubuntu64_vm_test_pgo-media-tests slave: tst-linux64-spot-1610 starttime: 1468397617.13 results: warnings (1) buildid: 20160712223105 builduid: 7bf0b8278aab4cdcab51cefaea772f9b revision: 37aed188b67459ab4180adbf018d31d933fdf942 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:37.126586) ========= master: http://buildbot-master52.bb.releng.use1.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:37.127435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:37.127722) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:37.169200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:37.169483) ========= 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-1468397301.813458-1718347284 _=/tools/buildbot/bin/python using PTY: False --2016-07-13 01:13:37-- 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% 9.95M=0.001s 2016-07-13 01:13:37 (9.95 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.811170 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:38.016471) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:38.016757) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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-1468397301.813458-1718347284 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020236 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:38.080911) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-13 01:13:38.081260) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 37aed188b67459ab4180adbf018d31d933fdf942 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 37aed188b67459ab4180adbf018d31d933fdf942 --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-1468397301.813458-1718347284 _=/tools/buildbot/bin/python using PTY: False 2016-07-13 01:13:38,208 truncating revision to first 12 chars 2016-07-13 01:13:38,209 Setting DEBUG logging. 2016-07-13 01:13:38,209 attempt 1/10 2016-07-13 01:13:38,209 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/37aed188b674?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 01:13:40,543 unpacking tar archive at: mozilla-inbound-37aed188b674/testing/mozharness/ program finished with exit code 0 elapsedTime=3.217982 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-07-13 01:13:41.330750) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:41.331033) ========= script_repo_revision: 37aed188b67459ab4180adbf018d31d933fdf942 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:41.331426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:41.331697) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 01:13:41.361907) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/firefox_media_tests_buildbot.py ...' warnings (results: 1, elapsed: 3 mins, 22 secs) (at 2016-07-13 01:13:41.362174) ========= /tools/buildbot/bin/python scripts/scripts/firefox_media_tests_buildbot.py --cfg mediatests/buildbot_posix_config.py --blob-upload-branch mozilla-inbound --download-symbols ondemand in dir /builds/slave/test/. (timeout 1800 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/firefox_media_tests_buildbot.py', '--cfg', 'mediatests/buildbot_posix_config.py', '--blob-upload-branch', 'mozilla-inbound', '--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-1468397301.813458-1718347284 _=/tools/buildbot/bin/python using PTY: False 01:13:41 INFO - MultiFileLogger online at 20160713 01:13:41 in /builds/slave/test 01:13:41 INFO - Run as scripts/scripts/firefox_media_tests_buildbot.py --cfg mediatests/buildbot_posix_config.py --blob-upload-branch mozilla-inbound --download-symbols ondemand 01:13:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:13:41 INFO - {'append_to_log': False, 01:13:41 INFO - 'base_work_dir': '/builds/slave/test', 01:13:41 INFO - 'blob_upload_branch': 'mozilla-inbound', 01:13:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:13:41 INFO - 'buildbot_json_path': 'buildprops.json', 01:13:41 INFO - 'config_files': ('mediatests/buildbot_posix_config.py',), 01:13:41 INFO - 'default_actions': ('clobber', 01:13:41 INFO - 'read-buildbot-config', 01:13:41 INFO - 'download-and-extract', 01:13:41 INFO - 'create-virtualenv', 01:13:41 INFO - 'install', 01:13:41 INFO - 'run-media-tests'), 01:13:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:13:41 INFO - 'download_minidump_stackwalk': True, 01:13:41 INFO - 'download_symbols': 'ondemand', 01:13:41 INFO - 'e10s': False, 01:13:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:13:41 INFO - 'tooltool.py': '/tools/tooltool.py', 01:13:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:13:41 INFO - '/tools/misc-python/virtualenv.py')}, 01:13:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:13:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:13:41 INFO - 'log_level': 'info', 01:13:41 INFO - 'log_to_console': True, 01:13:41 INFO - 'opt_config_files': (), 01:13:41 INFO - 'pip_index': False, 01:13:41 INFO - 'suite_definitions': {'media-tests': {'options': ()}, 01:13:41 INFO - 'media-youtube-tests': {'options': ('%(test_manifest)s',)}}, 01:13:41 INFO - 'test_suite': 'media-tests', 01:13:41 INFO - 'test_timeout': 10000, 01:13:41 INFO - 'virtualenv_path': 'venv', 01:13:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:13:41 INFO - 'work_dir': 'build'} 01:13:41 INFO - ##### 01:13:41 INFO - ##### Running clobber step. 01:13:41 INFO - ##### 01:13:41 INFO - Running pre-action listener: _resource_record_pre_action 01:13:41 INFO - Running main action method: clobber 01:13:41 INFO - rmtree: /builds/slave/test/build 01:13:41 INFO - Running post-action listener: _resource_record_post_action 01:13:41 INFO - ##### 01:13:41 INFO - ##### Finished clobber step (success) 01:13:41 INFO - ##### 01:13:41 INFO - ##### 01:13:41 INFO - ##### Running read-buildbot-config step. 01:13:41 INFO - ##### 01:13:41 INFO - Running pre-action listener: _resource_record_pre_action 01:13:41 INFO - Running main action method: read_buildbot_config 01:13:41 INFO - Using buildbot properties: 01:13:41 INFO - { 01:13:41 INFO - "project": "", 01:13:41 INFO - "product": "firefox", 01:13:41 INFO - "script_repo_revision": "production", 01:13:41 INFO - "scheduler": "tests-mozilla-inbound-ubuntu64_vm-pgo-unittest", 01:13:41 INFO - "repository": "", 01:13:41 INFO - "buildername": "Ubuntu VM 12.04 x64 mozilla-inbound pgo test media-tests", 01:13:41 INFO - "buildid": "20160712223105", 01:13:41 INFO - "pgo_build": "True", 01:13:41 INFO - "basedir": "/builds/slave/test", 01:13:41 INFO - "buildnumber": 2, 01:13:41 INFO - "slavename": "tst-linux64-spot-1610", 01:13:41 INFO - "master": "http://buildbot-master52.bb.releng.use1.mozilla.com:8201/", 01:13:41 INFO - "platform": "linux64", 01:13:41 INFO - "branch": "mozilla-inbound", 01:13:41 INFO - "revision": "37aed188b67459ab4180adbf018d31d933fdf942", 01:13:41 INFO - "repo_path": "integration/mozilla-inbound", 01:13:41 INFO - "moz_repo_path": "", 01:13:41 INFO - "stage_platform": "linux64-pgo", 01:13:41 INFO - "builduid": "7bf0b8278aab4cdcab51cefaea772f9b", 01:13:41 INFO - "slavebuilddir": "test" 01:13:41 INFO - } 01:13:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 01:13:41 INFO - Found a test packages url https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json. 01:13:41 INFO - Running post-action listener: _resource_record_post_action 01:13:41 INFO - ##### 01:13:41 INFO - ##### Finished read-buildbot-config step (success) 01:13:41 INFO - ##### 01:13:41 INFO - ##### 01:13:41 INFO - ##### Running download-and-extract step. 01:13:41 INFO - ##### 01:13:41 INFO - Running pre-action listener: _resource_record_pre_action 01:13:41 INFO - Running main action method: download_and_extract 01:13:41 INFO - mkdir: /builds/slave/test/build/tests 01:13:41 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')]} 01:13:41 INFO - https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 01:13:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:13:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:13:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:13:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 01:13:46 INFO - Downloaded 1361 bytes. 01:13:46 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 01:13:46 INFO - Using the following test package requirements: 01:13:46 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 01:13:46 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:13:46 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 01:13:46 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'jsshell-linux-x86_64.zip'], 01:13:46 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:13:46 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 01:13:46 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:13:46 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:13:46 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:13:46 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 01:13:46 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:13:46 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:13:46 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')]} 01:13:46 INFO - https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:13:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:13:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:13:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 01:13:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:13:50 INFO - Downloaded 24323897 bytes. 01:13:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests', 'config/*', 'external-media-tests/*', 'marionette/*', 'mozbase/*', 'puppeteer/*', 'tools/wptserve/*'] 01:13:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests config/* external-media-tests/* marionette/* mozbase/* puppeteer/* tools/wptserve/* 01:13:51 INFO - Return code: 0 01:13:51 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')]} 01:13:51 INFO - https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:13:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:13:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:13:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:13:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.use1.mozilla.com/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:13:56 INFO - Downloaded 60829433 bytes. 01:13:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:13:56 INFO - mkdir: /builds/slave/test/properties 01:13:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:13:56 INFO - Writing to file /builds/slave/test/properties/build_url 01:13:56 INFO - Contents: 01:13:56 INFO - build_url:https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 01:13:57 INFO - Running post-action listener: _resource_record_post_action 01:13:57 INFO - Running post-action listener: set_extra_try_arguments 01:13:57 INFO - ##### 01:13:57 INFO - ##### Finished download-and-extract step (success) 01:13:57 INFO - ##### 01:13:57 INFO - ##### 01:13:57 INFO - ##### Running create-virtualenv step. 01:13:57 INFO - ##### 01:13:57 INFO - Running pre-action listener: _pre_create_virtualenv 01:13:57 INFO - Running pre-action listener: _resource_record_pre_action 01:13:57 INFO - Running main action method: create_virtualenv 01:13:57 INFO - Creating virtualenv /builds/slave/test/build/venv 01:13:57 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 01:13:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:13:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:13:57 INFO - Using real prefix '/usr' 01:13:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:13:59 INFO - Installing distribute.............................................................................................................................................................................................done. 01:14:07 INFO - Installing pip.................done. 01:14:07 INFO - Return code: 0 01:14:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:14:07 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')]} 01:14:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:07 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')]} 01:14:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15603f0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a30b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, '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-1468397301.813458-1718347284', '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 01:14:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:14:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:14:07 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:14:07 INFO - 'CCACHE_UMASK': '002', 01:14:07 INFO - 'DISPLAY': ':0', 01:14:07 INFO - 'HOME': '/home/cltbld', 01:14:07 INFO - 'LANG': 'en_US.UTF-8', 01:14:07 INFO - 'LOGNAME': 'cltbld', 01:14:07 INFO - 'MAIL': '/var/mail/cltbld', 01:14:07 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:14:07 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:14:07 INFO - 'MOZ_NO_REMOTE': '1', 01:14:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:14:07 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:14:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:14:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:07 INFO - 'PWD': '/builds/slave/test', 01:14:07 INFO - 'SHELL': '/bin/bash', 01:14:07 INFO - 'SHLVL': '1', 01:14:07 INFO - 'TERM': 'linux', 01:14:07 INFO - 'TMOUT': '86400', 01:14:07 INFO - 'USER': 'cltbld', 01:14:07 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:14:07 INFO - '_': '/tools/buildbot/bin/python'} 01:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:08 INFO - Downloading/unpacking psutil>=3.1.1 01:14:08 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 01:14:08 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 01:14:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:08 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 01:14:08 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 01:14:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:14:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:14:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:14:13 INFO - Installing collected packages: psutil 01:14:13 INFO - Running setup.py install for psutil 01:14:13 INFO - building 'psutil._psutil_linux' extension 01:14:13 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 01:14:16 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 01:14:16 INFO - building 'psutil._psutil_posix' extension 01:14:16 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 01:14:16 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 01:14:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:14:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:14:16 INFO - Successfully installed psutil 01:14:16 INFO - Cleaning up... 01:14:17 INFO - Return code: 0 01:14:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:14:17 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')]} 01:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:17 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')]} 01:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15603f0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a30b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, '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-1468397301.813458-1718347284', '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 01:14:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:14:17 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:14:17 INFO - 'CCACHE_UMASK': '002', 01:14:17 INFO - 'DISPLAY': ':0', 01:14:17 INFO - 'HOME': '/home/cltbld', 01:14:17 INFO - 'LANG': 'en_US.UTF-8', 01:14:17 INFO - 'LOGNAME': 'cltbld', 01:14:17 INFO - 'MAIL': '/var/mail/cltbld', 01:14:17 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:14:17 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:14:17 INFO - 'MOZ_NO_REMOTE': '1', 01:14:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:14:17 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:14:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:17 INFO - 'PWD': '/builds/slave/test', 01:14:17 INFO - 'SHELL': '/bin/bash', 01:14:17 INFO - 'SHLVL': '1', 01:14:17 INFO - 'TERM': 'linux', 01:14:17 INFO - 'TMOUT': '86400', 01:14:17 INFO - 'USER': 'cltbld', 01:14:17 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:14:17 INFO - '_': '/tools/buildbot/bin/python'} 01:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:14:17 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 01:14:17 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 01:14:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:17 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 01:14:17 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 01:14:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:14:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:14:22 INFO - Installing collected packages: mozsystemmonitor 01:14:22 INFO - Running setup.py install for mozsystemmonitor 01:14:22 INFO - Successfully installed mozsystemmonitor 01:14:22 INFO - Cleaning up... 01:14:22 INFO - Return code: 0 01:14:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:14:22 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')]} 01:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:22 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')]} 01:14:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15603f0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a30b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, '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-1468397301.813458-1718347284', '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 01:14:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:14:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:14:22 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:14:22 INFO - 'CCACHE_UMASK': '002', 01:14:22 INFO - 'DISPLAY': ':0', 01:14:22 INFO - 'HOME': '/home/cltbld', 01:14:22 INFO - 'LANG': 'en_US.UTF-8', 01:14:22 INFO - 'LOGNAME': 'cltbld', 01:14:22 INFO - 'MAIL': '/var/mail/cltbld', 01:14:22 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:14:22 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:14:22 INFO - 'MOZ_NO_REMOTE': '1', 01:14:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:14:22 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:14:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:14:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:22 INFO - 'PWD': '/builds/slave/test', 01:14:22 INFO - 'SHELL': '/bin/bash', 01:14:22 INFO - 'SHLVL': '1', 01:14:22 INFO - 'TERM': 'linux', 01:14:22 INFO - 'TMOUT': '86400', 01:14:22 INFO - 'USER': 'cltbld', 01:14:22 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:14:22 INFO - '_': '/tools/buildbot/bin/python'} 01:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:23 INFO - Downloading/unpacking jsonschema==2.5.1 01:14:23 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 01:14:23 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 01:14:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:23 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 01:14:23 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 01:14:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:14:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:14:28 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 01:14:28 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 01:14:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:28 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 01:14:28 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 01:14:28 INFO - Downloading functools32-3.2.3-2.tar.gz 01:14:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:14:29 INFO - warning: no files found matching '*.txt' 01:14:29 INFO - no previously-included directories found matching 'build' 01:14:29 INFO - no previously-included directories found matching 'dist' 01:14:29 INFO - no previously-included directories found matching '.git*' 01:14:29 INFO - Installing collected packages: jsonschema, functools32 01:14:29 INFO - Running setup.py install for functools32 01:14:29 INFO - warning: no files found matching '*.txt' 01:14:29 INFO - no previously-included directories found matching 'build' 01:14:29 INFO - no previously-included directories found matching 'dist' 01:14:29 INFO - no previously-included directories found matching '.git*' 01:14:29 INFO - Successfully installed jsonschema functools32 01:14:29 INFO - Cleaning up... 01:14:29 INFO - Return code: 0 01:14:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:14:29 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')]} 01:14:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:29 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')]} 01:14:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 0x16941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15603f0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a30b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, '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-1468397301.813458-1718347284', '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 01:14:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 01:14:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 01:14:29 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:14:29 INFO - 'CCACHE_UMASK': '002', 01:14:29 INFO - 'DISPLAY': ':0', 01:14:29 INFO - 'HOME': '/home/cltbld', 01:14:29 INFO - 'LANG': 'en_US.UTF-8', 01:14:29 INFO - 'LOGNAME': 'cltbld', 01:14:29 INFO - 'MAIL': '/var/mail/cltbld', 01:14:29 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:14:29 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:14:29 INFO - 'MOZ_NO_REMOTE': '1', 01:14:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:14:29 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:14:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:14:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:29 INFO - 'PWD': '/builds/slave/test', 01:14:29 INFO - 'SHELL': '/bin/bash', 01:14:29 INFO - 'SHLVL': '1', 01:14:29 INFO - 'TERM': 'linux', 01:14:29 INFO - 'TMOUT': '86400', 01:14:29 INFO - 'USER': 'cltbld', 01:14:29 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:14:29 INFO - '_': '/tools/buildbot/bin/python'} 01:14:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:29 INFO - Downloading/unpacking blobuploader==1.2.4 01:14:29 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 01:14:29 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 01:14:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:29 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 01:14:29 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 01:14:35 INFO - Downloading blobuploader-1.2.4.tar.gz 01:14:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:14:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:14:35 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 01:14:35 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 01:14:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:35 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 01:14:35 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 01:14:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:14:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:14:36 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 01:14:36 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 01:14:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:36 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 01:14:36 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 01:14:36 INFO - Downloading docopt-0.6.1.tar.gz 01:14:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:14:37 INFO - Installing collected packages: blobuploader, requests, docopt 01:14:37 INFO - Running setup.py install for blobuploader 01:14:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:14:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:14:37 INFO - Running setup.py install for requests 01:14:38 INFO - Running setup.py install for docopt 01:14:38 INFO - Successfully installed blobuploader requests docopt 01:14:38 INFO - Cleaning up... 01:14:38 INFO - Return code: 0 01:14:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:14:38 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')]} 01:14:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:38 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')]} 01:14:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/external-media-tests-requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 0x16941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15603f0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a30b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, '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-1468397301.813458-1718347284', '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 01:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/external-media-tests-requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 01:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/external-media-tests-requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:14:38 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:14:38 INFO - 'CCACHE_UMASK': '002', 01:14:38 INFO - 'DISPLAY': ':0', 01:14:38 INFO - 'HOME': '/home/cltbld', 01:14:38 INFO - 'LANG': 'en_US.UTF-8', 01:14:38 INFO - 'LOGNAME': 'cltbld', 01:14:38 INFO - 'MAIL': '/var/mail/cltbld', 01:14:38 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:14:38 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:14:38 INFO - 'MOZ_NO_REMOTE': '1', 01:14:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:14:38 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:14:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:38 INFO - 'PWD': '/builds/slave/test', 01:14:38 INFO - 'SHELL': '/bin/bash', 01:14:38 INFO - 'SHLVL': '1', 01:14:38 INFO - 'TERM': 'linux', 01:14:38 INFO - 'TMOUT': '86400', 01:14:38 INFO - 'USER': 'cltbld', 01:14:38 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:14:38 INFO - '_': '/tools/buildbot/bin/python'} 01:14:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:14:38 INFO - Running setup.py (path:/tmp/pip-xEVHwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:14:39 INFO - Running setup.py (path:/tmp/pip-YukMSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:14:39 INFO - Running setup.py (path:/tmp/pip-yjg1AR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:14:39 INFO - Running setup.py (path:/tmp/pip-vS9R9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:14:39 INFO - Running setup.py (path:/tmp/pip-kxqIdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:14:39 INFO - Running setup.py (path:/tmp/pip-wrCyon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:14:39 INFO - Running setup.py (path:/tmp/pip-XoMxH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:14:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:14:39 INFO - Running setup.py (path:/tmp/pip-TX2AT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:14:40 INFO - Running setup.py (path:/tmp/pip-4EIC9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:14:40 INFO - Running setup.py (path:/tmp/pip-gBPU5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:14:40 INFO - Running setup.py (path:/tmp/pip-UNTfRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:14:40 INFO - Running setup.py (path:/tmp/pip-oFUlht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:14:40 INFO - Running setup.py (path:/tmp/pip-VqkANf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:14:40 INFO - Running setup.py (path:/tmp/pip-zJmRoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:14:40 INFO - Running setup.py (path:/tmp/pip-0C0RRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:14:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:14:41 INFO - Running setup.py (path:/tmp/pip-pqMHpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:14:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:14:41 INFO - Running setup.py (path:/tmp/pip-w7D5iu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:14:41 INFO - Unpacking /builds/slave/test/build/tests/tools/wptserve 01:14:41 INFO - Running setup.py (path:/tmp/pip-OKUPne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/tools/wptserve 01:14:41 INFO - Unpacking /builds/slave/test/build/tests/marionette/client 01:14:41 INFO - Running setup.py (path:/tmp/pip-FDiyya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/client 01:14:41 INFO - Unpacking /builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 01:14:41 INFO - Running setup.py (path:/tmp/pip-EgTwpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 01:14:41 INFO - Unpacking /builds/slave/test/build/tests/marionette 01:14:42 INFO - Running setup.py (path:/tmp/pip-_ZItti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette 01:14:42 INFO - warning: no files found matching '*.js' under directory 'marionette/touch' 01:14:42 INFO - Unpacking /builds/slave/test/build/tests/puppeteer/firefox 01:14:42 INFO - Running setup.py (path:/tmp/pip-7sltsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/puppeteer/firefox 01:14:42 INFO - Unpacking /builds/slave/test/build/tests/external-media-tests 01:14:42 INFO - Running setup.py (path:/tmp/pip-oNqgCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/external-media-tests 01:14:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion, wptserve, marionette-driver, browsermob-proxy, marionette-client, firefox-puppeteer, external-media-tests 01:14:42 INFO - Running setup.py install for manifestparser 01:14:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:14:42 INFO - Running setup.py install for mozcrash 01:14:42 INFO - Running setup.py install for mozdebug 01:14:43 INFO - Running setup.py install for mozdevice 01:14:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:14:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:14:43 INFO - Running setup.py install for mozfile 01:14:43 INFO - Running setup.py install for mozhttpd 01:14:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:14:43 INFO - Running setup.py install for mozinfo 01:14:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Running setup.py install for mozInstall 01:14:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Running setup.py install for mozleak 01:14:44 INFO - Running setup.py install for mozlog 01:14:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Running setup.py install for moznetwork 01:14:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:14:44 INFO - Running setup.py install for mozprocess 01:14:45 INFO - Running setup.py install for mozprofile 01:14:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:14:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:14:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:14:45 INFO - Running setup.py install for mozrunner 01:14:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:14:45 INFO - Running setup.py install for mozscreenshot 01:14:45 INFO - Running setup.py install for moztest 01:14:46 INFO - Running setup.py install for mozversion 01:14:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:14:46 INFO - Running setup.py install for wptserve 01:14:46 INFO - Running setup.py install for marionette-driver 01:14:46 INFO - Running setup.py install for browsermob-proxy 01:14:46 INFO - Running setup.py install for marionette-client 01:14:47 INFO - warning: no files found matching '*.js' under directory 'marionette/touch' 01:14:47 INFO - Installing marionette script to /builds/slave/test/build/venv/bin 01:14:47 INFO - Running setup.py install for firefox-puppeteer 01:14:47 INFO - Running setup.py install for external-media-tests 01:14:48 INFO - Installing external-media-tests script to /builds/slave/test/build/venv/bin 01:14:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion wptserve marionette-driver browsermob-proxy marionette-client firefox-puppeteer external-media-tests 01:14:48 INFO - Cleaning up... 01:14:48 INFO - Return code: 0 01:14:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:14:48 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')]} 01:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:48 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')]} 01:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub 01:14:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/external-media-tests-requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 0x16941f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15603f0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15a30b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, '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-1468397301.813458-1718347284', '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 01:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/external-media-tests-requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.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 01:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/external-media-tests-requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:14:48 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:14:48 INFO - 'CCACHE_UMASK': '002', 01:14:48 INFO - 'DISPLAY': ':0', 01:14:48 INFO - 'HOME': '/home/cltbld', 01:14:48 INFO - 'LANG': 'en_US.UTF-8', 01:14:48 INFO - 'LOGNAME': 'cltbld', 01:14:48 INFO - 'MAIL': '/var/mail/cltbld', 01:14:48 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:14:48 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:14:48 INFO - 'MOZ_NO_REMOTE': '1', 01:14:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:14:48 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:14:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:14:48 INFO - 'PWD': '/builds/slave/test', 01:14:48 INFO - 'SHELL': '/bin/bash', 01:14:48 INFO - 'SHLVL': '1', 01:14:48 INFO - 'TERM': 'linux', 01:14:48 INFO - 'TMOUT': '86400', 01:14:48 INFO - 'USER': 'cltbld', 01:14:48 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:14:48 INFO - '_': '/tools/buildbot/bin/python'} 01:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:14:48 INFO - Running setup.py (path:/tmp/pip-6vuEl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:14:48 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)) 01:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:14:48 INFO - Running setup.py (path:/tmp/pip-NBMEBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:14:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 01:14:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:14:48 INFO - Running setup.py (path:/tmp/pip-NIUbnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:14:49 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:14:49 INFO - Running setup.py (path:/tmp/pip-X3mhwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:14:49 INFO - Running setup.py (path:/tmp/pip-RJMRna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:14:49 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:14:49 INFO - Running setup.py (path:/tmp/pip-AEtpTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:14:49 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:14:49 INFO - Running setup.py (path:/tmp/pip-hRvbBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:14:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:14:49 INFO - Running setup.py (path:/tmp/pip-_an9M_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:14:49 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:14:49 INFO - Running setup.py (path:/tmp/pip-Dl6lyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:14:49 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)) 01:14:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:14:49 INFO - Running setup.py (path:/tmp/pip-1L2UP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:14:50 INFO - Running setup.py (path:/tmp/pip-Dv6bX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:14:50 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:14:50 INFO - Running setup.py (path:/tmp/pip-XuUXuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:14:50 INFO - Running setup.py (path:/tmp/pip-2GJGWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:14:50 INFO - Running setup.py (path:/tmp/pip-z0mHwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:14:50 INFO - Running setup.py (path:/tmp/pip-Gq9Mc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:14:50 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:14:50 INFO - Running setup.py (path:/tmp/pip-U9Rk5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:14:50 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)) 01:14:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:14:50 INFO - Running setup.py (path:/tmp/pip-PXS9vL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:14:51 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)) 01:14:51 INFO - Unpacking /builds/slave/test/build/tests/tools/wptserve 01:14:51 INFO - Running setup.py (path:/tmp/pip-fYQ1eU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/tools/wptserve 01:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): wptserve==1.4.0 from file:///builds/slave/test/build/tests/tools/wptserve in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 2)) 01:14:51 INFO - Unpacking /builds/slave/test/build/tests/marionette/client 01:14:51 INFO - Running setup.py (path:/tmp/pip-jL1YLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/client 01:14:51 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-driver==2.0.0 from file:///builds/slave/test/build/tests/marionette/client in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/marionette_requirements.txt (line 3)) 01:14:51 INFO - Unpacking /builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 01:14:51 INFO - Running setup.py (path:/tmp/pip-borGDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette/marionette/runner/mixins/browsermob-proxy-py 01:14:51 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)) 01:14:51 INFO - Unpacking /builds/slave/test/build/tests/marionette 01:14:51 INFO - Running setup.py (path:/tmp/pip-HjemDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/marionette 01:14:52 INFO - warning: no files found matching '*.js' under directory 'marionette/touch' 01:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): marionette-client==3.1.0 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)) 01:14:52 INFO - Unpacking /builds/slave/test/build/tests/puppeteer/firefox 01:14:52 INFO - Running setup.py (path:/tmp/pip-x0FmoB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/puppeteer/firefox 01:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): firefox-puppeteer==50.0.0 from file:///builds/slave/test/build/tests/puppeteer/firefox in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/external-media-tests-requirements.txt (line 2)) 01:14:52 INFO - Unpacking /builds/slave/test/build/tests/external-media-tests 01:14:52 INFO - Running setup.py (path:/tmp/pip-P0l96q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/external-media-tests 01:14:52 INFO - Requirement already satisfied (use --upgrade to upgrade): external-media-tests==2.0 from file:///builds/slave/test/build/tests/external-media-tests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/external-media-tests-requirements.txt (line 3)) 01:14:52 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:14:52 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:14:52 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)) 01:14:52 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:14:52 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:14:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:14:52 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 01:14:52 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 01:14:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.use1.mozilla.com has it available 01:14:52 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 01:14:52 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 01:14:57 INFO - Downloading blessings-1.6.tar.gz 01:14:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:14:57 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)) 01:14:57 INFO - Installing collected packages: blessings 01:14:57 INFO - Running setup.py install for blessings 01:14:57 INFO - Successfully installed blessings 01:14:57 INFO - Cleaning up... 01:14:58 INFO - Return code: 0 01:14:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:14:58 INFO - Reading from file tmpfile_stdout 01:14:58 INFO - Current package versions: 01:14:58 INFO - argparse == 1.2.1 01:14:58 INFO - blessings == 1.6 01:14:58 INFO - blobuploader == 1.2.4 01:14:58 INFO - browsermob-proxy == 0.6.0 01:14:58 INFO - docopt == 0.6.1 01:14:58 INFO - external-media-tests == 2.0 01:14:58 INFO - firefox-puppeteer == 50.0.0 01:14:58 INFO - functools32 == 3.2.3-2 01:14:58 INFO - jsonschema == 2.5.1 01:14:58 INFO - manifestparser == 1.1 01:14:58 INFO - marionette-client == 3.1.0 01:14:58 INFO - marionette-driver == 2.0.0 01:14:58 INFO - mozInstall == 1.12 01:14:58 INFO - mozcrash == 0.17 01:14:58 INFO - mozdebug == 0.1 01:14:58 INFO - mozdevice == 0.48 01:14:58 INFO - mozfile == 1.2 01:14:58 INFO - mozhttpd == 0.7 01:14:58 INFO - mozinfo == 0.9 01:14:58 INFO - mozleak == 0.1 01:14:58 INFO - mozlog == 3.2 01:14:58 INFO - moznetwork == 0.27 01:14:58 INFO - mozprocess == 0.23 01:14:58 INFO - mozprofile == 0.28 01:14:58 INFO - mozrunner == 6.12 01:14:58 INFO - mozscreenshot == 0.1 01:14:58 INFO - mozsystemmonitor == 0.3 01:14:58 INFO - moztest == 0.7 01:14:58 INFO - mozversion == 1.4 01:14:58 INFO - psutil == 3.1.1 01:14:58 INFO - requests == 1.2.3 01:14:58 INFO - wptserve == 1.4.0 01:14:58 INFO - wsgiref == 0.1.2 01:14:58 INFO - Running post-action listener: _resource_record_post_action 01:14:58 INFO - Running post-action listener: _start_resource_monitoring 01:14:58 INFO - Starting resource monitoring. 01:14:58 INFO - ##### 01:14:58 INFO - ##### Finished create-virtualenv step (success) 01:14:58 INFO - ##### 01:14:58 INFO - ##### 01:14:58 INFO - ##### Running install step. 01:14:58 INFO - ##### 01:14:58 INFO - Running pre-action listener: _resource_record_pre_action 01:14:58 INFO - Running main action method: install 01:14:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:14:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:14:59 INFO - Reading from file tmpfile_stdout 01:14:59 INFO - Detecting whether we're running mozinstall >=1.0... 01:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:14:59 INFO - Reading from file tmpfile_stdout 01:14:59 INFO - Output received: 01:14:59 INFO - Usage: mozinstall [options] installer 01:14:59 INFO - Options: 01:14:59 INFO - -h, --help show this help message and exit 01:14:59 INFO - -d DEST, --destination=DEST 01:14:59 INFO - Directory to install application into. [default: 01:14:59 INFO - "/builds/slave/test"] 01:14:59 INFO - --app=APP Application being installed. [default: firefox] 01:14:59 INFO - mkdir: /builds/slave/test/build/application 01:14:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:14:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:15:28 INFO - Reading from file tmpfile_stdout 01:15:28 INFO - Output received: 01:15:28 INFO - /builds/slave/test/build/application/firefox/firefox 01:15:28 INFO - Running post-action listener: _resource_record_post_action 01:15:28 INFO - ##### 01:15:28 INFO - ##### Finished install step (success) 01:15:28 INFO - ##### 01:15:28 INFO - ##### 01:15:28 INFO - ##### Running run-media-tests step. 01:15:28 INFO - ##### 01:15:28 INFO - Running pre-action listener: _resource_record_pre_action 01:15:28 INFO - Running main action method: run_media_tests /builds/slave/test/build/venv/lib/python2.7/site-packages/mozrunner/utils.py:20: UserWarning: Module mozprocess was already imported from /builds/slave/test/scripts/mozprocess/__init__.py, but /builds/slave/test/build/venv/lib/python2.7/site-packages is being added to sys.path import pkg_resources 01:15:29 INFO - Minidump tooltool manifest unknown. Determining based upon platform and architecture. 01:15:29 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')]} 01:15:29 INFO - trying https://hg.mozilla.org/mozilla-central/raw-file/default/testing/config/tooltool-manifests/linux64/releng.manifest 01:15:29 INFO - Downloading https://hg.mozilla.org/mozilla-central/raw-file/default/testing/config/tooltool-manifests/linux64/releng.manifest to /builds/slave/test/build/releng.manifest 01:15:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/mozilla-central/raw-file/default/testing/config/tooltool-manifests/linux64/releng.manifest', 'file_name': '/builds/slave/test/build/releng.manifest'}, attempt #1 01:15:30 INFO - Downloaded 259 bytes. 01:15:30 INFO - Minidump filename unknown. Determining based upon platform and architecture. 01:15:30 INFO - grabbing minidump binary from tooltool 01:15:30 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')]} 01:15:30 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/releng.manifest', '-o'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x159ff90>, '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 0x15b5e30>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1576dd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:15:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/releng.manifest', '-o'] in /builds/slave/test/build 01:15:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/releng.manifest -o 01:15:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/releng.manifest', '-o'] with output_timeout 600 01:15:30 INFO - INFO - Attempting to fetch from 'https://api.pub.build.mozilla.org/tooltool/'... 01:15:31 INFO - INFO - File linux64-minidump_stackwalk fetched from https://api.pub.build.mozilla.org/tooltool/ as /builds/slave/test/build/tmpkaxS0t 01:15:31 INFO - INFO - File integrity verified, renaming tmpkaxS0t to linux64-minidump_stackwalk 01:15:31 INFO - Return code: 0 01:15:31 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:15:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/lib/python2.7/site-packages/external_media_harness/runtests.pyc', '--binary', '/builds/slave/test/build/application/firefox/firefox', '--symbols-path', 'https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--disable-e10s', '--gecko-log', '/builds/slave/test/build/blobber_upload_dir/gecko.log', '--log-html', '/builds/slave/test/build/blobber_upload_dir/media_tests.html', '--log-mach', '/builds/slave/test/build/blobber_upload_dir/media_tests_mach.log'] 01:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/lib/python2.7/site-packages/external_media_harness/runtests.pyc --binary /builds/slave/test/build/application/firefox/firefox --symbols-path https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --disable-e10s --gecko-log /builds/slave/test/build/blobber_upload_dir/gecko.log --log-html /builds/slave/test/build/blobber_upload_dir/media_tests.html --log-mach /builds/slave/test/build/blobber_upload_dir/media_tests_mach.log 01:15:31 INFO - Using env: {'CCACHE_DIR': '/builds/ccache', 01:15:31 INFO - 'CCACHE_UMASK': '002', 01:15:31 INFO - 'DISPLAY': ':0', 01:15:31 INFO - 'HOME': '/home/cltbld', 01:15:31 INFO - 'LANG': 'en_US.UTF-8', 01:15:31 INFO - 'LOGNAME': 'cltbld', 01:15:31 INFO - 'MAIL': '/var/mail/cltbld', 01:15:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:15:31 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1', 01:15:31 INFO - 'MOZ_NODE_PATH': '/usr/bin/node', 01:15:31 INFO - 'MOZ_NO_REMOTE': '1', 01:15:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:15:31 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1', 01:15:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:15:31 INFO - 'PWD': '/builds/slave/test', 01:15:31 INFO - 'SHELL': '/bin/bash', 01:15:31 INFO - 'SHLVL': '1', 01:15:31 INFO - 'TERM': 'linux', 01:15:31 INFO - 'TMOUT': '86400', 01:15:31 INFO - 'USER': 'cltbld', 01:15:31 INFO - 'XDG_SESSION_COOKIE': '9ca12473fbb1d023794ffd180000023c-1468397301.813458-1718347284', 01:15:31 INFO - '_': '/tools/buildbot/bin/python'} 01:15:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/lib/python2.7/site-packages/external_media_harness/runtests.pyc', '--binary', '/builds/slave/test/build/application/firefox/firefox', '--symbols-path', 'https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--disable-e10s', '--gecko-log', '/builds/slave/test/build/blobber_upload_dir/gecko.log', '--log-html', '/builds/slave/test/build/blobber_upload_dir/media_tests.html', '--log-mach', '/builds/slave/test/build/blobber_upload_dir/media_tests_mach.log'] with output_timeout 10000 01:15:31 INFO - 0:00.00 LOG: MainThread INFO Using workspace for temporary data: "/builds/slave/test" 01:15:46 INFO - 0:14.51 LOG: MainThread INFO Initial Profile Destination is "/tmp/tmpwhd4jF.mozrunner" 01:15:46 INFO - 0:14.51 LOG: MainThread INFO starting httpd 01:15:46 INFO - 0:14.52 LOG: MainThread INFO running httpd on http://127.0.0.1:36343/ 01:15:46 INFO - 0:14.52 LOG: MainThread INFO mozinfo updated with the following: None 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_buildid: 20160712223105 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_changeset: 37aed188b67459ab4180adbf018d31d933fdf942 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_display_name: Nightly 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_name: Firefox 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_remotingname: firefox 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_vendor: Mozilla 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO application_version: 50.0a1 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO platform_buildid: 20160712223105 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO platform_changeset: 37aed188b67459ab4180adbf018d31d933fdf942 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 01:15:46 INFO - 0:14.54 LOG: MainThread mozversion INFO platform_version: 50.0a1 01:15:46 INFO - 0:14.54 LOG: MainThread INFO running with e10s: False 01:15:46 INFO - 0:14.54 SUITE_START: MainThread 1 01:15:46 INFO - 0:14.57 TEST_START: MainThread test_video_playback.py TestVideoPlayback.test_playback_starts 01:16:47 INFO - 1:15.62 LOG: MainThread INFO No data available about mozMediaSourceObject 01:16:47 ERROR - 1:15.66 TEST_END: MainThread FAIL, expected PASS 01:16:47 INFO - Traceback (most recent call last): 01:16:47 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette_test.py", line 344, in run 01:16:47 INFO - testMethod() 01:16:47 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/external_media_harness/testcase.py", line 169, in test_playback_starts 01:16:47 ERROR - raise self.failureException(e) 01:16:47 ERROR - AssertionError: TimeoutException: Timed out after 45.0 seconds with message: 01:16:47 INFO - condition: 01:16:47 INFO - VideoPuppeteer - test url: https://youtu.be/AbAACm1IQE0: { 01:16:47 INFO - video: None 01:16:47 INFO - } 01:16:52 INFO - 1:21.11 LOG: MainThread WARNING Failed to gather test failure debug. 01:16:52 INFO - Traceback (most recent call last): 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/runner/base.py", line 551, in gather_debug 01:16:52 INFO - rv['source'] = marionette.page_source 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1235, in page_source 01:16:52 INFO - return self._send_message("getPageSource", key="value") 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/decorators.py", line 38, in _ 01:16:52 INFO - return func(*args, **kwargs) 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 698, in _send_message 01:16:52 INFO - self._handle_error(err) 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 731, in _handle_error 01:16:52 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace) 01:16:52 ERROR - NoSuchWindowException: NoSuchWindowException: No such content frame; perhaps the listener was not registered? 01:16:52 INFO - 1:21.14 LOG: MainThread WARNING Failed to gather test failure media debug 01:16:52 INFO - Traceback (most recent call last): 01:16:52 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/external_media_harness/runtests.py", line 73, in gather_media_debug 01:16:52 INFO - debug_lines = marionette.execute_script(debug_script) 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 1591, in execute_script 01:16:52 INFO - rv = self._send_message("executeScript", body, key="value") 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/decorators.py", line 38, in _ 01:16:52 INFO - return func(*args, **kwargs) 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 698, in _send_message 01:16:52 INFO - self._handle_error(err) 01:16:52 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/marionette.py", line 731, in _handle_error 01:16:52 INFO - raise errors.lookup(error)(message, stacktrace=stacktrace) 01:16:52 ERROR - JavascriptException: JavascriptException: TypeError: tabbrowser is undefined 01:16:52 INFO - stacktrace: 01:16:52 INFO - execute_script @runtests.py, line 73 01:16:52 INFO - inline javascript, line 8 01:16:52 INFO - src: "for (var i=0; i < tabbrowser.browsers.length; ++i) {" 01:16:52 INFO - Stack: 01:16:52 INFO - @runtests.py:8:15 01:16:52 INFO - @runtests.py:0:49 01:16:52 INFO - evaluate.sandbox/promise<@chrome://marionette/content/evaluate.js:143:13 01:16:52 INFO - evaluate.sandbox@chrome://marionette/content/evaluate.js:104:17 01:16:52 INFO - GeckoDriver.prototype.execute_@chrome://marionette/content/driver.js:884:29 01:16:52 INFO - GeckoDriver.prototype.executeScript@chrome://marionette/content/driver.js:785:27 01:16:52 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:319:40 01:16:52 INFO - TaskImpl@resource://gre/modules/Task.jsm:280:3 01:16:52 INFO - createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14 01:16:52 INFO - Task_spawn@resource://gre/modules/Task.jsm:168:12 01:16:52 INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:388:16 01:16:52 INFO - TaskImpl_run@resource://gre/modules/Task.jsm:327:13 01:16:52 INFO - TaskImpl@resource://gre/modules/Task.jsm:280:3 01:16:52 INFO - createAsyncFunction/asyncFunction@resource://gre/modules/Task.jsm:254:14 01:16:52 INFO - Task_spawn@resource://gre/modules/Task.jsm:168:12 01:16:52 INFO - Dispatcher.prototype.execute@chrome://marionette/content/dispatcher.js:117:13 01:16:52 INFO - Dispatcher.prototype.onPacket@chrome://marionette/content/dispatcher.js:88:5 01:16:52 INFO - DebuggerTransport.prototype._onJSONObjectReady/<@chrome://marionette/content/server.js -> resource://devtools/shared/transport/transport.js:482:11 01:16:52 INFO - exports.makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:101:14 01:16:52 INFO - exports.makeInfallible/<@resource://gre/modules/commonjs/toolkit/loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:101:14 01:16:52 INFO - openModalWindow@resource://gre/components/nsPrompter.js:365:5 01:16:52 INFO - ModalPrompter.prototype.openPrompt@resource://gre/components/nsPrompter.js:555:9 01:16:52 INFO - ModalPrompter.prototype.confirmEx@resource://gre/components/nsPrompter.js:699:9 01:16:52 INFO - ix@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:1189:24 01:16:52 INFO - ux@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:1194:7 01:16:52 INFO - iy@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:1244:38 01:16:52 INFO - h.Oo@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:5374:87 01:16:52 INFO - CJ@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:2099:69 01:16:52 INFO - tra@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:4253:297 01:16:52 INFO - W8@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:4234:250 01:16:52 INFO - W8.create@https://s.ytimg.com/yts/jsbin/player-en_US-vflL1__zc/base.js:7162:547 01:16:52 INFO - ytplayer.load@https://www.youtube.com/watch?v=AbAACm1IQE0&feature=youtu.be:182:32896 01:16:52 INFO - @https://www.youtube.com/watch?v=AbAACm1IQE0&feature=youtu.be:182:33057 01:16:52 INFO - @https://www.youtube.com/watch?v=AbAACm1IQE0&feature=youtu.be:182:32993 01:16:52 ERROR - 1:21.14 LOG: MainThread ERROR test_end for test_video_playback.py TestVideoPlayback.test_playback_starts logged while not in progress. Logged with data: {"status": "ERROR", "extra": {"class_name": "test_video_playback.TestVideoPlayback", "method_name": "test_playback_starts", "screenshot": "iVBORw0KGgoAAAANSUhEUgAAAuAAAACSCAYAAAAEq3/qAAAgAElEQVR4nO3d938U1d4H8OevudfnUa/Xir17Re96r9fuVakqiCAoCIJKD1U6xBgghA6C0qSXgPQO6bvZTdu0ze6mbZqAfp4fNrPMrlumnCmbfHy93i/J7OzMOd9zZs4nkyX8T1trC4iIiIiIyBz/Y3UDiIiIiIj6EgZwIiIiIiITMYATEREREZmIAZyIiIiIyEQM4EREREREJmIAJyIiIiIyEQM4EREREZGJGMCJiIiIiEzEAE5EREREZCIGcCIiIiIiEzGAkykcDoflbegrjKg1x89eOB6sNftMHP/0xgCuklUTM90vCCXtT/c+2gUDeO/vYzq1Nd31xVrL+2xm/1Odqy+OhdXjb2fp0s5EGMA1DLYVg672nHb7RoEB3PoxsNsx7caq0NHbxyOd2tob26+3z2b3X88a0hvY4RuedKl1uuSiRBjANQye3QYxWVvtct50qFlvwQDe+/tr9/ala1t7Y/vTrc99fQ1hAO8dtVWCAVzD4Cl9miun9PXY7fHOmWgfNedPtG+q46g5lpY2q+2D0vaJOpbIOqh9X7I5qXXMtPZHyfipGVcRC4HeeZxsvqbrGOs9l9L5obQeaq8ptdekiPmutbZqa6e0Xlr7o/U4yeaKqGMqmUtqrgEl10yy7UrmqprX7Hy9mDkH1dRXS3vUtlnrfDSKZQG8paUZF511yD3qxKg1V/Dm4jN4cfavUd5cfBajcy9jzbEyXHHVoqXFukIpuSEoeU3rexPdELW0T8371NzclLZfybm0tl/pe7X0U88cSHTTVtNOpe9XWhO9c1pLe9TWTMkc0DtWatuZTmOcaB8tY6X1nqdnzLXOOyPfZ1S/RPdHT/8SzQNRx0xFbd/0XDda2qe1LqJfs3K9VlN3JfW1Uy4yi+kB3O31YdHuAvxzzkkMXHEBX28qxtK9VVibV4/tp/3YfiYYdtqPtXn1WLq3CpM2FWPg8gt4Ze5JLN5TCE+Nz/RCmbEY6ZloSvcx4lyitosK4GrrbFYdtCxKosKZGeNqVQA34nrQev3YaYz1nEvkea2aU1Zc43rPZcc+G13HVHXQck2Ivm7MPJ/d7utW9ll0e/psAPfWNWLKlmvoP+sYvlx7HauPeLHxRAOyD9Rh4U4v5myvxoyt1ZixpcfWaszZXo2FO73IPlCHjScasOqwF+PW5uPFWXmYuu0qahoCphVKbThwONT9aMyKiWb3CzpRDbWcTzqWnNq2JaK0XUq2qWmn3lrr7Y/WuaL02lB6szTietB6/dhpjPWcS+38UDOuoueUqPku8p6mpHZ66mXVPkasGckYWR+1bRM5d/SOv9H9NmIN0dJn0X3XMgeNZEoA333egxdnHsXna65gzVEvlu2twtQtHkxYW4av1rkxab0HX68vxzcbKqJ8vb4ck9Z78NU6NyasLcO0LR4s31eFnKNefJ57Df0zjmHPxXLD269loiUacDtNNDte0Greq+e4Rl+4Sm8yatop/9qImthhrijpn1H9FXH92H2Mk70uaqFSes+zQ9AU1U4l20XWy6p9jLqPqxkTM9ctpe3Q02ezrxejz6VlXO2Ui8xkaAAPNjVh+uZLeG1eHhbvcWP5vgpMWFeKz3NK8MWaUozLdWJ8risSxL9a58bEdR5MXOeJfD1hbRnG57owLteJsWvC752wrhQr9ldi8R43XpuXh4wfL6O5ucmwfui9oaiZAHZbcOxyQYs+LgO49QFca/+M6m9fC+DJzqV3oRJ1D7Hb/VDPdpH1YgA3bs5paYcZGSGd1mtRfRbd9z4TwP3+JgzLOoXBy08j82AFvt1cgk+zCzBqZSE+W1WEMauL8XlOCcauKcW4NU6My3Xhyxjjcl0YtyYcvL/IKcGY1cX4bFURRq0sxKfZBZi8uQSZByowcNkpjMg6Db/fmBAu8uJK9T7RC6vettnlglZzXCV9ELWPmvaKCGdK2iHqOKKOqXZcRfRZy9jZJYBrHWMt50oW9vUsVlqva6OuXdFjqrZfeuulpQ5K261mzhpxTLXjYdQc0HOfMiOAax1zUeuqmjmmZWztlIvMYkgAb2pqxojvT2Jo5iks2+vGmJzrGJZ1DZ/8cB0jsvMxcmUBRq0qxOjVt4P45zkl+CKnNIq0fczqYoxeXYRRqwoxcmUBRmTn45Mfwscck3MdS/e5MXTFaYzKPiX8SbjWsCOX6H3x9lE60dSeI1UfEu2ndfHR2i81NdRai2R9FbFPsnmRapvSNigdTzPrp2TsRNZOxDxWOhZ2HGO1fdByLq3zQ+3YmHFNij6enutQzTWitD9a54iSthp5TC3zPFXflc5LNftr6Y8drxclxxM1zmrHQk99tbRDyRw0kiEB/Jv15/DeohNYuMeFEdlXMTTzMj76/go+zrqK4T+Eg/inPUF8ROZlDF1wHAMy9uG9qT/j3ck/4t3JP+K9aTswIGMvhn6XhxHfX8HIlQX4tCd4D//hGj7OuoqPvr+CoZmXMSL7KhbuceG9hccxZdN5y4tqNDtMHLswshZ2rbNd22Vk/3p7n/t6f/XUobfVqrf1p7f3y076co3Tse/CA/j2X0vgmLkf3+0qxSfZlzFo+UUMXnEJQ1ZcwoeyID4s8wrey9iH0Yv2YuuRAlwvq0drezcA4I8//kBzqAtF5T5sO1aIzxbuxfuzDmBY5hUM6wneH2ZexpAVlzB4xSUMWn4RI7KvYP6uEjgy9mPXmVLLC8uJlv61sGOd7dgmo/vX2/vc1/urtxa9rV69rT+9vV920pdrnI59FxrAvXWNeO6b3Zi6NR9jcq5iwNLzGLjsAgYtv4DByy9iyIpLGJp5GUOWX8DA2btw4moF/vgDKf/7448/cPSSBwNm7cbQFRfDx1hxCYOXX8Sg5RcwcNkFDFh6HmPWXMXkLdfx3Le7UFvvt7y4nGjpUYt4P7qyW43t2Cb2jzUwoyYi9kkn6d6fdLif9lZ9uc7p2HehAfybdacxZNkJfLslH+8tPov3l5zFB0vPYcAyKYhfxODlF/HhwkOo9rVEAvaNm7/jwNUGzN3hwtebivDdLheOFTTi99+j07mrOoAB8w72BO+L4eC97Dw+WHoO7y85h/cWn8XkLfkYvDQP0zedtry4RERERESxhAVwZ2Udnpm4HdO35WPg8nN4d9Fp/HfxGby35CzeX3IuKogfvOCOCtaLfynDkJ6PqcjN2FaCrt9uRe3704niPwfvJWfx38Vn8O6i0xi4/Bym/5iPpyZug7Oq3vICExERERHJCQvgc7aexQeLjmLcuit4a+FJvLPwFN5ddBrvLj7zpyDeHOqKBOrW9m58sPgMBi0PPyGPdgGrDrmiAri/pePPwbsnfL+z8BTeWngS49ZdwQeLjmDetnOWF5iipeOPiezMzHqqPRfH2r614tgQEVlLSABvbm7CU+O3YMK6S/hg6Sm8ueBXvLXgJN5eeCpuEG+SBXAAGLXqEgYsPR/XpNzzUfv+dvNW3OD99sJTeGvBSby54FcMWHYa49ddxNMTtqCludnyItNt6bLwG9HOdDmmqHOly1jbAQN4erezr/eJiNQTEsDPF5bjpck/Y8KGy3ht/nG8/t0JvPHdr38K4u8sOo13F53GoWv1UZ//HvbDxZ4n5NHeW3IWi7ZHB/CaQCgcuuME7ze++xWvf3cCr80/jq82XEb/b3/CpZJKy4vcF6n9vah2Y0TY1NN3O9STAdw+tVX6HjvMGzPrYqd+pXvtichYQgJ45u7zeGvuPgzPPotX5+XhP/OOxw3iby0Mh/H3l5zBuhMVOHitHtO3FUSekMd6Y+4RXHfXRwXwbWeqwqF74cm4wfs/847j1Xl5+CT7LN6Zuw9Zey5YXuS+qK8tPkb3yw71TOcwZHcM4NbVsS+0hYjsR0gA/3jRLxi85Bg+WPor/j33GF6dewz/kQfx+SfwhiyMv7ngZFQgj2vBSYzPOhQVvoOhbgxYdiYSut/47gRen387eP9nXh5enXsM/557DB8sPYlBS45i2JK9uvun5lcqJdpH7UKo5uadrF0i2q72OPH2F90epefT2h61bVbSJ611sKKeWvqt9f1K57+WcTCizUZcO8nqoHfO8TrU31YRczHeWBt5jSiZt0RkHSEBvP+kDRiedRJvfJeHV2Yfwb/mHMW/e4Jw+Il4Hl6bfzuMh5+M9wTySCi/Hc7fXHAS/5m+A1UNzZHw3fnbTUxYfwWvy0J3OHjn4dV5eZHz/WvOUbwy+wje+C4Pw78/if6TNunun9KbYuy2RDfdVMdJtl3NOUW1Xc1xtPRLS3vU1EpvO/W2T+sxraqnqDmipx2ijiWizWrGVcTcMPq4Rs2bdLkOtbTVqLlt5DXCEE5kb0ICeL/PcjBy1Wm8Mucw/jnrMByzj0SCuBTGX5WFcenJeFQol3l11iFsOnwtEr47um9iytZr0U+6ZU+7pfO8MvsIHLOP4J+zDuNfcw7j05Wn8fDoVbr7p/VGmmqfeE95kh3LrHZp3UfUdpHfrGgNBaLq05vqadW8ssM+RsyfZMcx6rhWzBslbbXqOrSiLXa4PxOR9YQE8PuGZ2HkqtN4KeMgXso4hJdnHeoJ4j1hfE50GJcH8tuh/LZhi36J/CM8/tYujFlzMSpwR4XuOeHQ7ZgdDv8vzzqElzIO4aVZhzBy1WncPyJLd//6QgBPRM1xRG1X0p5471fyNMmssRVxTKvqaeacMXsfrW02Yv4kO46otvI6VH4uO89bI8aKiKwlJoAPy8Tw7FPoP+MAXpx5AP1nHsRLGQfxcrwwHhPIY4O5Y+Z+XCz1AgDqmzoxNPN01H6vzDkSedItD90vZxzCSxkH0X/mwUgbPsk+hfs/WaG7f6JukoneozUA2GHRMiowihorqxdbvfPFinqaOWfssI+a+WJWrUS1ldeh+rrZYU4a0W8ishchAfyhEZkYuuI4+s/cj3/MCAuH4APon/HnMC6RQrncsCX7AQDdN25hSOapqNfk740K3RkH0X9mOPxL5+8/cz+GrDiOhz79Xnf/jAjgSreb0a7eFMDVHosB3Lhj2iGAMIDzOlRbNzvMSQZwot5PSAB/fuxqvLvgIF7OOIDnp+3FC9P34YXp+8JBPPJU/PaT8chHVXpCudyWvILwrxs87Y5+LaPnoyU97+8/83bofnFGOHhL531+2l68nHEA7y44iBfH5+jun9pwnex9agO42sVCTRv0nMfo7WY9fdO6uOkNTHavp4g+65l7WuaxmW1Wcw6toVDrNWnlvNF6LDOvQyPH1IhwLarvDOlE9iIkgA+Z+yP+PXMX/jl7P56d+guenfYLnpu2F8/3BGIpjMsDuUQK5pLLztqefx3zjGx79HukwB0Vuqfvw3PT9uK5aXvx7NRf4Ji9H/+asQMfzd+mu39qFjGHQ9nnJNUcT0n7Ep1T/pqSdiXaV+1CnqhdWmuZrO169lG6SKo9h5Jj2rWeavZPNWe0zj0j2qN1nou4BlO9X34MNf1RW1sj5026XIdK26tlTJWMj6hxUHuNKJ2HRGQOIQF86bY8/GPSBrw6/yCenrIHz0zZg2em/hIdxnsCuTyUvzDj9kdWJNWNLbj1++94KeNA1PYXYsL28z1Pup+bthfPTguf65mpv+CZKXvw9JQ9eHX+Qfxj4jqs+OmE5UXWQ+9Nkzdd68eArBsXEWPH8bcHjgMR9SZCAviZ6048+Vkm3lxwCE9O3oWnJu/GU1N24+kpu8OBfOqecBiXBXLJ8zHh3NfSiVDXjaiQ/bxsf3ngDofucOB+esoePDVlN56avBtPTt6FNxccxmOjluNCYZnlRdaK4cEeWEN7MiOAc+ztg2NBRL2JkADe0tKMh4cvxr8yduOZabvxxLc78cS3u/Dk5F3xA3lPKH+m56n1szKDlh/Hh9//GrXtmYg9kSfcT0/ZHRW4n5y8C098uwtPfLsTz07bjVcyduKJEcvQ2mJ9ka3ERYs1THfxfqyu5KMaHLfeheNJRL2JkADe1tqCGTl78cy4HLyU8Qse+2YHHvtmBx7/Zice/3bnnwJ5JJRHBfPEpKAtD9vywP34tzvx+Dc7I+d9edZePD1uNWatO2B5gYmIiIiI5IQF8ApvLe4ZMhsvz9iBx7/ejkcmbcejX/+ER7/+WRbId4TDciSU3w7nT3y7C4+M34wHR2Wj35g1eGzS9sh2+b6PRwL3jshxH/36Zzz69U94ZNJ2PPHNT3h5xg7cO2QOquvqLS8wEREREZGcsADe1tqCMUu24bExWXhh6k/oN2ELHv5qKx7+6kc8MnFbOJBP+qknLN8O5ZKHv9yITxf/hH3nipG1+wwck1bj0Ynb/rSf9P5HJ4UD9yMTt+Hhr37Ew19tRb8JW/DC1J/w2OhMfLXiZ8uLS0REREQUS1gAb21phqeiGvcOysDTX23A4xO34KEvN+Gh8ZvRb/xm9JuwBf0mbO0Jyz/i4YnbwsF84jY8MnE7Rmf+Avl/oc5u/HPyZjwycXtkv4cjYftH9JsQDtz9xm/GQ+M346EvN+GJiVvx9IT1uHdwBiqrvWhtaba8wEREREREckICeHNTEAF/I3wNDdi491fcNzgDT0/ahH7j1uOBsevx4NgNeHDcBjw4bmM4lMuD+fjN6Dd+C45e9SD2vyW7LqLf+C2R/aSg/dCXm/DguI3hY47dgAfGrke/cevx9MRNuHdQBrbuPwlfQwMC/kY0NwUtLzIRERERkURXAG9taUZTMAB/ow++hnrU1dbAW12FkfPW44Ghc/DkhPV44Itc3D8mF/d/nov7P1+LB75YFzZWCufhgJ5zpOBPAfyL1Xnh8N6z7wNj10fef//na8PHHJOLB77IxZMT1uP+obPxxcJ18FZXoa62Br6GevgbfWgKBvg0nIiIiIhsQVcAv/3k+3b4rij3wOl04u2Jy3Dv0Ll4bFwu7hu9Gvd+1mN0Du4bnYP7xqyJuH9MLh4Ztx57L7rR3N6NuqYQFu68EA7uY3Kj9r1vdA7uHZ0TOd59o1fjsS9z8cDQuXhn0nK4nKWoKPdEhXA+CSciIiIiu9AcwFtbmhEM+NHoa0BDfR1qvNWoqqxAuceNMpcT165fx7/HLcK9g2biodErcd+olbjn0x9wz8hs/H1kNv4+amWPVfj7qFW4V/JZj56v/x7Rs//IbNwzMhv3fPoD7v9sJR4avRL3DpyO18YtxLXr11HmcqLc40ZVZQVqvNVoqK9Do68BwYCfT8GJiIiIyHKGBfCSkmJcz7+OD6dl4c7/TsTfhy3G/SOz8LcRmbj7k0z8bcT3PbIUCu8ffm8m7h+ZhXuHLcH//Xcihs/8AdevXUdJSTEDOBERERHZmiEfQXGXueAsLUVxcREKC/KxbN0O3Pn2l7jrg2n427Al+Nvw5bh7+DLcNWwZ7hq2XKFluHv4Mvxt+HLcM2wp7h4wHXe9PQGZm3agsCAfxcVFcJaWwl3m4kdQiIiIiMi2DPlLmJUV5fC4y+BylqKkpBhFhQU4dfYcPp6+Ane8PhZ3vTcVdw6dj7s/XoK7PlqCOz9ajDs/Woz/+zCatP2uj5bg7o+X4M6h83DXf6fir6+PxSfTv8eZ8+dRVFiAkpJiuJyl8LjLUFlRzr+ESURERES2JfzXENbX1UY+jiI9DZeCeHFRIY6fPIPRc7Jx99vj8L9vfIk73vkaf3l/Ou4YOBt3DJqHOwb3GDQPdwycjb+8Px1/ffdr/PWNsbjr7XH4fE42jp08g+Kiwkjwlp56Sx87qa+r5a8hJCIiIiJbEvoP8TQFA1FBvLbGG3kiXu5xR8J4aUkJigoLsOPAMUxbsQ5vfDEXD70/EX95bQz+8urosNfG4MH3JuKNL+Zi+ooN2LH/GIqLi1BaUhIJ3eUed+SJd22NNyp486k3EREREdmR0H+KXtLcFEQw4O/5aEr4L2nW1dagxluN6qpKVFVWREK5x10Gj7sM7jIXylzOKO4yV+R1KWxXVVaguqoSNd5q1NXWoKG+Dr6GBvgbfQgG/HziTURERES2ZkgAl2ttaY4E8oC/EY2+BvgaGuBrqEd9XS3q62pRV1uD2hpvXHW1NZH9fA318DU0oNEXfsotBW4+6SYiIiKidGF4AE+ktaU5Es6bm4JoCgbikl6X9re6YEREREREelgWwImIiIiI+iIGcCIiIiIiEzGAExERERGZiAG8l3M4HJa3QVSb07Ev6casGqs9jxntUnMOzkXrORyOKL11XHpz34j6MgZwheLd7EUc04x2W107Ee01sx/pVrN067f8PErPqWQ/Pe234zcEdpesBrGvib5/Kr1PpPs4ablW7DIHiCg5BnAF4t1kjFxERO2v9D12CL1WtMVO/bYDkWFY6fsZwNOX0gBuxP3TiABuxzHtCwE81TGSjXWyb+qMeGhGJBIDuAJ2uXgZwK2vZ29mRQC3y1gxgIutmdHBUXQAt2tIs3sAF9G/VPNI6TdwRn/TRyQaA7gCam/isTeN2O3xPtOXaB+lx1faJrXHVdMfve1Vs13p+ZNtV9I+JTXQU3u1/dI7jkrmuNa6KW1/qq+1zjktc1RJG9QEAD3zXc3YKplLRh0zVb+Uzictx040B+XblbYx3jHVtlNJ3dVer3rmpdLjar3HqG1jsnFIVfdkryu5/tXMVyIrMIArpHYRV/Ka2huG0oU81Wt6j6vnmCLOp+ZGmuy9WsYl3jzQskCoHUclddDaVrXH1jIP9RxXy5xTU38t46m2r1rGTu359M4XtedRM15qA76WUKalbnq/URA1Flrfo3cemlU/PdcqAzj1VgzgKqkJNale1xsGlbZXxLlFHVNvW9UeW3QAV7JN1DdKImqudVs6BPBk7zfim0e181tpADfiejLiulezX7Jjaz2+lraL+oZCD61t0jIv9cwjvfctrfXTc++TS/UeBnCyGwZwjfQ8VVBzjHQN4NLXehdzvQs5A7i2wKknKOsNv2oWT70BXMS5YtseLxSkqmciStqUaH89x0w2F0XPZ6010zq+WtushdYgqHVeppqH8teVzN9U91k993wtc0XNNjX9ILICA7hGDOD6t4t4r5pjK12cGcC11U3tedXUyu4BXE2dRdVT7/52CeBK90m3AC76fijyHqC3VqLmkNa5ouUbGwZwshsGcI0YwJVvF1UjvcdmAI+/zYgArvR9SmvCAN73AriWmjGAi7/GjJoDeuaKnn4S2QUDuAJ6Qkyy19TeOEUGO9EBXM1NVES/RPTF7FCrZ6FUcg6R3yzoaaeIOaalXqK+odUyvnpDgqjAKuKYekOr3m+84u2XbgFcz3iKnJdqz6X3HpToNdHfrIm8toiswgCukMOh/dc/KQ0Das+R6vhKz53ouMnOl6qtyV7XG8DVHFvNsRKNi5JjKamB0jmhtK562qVk7mipm55xSfV+JbVRez4lY5BqP61tFDFfUtE6B/WcQ+s+auagkjom6r+WsRFZ91Q1ETUv1V6jWuaM3utfSd313itFjSmRaAzgNpFONwrRbRUVBOxeXzu0QW2bRLXZjDFOhzbZcQ6QvaTTHEmnthLZDQO4TaTTjcyMYNzbwred2qG0PUaHbytrYkWb7Db+ZD/pNkfSrb1EdsIAbhPpdCNLlwBu1Y8glf7I22pKP1phxHmsromZAdzqvuptdzrMZbP7b1Rt0qW2fWkeEBmFAZyIiIiIyEQM4EREREREJmIAJyIiIiIyEQM4EREREZGJGMCJiIiIiEzEAE5EREREZCIGcCIiIiIiEzGAExERERGZiAFcgWAwgECgEf7GBkUCgUYEgwHL201ERERE9sMAnkJT0A+/rwFnfj2OrRvWYk12ZlJbN67FmZPH0ehrQFPQb3n7iYiIiMheGMBTCDQ2oOD6FVy6cAY1NV74/X74fL64/H4/amq8uHT+DArzryLQ2GB5+83+54L5zxMTERERJccAnoK/sQEnjh6E3+dDMBhEe3s7Ojs742pvb0cwGITP14ATxw7CHxPAHQ5HXEa2nwGcfSYiIiJ7YQBPwd/ow+H9exAIBNDZ2Ymurq6kOjo6EAgEcHj/HvgbfVHHShTUjAxwscfubYHfDvpin4mIiEg7BvAUAv5wAA8Gg+ju7lYkGAyGQ7tfWQBP9ZpW8Y7JAE5ERERkLQbwFII6AnhQRQBP9Hqyj6po+TiL0v1T7ZfodTVP3JMdQ037RPQ5VS1TvaZnTIiIiKhvYQBPIRjw4/D+PWhubsZvv/2mSHNzcziAB6J/C4raAK72ay3nUCpZyNayX6L+aOmj2j6JrmOqPjOEExERkRwDeApNwfDnuVtaWnDjxg1FWlpacHj/HjTF/C5wraFOzz5qXtPbNvlrSp9Mq90m4nP0Io6nJoDrqTsRERH1PgzgKTQ3hT9O0traips3byrS2toafmreFIw6VjoFcKUfyUj0PqXnULstXrvUfpxGSw2VHoMBnIiIiFJhAE+hpbkJh/fvQVtbm+IA3tbWFn5q3twUdax0CeBqPkaR6OMcZj8B13oOrXVmACciIiKtGMBTaG0Nf5yko6MDN2/exK1bt5K6efMmOjo6ep6aRx9LTTBOlwCerE1aPo/OAE5ERES9HQN4CqG2Nhw/chBtrS3o6urCrVu38Pvvv8d169YtdHV1obWlGSeOHkSorS3qWGrDWapQbvcArqUtSrZp/by3muMxgBMREZFRGMBTaG8PoTD/KpzFBQiF2tDV1ZXwX8Ls6upCKNSK0uICFOVfQ3v7nwO4ms8ux74n3muJ3qP1mMnamui1VOdW004120R8BlzpPvwMOBEREYnCAJ5Ce6gNobY2eFyluHzhLM6fOZnU5Qtn4Slzoq2tFR3tIcvbT9owMBMREZFRGMAV6GhvR3d3F37r7laku7sLHe3tlrebtGMAJyIiIqMwgJsk0cdP1H4khcwZF6vbRURERL0XAzgRERERkYkYwImIiIiITMQATkRERERkIgZwIiIiIiITMYATEREREZmIAZyIiIiIyEQM4EREREREJmIAJyIiIiIyEQM4EREREZGJGC0Wf9kAAAp0SURBVMCJiIhInZZmtHrL0VZWgFbnNbSVXiFBWp3XwnX1lqOtpdn6sSZDaA7grS3NKMjPR15eHg4cOEAGycvLQ0F+Plp5ERIRkR20NKHFXQTP3o0oW/EVPPNHoGLecBLEM38EXCsmwr1nA1rKCtHW0qRqfJjP0iOTaQ7g+dev49y5swgGg7hx4wZu3bpFgt24cQOBQABnz55Bfv5162+6RETU57XWVMC9dxMqMycgWHQJN7q7LF8ve5Mb3V0IFl1E5YrxKNuzAW01FarGh/nMoHERnMk0B/DDhw8hEAigs7MTTU1NCAaDCQUCgZT79BVSHaT/BwKBqK9j9+3s7EQgEMDhw4csv+kSERG1lRXAtWwCgsWXmQEMXPuDRZfgXDYerWUFqsaH+czYcRGVyTQH8N27d6OrqyvSYL/fH9HY2Bj1Z+lr+fa+Sl6PePWJrVEgEEBXVxd2795t/U2XiIio9Ao8c4ejqz3EDGDk2t8egnvucLQ5r6oaH+Yzg8dFUCbTFMBDba3YuXMnurq60NjYCJ/PF9HY2Ij6+no0NDRo5nA4dL0/ncTWyufzRf4v1bOrqws7d+5EqK3V+hsvERH1bc6rcM/+yLAM0BcoXfvdsz9SFcCNzme9nZmZTFsAb23Bzz//jI6ODvh8Pjgcjrjq6upQW1sb+b/051Sk9/YGsX2R6iDVoqamJqoutbW1qK+vj/D5fOjo6MDPP/+MkNU3XSIiotIrcM/+KJIB5MFFvu5La2BsLugt1PRH69rvnv1R+LejKByb2HyWbGyS5TMrx83M81mZyTQH8O3btyMUCqG+vh4OhwM1NTWRhnu9Xni93sh2r9eL6urqyPZUHA6H4n3tLlFf5PWQ/1ley7q6OtTX1yMUCoXrbfVNl4iIqPQKymZ9GMkA9fX1ijOAfE1M97VebfsdDkfCtV+qX+zaXzbrQ9UBXJ7PEo2NdM7YdiTqlxFjleiYVswLteMiIpNp+wx4Wyu2bduGtra2yHcr8oZXVVVFOByOyJ8rKysVcTgcivdNV1I9KioqUFVVFamb/AKVBrytra2n3vwIChERWcx5NRwMezKAPNjJM4C0lifKAH1hrY/tb6K1X/pz7NpfNutDdZ8Bj8lnicZGakfs2Jg5JnYaf7XjIiKTaf4M+NatW9HS0oKampqoSVVZWYny8vLI/ysqKuBwOFBRUYGKigqUl5ejvLw86kcb0rZ4ryXbJ3Zbou1KjpdoX6WvJzqHkn2kekm1kqupqUFLSwu2bt3Kz4ATEZH1nFfhyhgayQBerzduBpCv/bEZQO16q2ddt3p9l2eh2NeltV8eBKurqyNrvytjqOrPgMvzWaKxkdokp6SGIscnWb3NyIPxahCbyRKNi4hMpjmAb968GU1NTZEfF0iNraiogMfjgcfjgdvthsfjgcPhiHxdVlYGh8OBsrKyiFRfK91H67FSbVfyupJjOhwOuN3uSF2k2pSVlcHj8UQmh3zQvV4vmpqasHnzZgZwIiKyXk8AlzKAtF7FywBS2JFnAC1rqp613az1PdnrUi3ktZGv/VIYjF37tQRweT5LNjbSn+VjlaxGosdHRF315MF4mUzpuIjIZNoCeKgNGzduRDAYjPyYSWqox+OBy+WC2+2Gy+WK6rTL5YLD4YDL5foT+XYz91GyPdVrWtojTQCn0xmZBFKtpIGvqKhAdXU1gsEgNm7ciFCozfobLxER9W3Oa3DOHBLJANIT1UQZQFrbYtdJPWu0ndf3ZPvI136pTlJAj7f2O2cOQZvzmuKxic1nSsYmVT4zsh522UftuIjIZJoCeHuoDevXr4ff74/8mEn6LkLqgJzD4UBpaWnkz4nI9489Rux2UfvEey3e68neo6U9paWlEfEuUGnQKysr4ff7sX79erQzgBMRkcVCPQFcygDl5eVJM4C01uldo9NlfU+2j3ztl8Ke/MFc7NrvnDkEIRUBPDafaR0bs+ph5j7JsqfacRGRyTQH8LVr18Ln80U+N+Ryuf7UiZKSEpSUlMDhcET9ubi4OKlE+8i3i9pHaRtSvUdNe6RayGsSewFI34FVVFTA5/Nh7dq1DOBERGS5kPMaSmcMjmQA6clqogwQu95pXaPTYX1PtY+8FsXFxZG1Xx785Gt/6YzBqgO4PJ9pHRu7ZDSj94mXyZSMi4hMpjGAh5Cbm4uGhobI55jlgyt1qKioKKrT0tdFRUVJJdpHvl3UPkrbkeo9atsj1aSwsDByUUqTRD7gHo8HDQ0NyM3NRXsoZPmNl4iI+jYpgEsZQHqyGi8DyIOO3jU6Xdb3ZPvI1/6ioqLI2l9SUhJ37VcfwKPzmZqxsWNGM3qfeJlMybiIyGTaAnh7CDk5Oairq4t8cF/6jkoaSClMFhYWRjpUVFQUCZzS9njiva51m9L3KWlHsvclek2+PXYCyLfJb07y777cbjfq6uqQk5OD9nYGcCIislbIdQ0l0wdFMoDL5VKcAWLXRK3rr5613aj1Pdnr8hAo3yavV7y1v2T6IIRc1xSPTWw+SzQ2se2Qf60lWymtp5LxV7uP1jbL+652XERkMk0BvLMjhFWrVqGmpibqL1fEKioqQkFBQVzx9lfyWqrjqD2X3mPJtyfaP1U7Yi8A+etlZWWoqanBqlWr0NnBAE5ERNbqKLuG4mkDIxlAerIaL9ilWldFvJYqJ1i5vic7R+zaL3/iKq39xdMGoqPsmuKxic1nycZGZL2S1SbZ9lT1VnockXlQybiIyGSaAnhHezuys7Ph9XojP9qIHWCpE9Kf7STVhWrmeRN9NyYNuNPphNfrRXZ2Njra2y2/8RIRUd/W7rqO4mkDLc0AVq3jotqjdO0vnjYQ7a7riscm3fOZ1czMZNqegHd2ICsrC5WVlVE/2oj9DktqvPxrq4ur5UIRed5kPxKLHeji4mI4nU5UVlYiKysLnZ0dlt94iYiob+vwFKBo6kBUlXssywDpFsC1rP1V5R4UTh2IDk+B4rFJ93xmNiszmaYA3tXZgTVr1qCwsBAejyfqg/3ygVbyt2mNlOijMVa2J9722FrJ/xaux+NBQUEB1qxZgy4GcCIislhnZQkuz/4Uzl8PWpYBrFzLRbUn1drvPHEAl+eMREdlieKxSZd8ZmdmZTLNT8CPH8/D9u3bUVCQH/WvKZE4brcbBQX52L5tG06cOM4n4EREZLkOnxdXt2SjaP4olB7fh3J3meXrZa9S5oLzxD4Uzx+Faz9mo8PnVTw2zGfGEZ3JNH8GvL09hOPH87By5UosW7aMDLJy5UocP56H9vYQPwNORESW6wi1ob3KiatbsnBp1ifInzIAhVM+IEHypwzAxVkjcGVzFtqrnOhQ8fummc/SJ5NpCuBtrS3o6GhHd1cXurvJcF1d6Ohg+CYiInvoaA+hu7EOnVWl6CwvRqenkEQpL0ZnVSm6G+vQ0a7+H3thPkuPTKY5gBMRERERkXoM4EREREREJmIAJyIiIiIyEQM4EREREZGJGMCJiIiIiEzEAE5EREREZCIGcCIiIiIiEzGAExERERGZiAGciIiIiMhEDOBERERERCZiACciIiIiMhEDOBERERGRif4fjL80JqiO6uEAAAAASUVORK5CYII="}, "expected": "PASS", "test": "test_video_playback.py TestVideoPlayback.test_playback_starts", "message": "TimeoutException: TimeoutException: Timed out after 5.1 seconds with message: Cannot get window type for chrome window handle \"15\"", "stack": "Traceback (most recent call last):\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette_test.py\", line 374, in run\n self.tearDown()\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/testcases/base.py\", line 117, in tearDown\n self._check_and_fix_leaked_handles()\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/testcases/base.py\", line 62, in _check_and_fix_leaked_handles\n self.windows.close_all([self.browser])\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py\", line 93, in close_all\n self.close(handle)\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py\", line 72, in close\n self.switch_to(handle)\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py\", line 202, in switch_to\n return self.create_window_instance(target_handle)\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py\", line 112, in create_window_instance\n message='Cannot get window type for chrome window handle \"%s\"' % handle\n File \"/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/wait.py\", line 144, in until\n cause=last_exc)\n"} 01:16:52 INFO - 1:21.20 TEST_START: MainThread test_video_playback.py TestVideoPlayback.test_video_playback_partial 01:16:58 INFO - 1:26.73 LOG: MainThread INFO No data available about mozMediaSourceObject 01:16:58 ERROR - 1:26.73 TEST_END: MainThread ERROR, expected PASS 01:16:58 INFO - Traceback (most recent call last): 01:16:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette/marionette_test.py", line 325, in run 01:16:58 INFO - self.setUp() 01:16:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/testcases/base.py", line 103, in setUp 01:16:58 INFO - self.browser.focus() 01:16:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py", line 319, in focus 01:16:58 INFO - return self._windows.focus(self.handle) 01:16:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/firefox_puppeteer/ui/windows.py", line 149, in focus 01:16:58 INFO - message='Focus has not been set to chrome window handle "%s".' % handle) 01:16:58 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/marionette_driver/wait.py", line 144, in until 01:16:58 INFO - cause=last_exc) 01:16:58 ERROR - TimeoutException: TimeoutException: Timed out after 5.1 seconds with message: Focus has not been set to chrome window handle "3". 01:16:58 INFO - 1:26.75 LOG: MainThread INFO START LOG: 01:16:58 INFO - 1:26.75 LOG: MainThread INFO INFO TEST-START: /builds/slave/test/build/venv/local/lib/python2.7/site-packages/external_media_tests/playback/test_video_playback.py:test_playback_starts Wed Jul 13 2016 01:15:46 GMT-0700 (PDT) 01:16:58 INFO - 1:26.75 LOG: MainThread INFO INFO URL: https://youtu.be/AbAACm1IQE0 Wed Jul 13 2016 01:16:02 GMT-0700 (PDT) 01:16:58 INFO - 1:26.75 LOG: MainThread INFO INFO TEST-END: /builds/slave/test/build/venv/local/lib/python2.7/site-packages/external_media_tests/playback/test_video_playback.py:test_playback_starts Wed Jul 13 2016 01:16:52 GMT-0700 (PDT) 01:16:58 INFO - 1:26.75 LOG: MainThread INFO END LOG: 01:16:58 INFO - 1:26.75 LOG: MainThread INFO 01:16:58 INFO - SUMMARY 01:16:58 INFO - ------- 01:16:58 INFO - 1:26.75 LOG: MainThread INFO passed: 0 01:16:58 INFO - 1:26.75 LOG: MainThread INFO failed: 3 01:16:58 INFO - 1:26.75 LOG: MainThread INFO todo: 0 01:16:58 INFO - 1:26.75 LOG: MainThread INFO 01:16:58 ERROR - FAILED TESTS 01:16:58 INFO - ------- 01:16:58 INFO - 1:26.75 LOG: MainThread INFO test_video_playback.py test_video_playback.TestVideoPlayback.test_playback_starts 01:16:58 INFO - 1:26.75 LOG: MainThread INFO test_video_playback.py test_video_playback.TestVideoPlayback.test_playback_starts 01:16:58 INFO - 1:26.75 LOG: MainThread INFO test_video_playback.py test_video_playback.TestVideoPlayback.test_video_playback_partial 01:16:58 INFO - 1:26.85 LOG: MainThread INFO mode: non-e10s 01:16:58 INFO - 1:26.87 SUITE_END: MainThread 01:16:58 INFO - Summary 01:16:58 INFO - ======= 01:16:58 INFO - Ran 4 tests 01:16:58 INFO - Expected results: 0 01:16:58 ERROR - Unexpected results: 4 (ERROR: 2, FAIL: 2) 01:16:58 INFO - Unexpected Results 01:16:58 INFO - ================== 01:16:58 ERROR - FAIL test_video_playback.py TestVideoPlayback.test_playback_starts 01:16:58 ERROR - FAIL test_video_playback.py TestVideoPlayback.test_playback_starts 01:16:58 ERROR - ERROR test_video_playback.py TestVideoPlayback.test_video_playback_partial 01:16:58 ERROR - ERROR test_video_playback.py TestVideoPlayback.test_video_playback_partial 01:16:58 ERROR - Return code: 10 01:16:58 WARNING - # TBPL WARNING # 01:16:58 WARNING - setting return code to 1 01:16:58 INFO - Running post-action listener: _collect_uploads 01:16:58 INFO - Copying /builds/slave/test/logs/log_error.log to /builds/slave/test/build/blobber_upload_dir/log_error.log 01:16:58 INFO - Copying /builds/slave/test/logs/log_info.log to /builds/slave/test/build/blobber_upload_dir/log_info.log 01:16:58 INFO - Running post-action listener: _resource_record_post_action 01:16:58 INFO - ##### 01:16:58 INFO - ##### Finished run-media-tests step (success) 01:16:58 INFO - ##### 01:16:58 INFO - Running post-run listener: _resource_record_post_run 01:16:58 INFO - Total resource usage - Wall time: 120s; CPU: 62.0%; Read bytes: 5464064; Write bytes: 218267648; Read time: 25884; Write time: 2828552 01:16:58 INFO - TinderboxPrint: CPU usage
62.4% 01:16:58 INFO - TinderboxPrint: I/O read bytes / time
5,464,064 / 25,884 01:16:58 INFO - TinderboxPrint: I/O write bytes / time
218,267,648 / 2,828,552 01:16:58 INFO - TinderboxPrint: CPU idle
44.8 (37.6%) 01:16:58 INFO - TinderboxPrint: CPU iowait
25.8 (21.6%) 01:16:58 INFO - TinderboxPrint: CPU system
6.1 (5.1%) 01:16:58 INFO - TinderboxPrint: CPU user
42.5 (35.6%) 01:16:58 INFO - TinderboxPrint: Swap in / out
0 / 0 01:16:58 INFO - install - Wall time: 29s; CPU: 100.0%; Read bytes: 0; Write bytes: 69111808; Read time: 0; Write time: 753528 01:16:58 INFO - run-media-tests - Wall time: 91s; CPU: 50.0%; Read bytes: 4493312; Write bytes: 141123584; Read time: 25236; Write time: 1994236 01:16:58 INFO - Running post-run listener: _upload_blobber_files 01:16:58 INFO - Blob upload gear active. 01:16:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:16:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:16:58 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-inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:16:58 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-inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:16:59 INFO - (blobuploader) - INFO - Open directory for files ... 01:16:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/media_tests.html ... 01:16:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:16:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:17:00 INFO - (blobuploader) - INFO - TinderboxPrint: media_tests.html: uploaded 01:17:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:17:00 INFO - (blobuploader) - INFO - Done attempting. 01:17:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/log_error.log ... 01:17:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:17:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:17:00 INFO - (blobuploader) - INFO - TinderboxPrint: log_error.log: uploaded 01:17:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:17:00 INFO - (blobuploader) - INFO - Done attempting. 01:17:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/media_tests_mach.log ... 01:17:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:17:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:17:01 INFO - (blobuploader) - INFO - TinderboxPrint: media_tests_mach.log: uploaded 01:17:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:17:01 INFO - (blobuploader) - INFO - Done attempting. 01:17:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:17:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:17:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:17:02 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:17:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:17:02 INFO - (blobuploader) - INFO - Done attempting. 01:17:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/gecko.log ... 01:17:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:17:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:17:03 INFO - (blobuploader) - INFO - TinderboxPrint: gecko.log: uploaded 01:17:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:17:03 INFO - (blobuploader) - INFO - Done attempting. 01:17:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/log_info.log ... 01:17:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:17:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:17:03 INFO - (blobuploader) - INFO - TinderboxPrint: log_info.log: uploaded 01:17:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:17:03 INFO - (blobuploader) - INFO - Done attempting. 01:17:03 INFO - (blobuploader) - INFO - Iteration through files over. 01:17:04 INFO - Return code: 0 01:17:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:17:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:17:04 INFO - Setting buildbot property blobber_files to {"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/1903bf4a2ac6730318e6ddfd5b8a3d700e003625ad0f7e6320c9eeb2edbe0fa27ff518d11b4c0d054400640299c6830c7752ddb4b407fe6d93c635513a815750", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/affce2d22046f76d3d295b886e19ccb28d2496499a8a4b828f63b776127375c4352d3491f1c4ed802b214b6079cb91700ba447cef404146d1d35148f8b979186", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/2da47b2b976c9aee5e3cdf38ef5355c9cef10a32bf6fc6592ae6a1a61b397c01887db8068814e53865759768335daaf415c56b1eb92158bdec1a72bb2d251909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/d97c8b8d1a475afed67125f0dfdb225c2d50a2f26e0dc091165c2719b224b1485f42b87338ea9835ee2ebc2f3feb8dab558342fc3ea5918d5240476fd8c57b84", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/63e1a1d59aae40269421a9fbfacecdd9214d9a6bc4ef94ce2f4e87db67d7311b50fd6c362cc46fbbe93a12333d3e6f23282394cb0a77d0004036740236fda413", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/81ecea5bb560afab4edbfbaabc32679db8c340f8ce31400aaf46c1a814a952f77678d2fb32da5c1a3209160097bca228b88e97945c0b00e2922bed0179a2d2c3"} 01:17:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:17:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:17:04 INFO - Contents: 01:17:04 INFO - blobber_files:{"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/1903bf4a2ac6730318e6ddfd5b8a3d700e003625ad0f7e6320c9eeb2edbe0fa27ff518d11b4c0d054400640299c6830c7752ddb4b407fe6d93c635513a815750", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/affce2d22046f76d3d295b886e19ccb28d2496499a8a4b828f63b776127375c4352d3491f1c4ed802b214b6079cb91700ba447cef404146d1d35148f8b979186", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/2da47b2b976c9aee5e3cdf38ef5355c9cef10a32bf6fc6592ae6a1a61b397c01887db8068814e53865759768335daaf415c56b1eb92158bdec1a72bb2d251909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/d97c8b8d1a475afed67125f0dfdb225c2d50a2f26e0dc091165c2719b224b1485f42b87338ea9835ee2ebc2f3feb8dab558342fc3ea5918d5240476fd8c57b84", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/63e1a1d59aae40269421a9fbfacecdd9214d9a6bc4ef94ce2f4e87db67d7311b50fd6c362cc46fbbe93a12333d3e6f23282394cb0a77d0004036740236fda413", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/81ecea5bb560afab4edbfbaabc32679db8c340f8ce31400aaf46c1a814a952f77678d2fb32da5c1a3209160097bca228b88e97945c0b00e2922bed0179a2d2c3"} 01:17:04 INFO - Running post-run listener: copy_logs_to_upload_dir 01:17:04 INFO - Copying logs to upload dir... 01:17:04 INFO - mkdir: /builds/slave/test/build/upload/logs 01:17:04 INFO - Copying logs to upload dir... 01:17:04 WARNING - returning nonzero exit status 1 program finished with exit code 1 elapsedTime=202.690257 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/firefox_media_tests_buildbot.py ...' warnings (results: 1, elapsed: 3 mins, 22 secs) (at 2016-07-13 01:17:04.091603) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 01:17:04.092438) ========= 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-1468397301.813458-1718347284 _=/tools/buildbot/bin/python using PTY: False blobber_files:{"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/1903bf4a2ac6730318e6ddfd5b8a3d700e003625ad0f7e6320c9eeb2edbe0fa27ff518d11b4c0d054400640299c6830c7752ddb4b407fe6d93c635513a815750", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/affce2d22046f76d3d295b886e19ccb28d2496499a8a4b828f63b776127375c4352d3491f1c4ed802b214b6079cb91700ba447cef404146d1d35148f8b979186", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/2da47b2b976c9aee5e3cdf38ef5355c9cef10a32bf6fc6592ae6a1a61b397c01887db8068814e53865759768335daaf415c56b1eb92158bdec1a72bb2d251909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/d97c8b8d1a475afed67125f0dfdb225c2d50a2f26e0dc091165c2719b224b1485f42b87338ea9835ee2ebc2f3feb8dab558342fc3ea5918d5240476fd8c57b84", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/63e1a1d59aae40269421a9fbfacecdd9214d9a6bc4ef94ce2f4e87db67d7311b50fd6c362cc46fbbe93a12333d3e6f23282394cb0a77d0004036740236fda413", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/81ecea5bb560afab4edbfbaabc32679db8c340f8ce31400aaf46c1a814a952f77678d2fb32da5c1a3209160097bca228b88e97945c0b00e2922bed0179a2d2c3"} build_url:https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.035675 build_url: 'https://queue.taskcluster.net/v1/task/BQ04lUlwQze0OueGAOk_3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"log_error.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/1903bf4a2ac6730318e6ddfd5b8a3d700e003625ad0f7e6320c9eeb2edbe0fa27ff518d11b4c0d054400640299c6830c7752ddb4b407fe6d93c635513a815750", "log_info.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/affce2d22046f76d3d295b886e19ccb28d2496499a8a4b828f63b776127375c4352d3491f1c4ed802b214b6079cb91700ba447cef404146d1d35148f8b979186", "media_tests.html": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/2da47b2b976c9aee5e3cdf38ef5355c9cef10a32bf6fc6592ae6a1a61b397c01887db8068814e53865759768335daaf415c56b1eb92158bdec1a72bb2d251909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/d97c8b8d1a475afed67125f0dfdb225c2d50a2f26e0dc091165c2719b224b1485f42b87338ea9835ee2ebc2f3feb8dab558342fc3ea5918d5240476fd8c57b84", "gecko.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/63e1a1d59aae40269421a9fbfacecdd9214d9a6bc4ef94ce2f4e87db67d7311b50fd6c362cc46fbbe93a12333d3e6f23282394cb0a77d0004036740236fda413", "media_tests_mach.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/mozilla-inbound/sha512/81ecea5bb560afab4edbfbaabc32679db8c340f8ce31400aaf46c1a814a952f77678d2fb32da5c1a3209160097bca228b88e97945c0b00e2922bed0179a2d2c3"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 01:17:04.163841) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 01:17:04.164156) ========= 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-1468397301.813458-1718347284 _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021421 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 01:17:04.231567) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 01:17:04.232073) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 01:17:04.232739) ========= ========= Total master_lag: 0.23 =========