builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-050 starttime: 1469914214.24 results: success (0) buildid: 20160730131225 builduid: da999327289a4b4499b0c01713c4792a revision: 3b04c5fb4e57e684fd7ff59231c059ba650ab2b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.241205) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.241673) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.241981) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.275140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.275440) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-30 14:30:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 104M=0s 2016-07-30 14:30:15 (104 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.169071 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.458111) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.458407) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014839 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:14.508565) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 14:30:14.508896) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 3b04c5fb4e57e684fd7ff59231c059ba650ab2b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 3b04c5fb4e57e684fd7ff59231c059ba650ab2b0 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-30 14:30:15,575 truncating revision to first 12 chars 2016-07-30 14:30:15,575 Setting DEBUG logging. 2016-07-30 14:30:15,575 attempt 1/10 2016-07-30 14:30:15,575 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/3b04c5fb4e57?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-30 14:30:16,478 unpacking tar archive at: autoland-3b04c5fb4e57/testing/mozharness/ program finished with exit code 0 elapsedTime=1.239596 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-30 14:30:15.763890) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:15.764444) ========= script_repo_revision: 3b04c5fb4e57e684fd7ff59231c059ba650ab2b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:15.764873) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:15.765189) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-30 14:30:15.776090) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-30 14:30:15.776511) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=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=6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:30:16 INFO - MultiFileLogger online at 20160730 14:30:16 in /builds/slave/test 14:30:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 14:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:30:16 INFO - {'append_to_log': False, 14:30:16 INFO - 'base_work_dir': '/builds/slave/test', 14:30:16 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 14:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:30:16 INFO - 'branch': 'Autoland-Non-PGO', 14:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 14:30:16 INFO - 'config_files': ('talos/linux_config.py',), 14:30:16 INFO - 'default_actions': ('clobber', 14:30:16 INFO - 'read-buildbot-config', 14:30:16 INFO - 'download-and-extract', 14:30:16 INFO - 'populate-webroot', 14:30:16 INFO - 'create-virtualenv', 14:30:16 INFO - 'install', 14:30:16 INFO - 'run-tests'), 14:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:30:16 INFO - 'download_minidump_stackwalk': True, 14:30:16 INFO - 'download_symbols': 'ondemand', 14:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 14:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 14:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:30:16 INFO - 'installer_path': 'installer.exe', 14:30:16 INFO - 'log_level': 'info', 14:30:16 INFO - 'log_name': 'talos', 14:30:16 INFO - 'log_to_console': True, 14:30:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:30:16 INFO - 'opt_config_files': (), 14:30:16 INFO - 'pip_index': False, 14:30:16 INFO - 'sps_profile': False, 14:30:16 INFO - 'sps_profile_interval': 0, 14:30:16 INFO - 'suite': 'dromaeojs', 14:30:16 INFO - 'system_bits': '32', 14:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:30:16 INFO - 'title': 'talos-linux64-ix-050', 14:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:30:16 INFO - 'use_talos_json': True, 14:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:30:16 INFO - 'work_dir': 'build'} 14:30:16 INFO - [mozharness: 2016-07-30 21:30:16.929885Z] Running clobber step. 14:30:16 INFO - Running pre-action listener: _resource_record_pre_action 14:30:16 INFO - Running main action method: clobber 14:30:16 INFO - rmtree: /builds/slave/test/build 14:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:30:17 INFO - Running post-action listener: _resource_record_post_action 14:30:17 INFO - [mozharness: 2016-07-30 21:30:17.903322Z] Finished clobber step (success) 14:30:17 INFO - [mozharness: 2016-07-30 21:30:17.903422Z] Running read-buildbot-config step. 14:30:17 INFO - Running pre-action listener: _resource_record_pre_action 14:30:17 INFO - Running main action method: read_buildbot_config 14:30:17 INFO - Using buildbot properties: 14:30:17 INFO - { 14:30:17 INFO - "project": "", 14:30:17 INFO - "product": "firefox", 14:30:17 INFO - "script_repo_revision": "production", 14:30:17 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 14:30:17 INFO - "repository": "", 14:30:17 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 14:30:17 INFO - "buildid": "20160730131225", 14:30:17 INFO - "pgo_build": "False", 14:30:17 INFO - "basedir": "/builds/slave/test", 14:30:17 INFO - "buildnumber": 273, 14:30:17 INFO - "slavename": "talos-linux64-ix-050", 14:30:17 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:30:17 INFO - "platform": "ubuntu64_hw", 14:30:17 INFO - "scheduler": "tests-autoland-linux64-talos", 14:30:17 INFO - "branch": "autoland", 14:30:17 INFO - "repo_path": "integration/autoland", 14:30:17 INFO - "revision": "3b04c5fb4e57e684fd7ff59231c059ba650ab2b0", 14:30:17 INFO - "stage_platform": "linux64", 14:30:17 INFO - "builduid": "da999327289a4b4499b0c01713c4792a", 14:30:17 INFO - "slavebuilddir": "test" 14:30:17 INFO - } 14:30:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 14:30:17 INFO - Running post-action listener: _resource_record_post_action 14:30:17 INFO - [mozharness: 2016-07-30 21:30:17.905857Z] Finished read-buildbot-config step (success) 14:30:17 INFO - [mozharness: 2016-07-30 21:30:17.905945Z] Running download-and-extract step. 14:30:17 INFO - Running pre-action listener: _resource_record_pre_action 14:30:17 INFO - Running main action method: download_and_extract 14:30:17 INFO - mkdir: /builds/slave/test/build/tests 14:30: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')]} 14:30:17 INFO - https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:30:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:30:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:30:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:20 INFO - Downloaded 1361 bytes. 14:30:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 14:30:20 INFO - Using the following test package requirements: 14:30:20 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:30:20 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:30:20 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:30:20 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'jsshell-linux-x86_64.zip'], 14:30:20 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:30:20 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 14:30:20 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:30:20 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:30:20 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:30:20 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 14:30:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:30:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:30:20 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')]} 14:30:20 INFO - https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:30:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:30:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:30:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:23 INFO - Downloaded 20234639 bytes. 14:30:23 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'] 14:30:23 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 14:30:25 INFO - Return code: 0 14:30:25 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:30:25 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')]} 14:30:25 INFO - https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:30:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:30:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 14:30:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:29 INFO - Downloaded 20234639 bytes. 14:30:29 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'] 14:30:29 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 14:30:30 INFO - Return code: 0 14:30: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')]} 14:30:30 INFO - https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:30:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:30:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:30:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 14:30:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:30:31 INFO - Downloaded 14413723 bytes. 14:30:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:30:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:30:31 INFO - Return code: 0 14:30:31 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')]} 14:30:31 INFO - https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:30:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:30:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:30:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/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 14:30:32 INFO - Downloaded 55661145 bytes. 14:30:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:30:32 INFO - mkdir: /builds/slave/test/properties 14:30:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:30:32 INFO - Writing to file /builds/slave/test/properties/build_url 14:30:32 INFO - Contents: 14:30:32 INFO - build_url:https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 14:30:33 INFO - Running post-action listener: _resource_record_post_action 14:30:33 INFO - Running post-action listener: set_extra_try_arguments 14:30:33 INFO - [mozharness: 2016-07-30 21:30:33.478774Z] Finished download-and-extract step (success) 14:30:33 INFO - [mozharness: 2016-07-30 21:30:33.479006Z] Running populate-webroot step. 14:30:33 INFO - Running pre-action listener: _resource_record_pre_action 14:30:33 INFO - Running main action method: populate_webroot 14:30:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:30:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:30:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:30:33 INFO - '--tppagecycles', 14:30:33 INFO - '7'], 14:30:33 INFO - 'tests': ['tsvgm']}}, 14:30:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:30:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:30:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:30:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:30:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:30:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['damp', 'tps']}, 14:30:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'tests': ['damp', 'tps']}, 14:30:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['dromaeo_dom']}, 14:30:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:30:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['basic_compositor_video']}, 14:30:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:30:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['a11yr', 14:30:33 INFO - 'ts_paint', 14:30:33 INFO - 'tpaint', 14:30:33 INFO - 'sessionrestore', 14:30:33 INFO - 'sessionrestore_no_auto_restore', 14:30:33 INFO - 'tabpaint']}, 14:30:33 INFO - 'other-e10s': {'tests': ['a11yr', 14:30:33 INFO - 'ts_paint', 14:30:33 INFO - 'tpaint', 14:30:33 INFO - 'sessionrestore', 14:30:33 INFO - 'sessionrestore_no_auto_restore', 14:30:33 INFO - 'tabpaint']}, 14:30:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:30:33 INFO - 'ts_paint', 14:30:33 INFO - 'tpaint', 14:30:33 INFO - 'sessionrestore', 14:30:33 INFO - 'sessionrestore_no_auto_restore', 14:30:33 INFO - 'tabpaint']}, 14:30:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:30:33 INFO - 'ts_paint', 14:30:33 INFO - 'tpaint', 14:30:33 INFO - 'sessionrestore', 14:30:33 INFO - 'sessionrestore_no_auto_restore', 14:30:33 INFO - 'tabpaint']}, 14:30:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['a11yr', 14:30:33 INFO - 'ts_paint', 14:30:33 INFO - 'tpaint', 14:30:33 INFO - 'sessionrestore', 14:30:33 INFO - 'sessionrestore_no_auto_restore', 14:30:33 INFO - 'tabpaint']}, 14:30:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['a11yr', 14:30:33 INFO - 'ts_paint', 14:30:33 INFO - 'tpaint', 14:30:33 INFO - 'sessionrestore', 14:30:33 INFO - 'sessionrestore_no_auto_restore', 14:30:33 INFO - 'tabpaint']}, 14:30:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['tsvgx', 14:30:33 INFO - 'tsvgr_opacity', 14:30:33 INFO - 'tart', 14:30:33 INFO - 'tscrollx', 14:30:33 INFO - 'cart']}, 14:30:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:30:33 INFO - 'tsvgr_opacity', 14:30:33 INFO - 'tart', 14:30:33 INFO - 'tscrollx', 14:30:33 INFO - 'cart']}, 14:30:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'talos_options': ['--disable-e10s'], 14:30:33 INFO - 'tests': ['tp5o']}, 14:30:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'tests': ['tp5o']}, 14:30:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'talos_options': ['--disable-e10s', 14:30:33 INFO - '--xperf_path', 14:30:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:33 INFO - 'tests': ['tp5n']}, 14:30:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:30:33 INFO - 'talos_options': ['--xperf_path', 14:30:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:30:33 INFO - 'tests': ['tp5n']}}, 14:30:33 INFO - 'talos.zip': {'path': '', 14:30:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:30:33 INFO - Running post-action listener: _resource_record_post_action 14:30:33 INFO - [mozharness: 2016-07-30 21:30:33.501377Z] Finished populate-webroot step (success) 14:30:33 INFO - [mozharness: 2016-07-30 21:30:33.501477Z] Running create-virtualenv step. 14:30:33 INFO - Running pre-action listener: _resource_record_pre_action 14:30:33 INFO - Running main action method: create_virtualenv 14:30:33 INFO - Creating virtualenv /builds/slave/test/build/venv 14:30:33 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 14:30:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:30:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:30:33 INFO - Using real prefix '/usr' 14:30:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:30:34 INFO - Installing distribute.............................................................................................................................................................................................done. 14:30:36 INFO - Installing pip.................done. 14:30:36 INFO - Return code: 0 14:30:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:30:36 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')]} 14:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:36 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')]} 14:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:36 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:36 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:30:36 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:30:36 INFO - Using env: {'DISPLAY': ':0', 14:30:36 INFO - 'HOME': '/home/cltbld', 14:30:36 INFO - 'LANG': 'en_US.UTF-8', 14:30:36 INFO - 'LANGUAGE': 'en_US:en', 14:30:36 INFO - 'LOGNAME': 'cltbld', 14:30:36 INFO - 'MAIL': '/var/mail/cltbld', 14:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:36 INFO - 'MOZ_NO_REMOTE': '1', 14:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:36 INFO - 'NO_EM_RESTART': '1', 14:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:36 INFO - 'PWD': '/builds/slave/test', 14:30:36 INFO - 'SHELL': '/bin/bash', 14:30:36 INFO - 'SHLVL': '1', 14:30:36 INFO - 'TERM': 'linux', 14:30:36 INFO - 'TMOUT': '86400', 14:30:36 INFO - 'USER': 'cltbld', 14:30:36 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:36 INFO - '_': '/tools/buildbot/bin/python'} 14:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:30:36 INFO - Cleaning up... 14:30:36 INFO - Return code: 0 14:30:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:30:36 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')]} 14:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:36 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')]} 14:30:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:36 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:36 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:36 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:36 INFO - Using env: {'DISPLAY': ':0', 14:30:36 INFO - 'HOME': '/home/cltbld', 14:30:36 INFO - 'LANG': 'en_US.UTF-8', 14:30:36 INFO - 'LANGUAGE': 'en_US:en', 14:30:36 INFO - 'LOGNAME': 'cltbld', 14:30:36 INFO - 'MAIL': '/var/mail/cltbld', 14:30:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:36 INFO - 'MOZ_NO_REMOTE': '1', 14:30:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:36 INFO - 'NO_EM_RESTART': '1', 14:30:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:36 INFO - 'PWD': '/builds/slave/test', 14:30:36 INFO - 'SHELL': '/bin/bash', 14:30:36 INFO - 'SHLVL': '1', 14:30:36 INFO - 'TERM': 'linux', 14:30:36 INFO - 'TMOUT': '86400', 14:30:36 INFO - 'USER': 'cltbld', 14:30:36 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:36 INFO - '_': '/tools/buildbot/bin/python'} 14:30:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:36 INFO - Downloading/unpacking psutil>=3.1.1 14:30: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 14:30: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 14:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30: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 14:30: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 14:30:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:30:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:38 INFO - Installing collected packages: psutil 14:30:38 INFO - Running setup.py install for psutil 14:30:38 INFO - building 'psutil._psutil_linux' extension 14:30:38 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 14:30:38 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 14:30:38 INFO - building 'psutil._psutil_posix' extension 14:30:38 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 14:30:38 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 14:30:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:30:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:30:39 INFO - Successfully installed psutil 14:30:39 INFO - Cleaning up... 14:30:39 INFO - Return code: 0 14:30:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:39 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:39 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:39 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:30:39 INFO - Using env: {'DISPLAY': ':0', 14:30:39 INFO - 'HOME': '/home/cltbld', 14:30:39 INFO - 'LANG': 'en_US.UTF-8', 14:30:39 INFO - 'LANGUAGE': 'en_US:en', 14:30:39 INFO - 'LOGNAME': 'cltbld', 14:30:39 INFO - 'MAIL': '/var/mail/cltbld', 14:30:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:39 INFO - 'MOZ_NO_REMOTE': '1', 14:30:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:39 INFO - 'NO_EM_RESTART': '1', 14:30:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:39 INFO - 'PWD': '/builds/slave/test', 14:30:39 INFO - 'SHELL': '/bin/bash', 14:30:39 INFO - 'SHLVL': '1', 14:30:39 INFO - 'TERM': 'linux', 14:30:39 INFO - 'TMOUT': '86400', 14:30:39 INFO - 'USER': 'cltbld', 14:30:39 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:39 INFO - '_': '/tools/buildbot/bin/python'} 14:30:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:30:39 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 14:30:39 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 14:30:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:39 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 14:30:39 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 14:30:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:30:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:30:41 INFO - Installing collected packages: mozsystemmonitor 14:30:41 INFO - Running setup.py install for mozsystemmonitor 14:30:41 INFO - Successfully installed mozsystemmonitor 14:30:41 INFO - Cleaning up... 14:30:41 INFO - Return code: 0 14:30:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:30: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')]} 14:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30: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')]} 14:30:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:41 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:41 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:41 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:41 INFO - Using env: {'DISPLAY': ':0', 14:30:41 INFO - 'HOME': '/home/cltbld', 14:30:41 INFO - 'LANG': 'en_US.UTF-8', 14:30:41 INFO - 'LANGUAGE': 'en_US:en', 14:30:41 INFO - 'LOGNAME': 'cltbld', 14:30:41 INFO - 'MAIL': '/var/mail/cltbld', 14:30:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:41 INFO - 'MOZ_NO_REMOTE': '1', 14:30:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:41 INFO - 'NO_EM_RESTART': '1', 14:30:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:41 INFO - 'PWD': '/builds/slave/test', 14:30:41 INFO - 'SHELL': '/bin/bash', 14:30:41 INFO - 'SHLVL': '1', 14:30:41 INFO - 'TERM': 'linux', 14:30:41 INFO - 'TMOUT': '86400', 14:30:41 INFO - 'USER': 'cltbld', 14:30:41 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:41 INFO - '_': '/tools/buildbot/bin/python'} 14:30:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:41 INFO - Downloading/unpacking jsonschema==2.5.1 14:30:41 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 14:30:41 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 14:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:41 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 14:30:41 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 14:30:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:30:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:30:43 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 14:30:43 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 14:30:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:43 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 14:30:43 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 14:30:43 INFO - Downloading functools32-3.2.3-2.tar.gz 14:30:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:30:43 INFO - warning: no files found matching '*.txt' 14:30:43 INFO - no previously-included directories found matching 'build' 14:30:43 INFO - no previously-included directories found matching 'dist' 14:30:43 INFO - no previously-included directories found matching '.git*' 14:30:43 INFO - Installing collected packages: jsonschema, functools32 14:30:43 INFO - Running setup.py install for functools32 14:30:43 INFO - warning: no files found matching '*.txt' 14:30:43 INFO - no previously-included directories found matching 'build' 14:30:43 INFO - no previously-included directories found matching 'dist' 14:30:43 INFO - no previously-included directories found matching '.git*' 14:30:43 INFO - Successfully installed jsonschema functools32 14:30:43 INFO - Cleaning up... 14:30:43 INFO - Return code: 0 14:30:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:43 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:43 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:30:43 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:30:43 INFO - Using env: {'DISPLAY': ':0', 14:30:43 INFO - 'HOME': '/home/cltbld', 14:30:43 INFO - 'LANG': 'en_US.UTF-8', 14:30:43 INFO - 'LANGUAGE': 'en_US:en', 14:30:43 INFO - 'LOGNAME': 'cltbld', 14:30:43 INFO - 'MAIL': '/var/mail/cltbld', 14:30:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:43 INFO - 'MOZ_NO_REMOTE': '1', 14:30:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:43 INFO - 'NO_EM_RESTART': '1', 14:30:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:43 INFO - 'PWD': '/builds/slave/test', 14:30:43 INFO - 'SHELL': '/bin/bash', 14:30:43 INFO - 'SHLVL': '1', 14:30:43 INFO - 'TERM': 'linux', 14:30:43 INFO - 'TMOUT': '86400', 14:30:43 INFO - 'USER': 'cltbld', 14:30:43 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:43 INFO - '_': '/tools/buildbot/bin/python'} 14:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:30:44 INFO - Cleaning up... 14:30:44 INFO - Return code: 0 14:30:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:30:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:44 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:44 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:44 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:44 INFO - Using env: {'DISPLAY': ':0', 14:30:44 INFO - 'HOME': '/home/cltbld', 14:30:44 INFO - 'LANG': 'en_US.UTF-8', 14:30:44 INFO - 'LANGUAGE': 'en_US:en', 14:30:44 INFO - 'LOGNAME': 'cltbld', 14:30:44 INFO - 'MAIL': '/var/mail/cltbld', 14:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:44 INFO - 'MOZ_NO_REMOTE': '1', 14:30:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:44 INFO - 'NO_EM_RESTART': '1', 14:30:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:44 INFO - 'PWD': '/builds/slave/test', 14:30:44 INFO - 'SHELL': '/bin/bash', 14:30:44 INFO - 'SHLVL': '1', 14:30:44 INFO - 'TERM': 'linux', 14:30:44 INFO - 'TMOUT': '86400', 14:30:44 INFO - 'USER': 'cltbld', 14:30:44 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:44 INFO - '_': '/tools/buildbot/bin/python'} 14:30:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:44 INFO - Downloading/unpacking blobuploader==1.2.4 14:30:44 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 14:30:44 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 14:30:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:44 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 14:30:44 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 14:30:46 INFO - Downloading blobuploader-1.2.4.tar.gz 14:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:30:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:30:46 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 14:30:46 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 14:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:46 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 14:30:46 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 14:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:30:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:30:46 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 14:30:46 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 14:30:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:46 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 14:30:46 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 14:30:46 INFO - Downloading docopt-0.6.1.tar.gz 14:30:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:30:46 INFO - Installing collected packages: blobuploader, requests, docopt 14:30:46 INFO - Running setup.py install for blobuploader 14:30:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:30:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:30:47 INFO - Running setup.py install for requests 14:30:47 INFO - Running setup.py install for docopt 14:30:47 INFO - Successfully installed blobuploader requests docopt 14:30:47 INFO - Cleaning up... 14:30:47 INFO - Return code: 0 14:30:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:47 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')]} 14:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:47 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')]} 14:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:47 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/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:30:47 INFO - Using env: {'DISPLAY': ':0', 14:30:47 INFO - 'HOME': '/home/cltbld', 14:30:47 INFO - 'LANG': 'en_US.UTF-8', 14:30:47 INFO - 'LANGUAGE': 'en_US:en', 14:30:47 INFO - 'LOGNAME': 'cltbld', 14:30:47 INFO - 'MAIL': '/var/mail/cltbld', 14:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:47 INFO - 'MOZ_NO_REMOTE': '1', 14:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:47 INFO - 'NO_EM_RESTART': '1', 14:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:47 INFO - 'PWD': '/builds/slave/test', 14:30:47 INFO - 'SHELL': '/bin/bash', 14:30:47 INFO - 'SHLVL': '1', 14:30:47 INFO - 'TERM': 'linux', 14:30:47 INFO - 'TMOUT': '86400', 14:30:47 INFO - 'USER': 'cltbld', 14:30:47 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:47 INFO - '_': '/tools/buildbot/bin/python'} 14:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:47 INFO - Running setup.py (path:/tmp/pip-VVDQqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:47 INFO - Running setup.py (path:/tmp/pip-ecbJ2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:47 INFO - Running setup.py (path:/tmp/pip-X_QCCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:48 INFO - Running setup.py (path:/tmp/pip-lvBx8a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:48 INFO - Running setup.py (path:/tmp/pip-DOXUxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:48 INFO - Running setup.py (path:/tmp/pip-vnORDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:48 INFO - Running setup.py (path:/tmp/pip-PRjTwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:48 INFO - Running setup.py (path:/tmp/pip-7v_FsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:48 INFO - Running setup.py (path:/tmp/pip-3INMHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:48 INFO - Running setup.py (path:/tmp/pip-JhUNDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:48 INFO - Running setup.py (path:/tmp/pip-mzRhwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:48 INFO - Running setup.py (path:/tmp/pip-W9wA8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:48 INFO - Running setup.py (path:/tmp/pip-xCHTXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:48 INFO - Running setup.py (path:/tmp/pip-KbWJgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:48 INFO - Running setup.py (path:/tmp/pip-daPj_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:48 INFO - Running setup.py (path:/tmp/pip-kRxv38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:48 INFO - Running setup.py (path:/tmp/pip-NJ2OpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:30:49 INFO - Running setup.py install for manifestparser 14:30:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Running setup.py install for mozcrash 14:30:49 INFO - Running setup.py install for mozdebug 14:30:49 INFO - Running setup.py install for mozdevice 14:30:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Running setup.py install for mozfile 14:30:49 INFO - Running setup.py install for mozhttpd 14:30:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Running setup.py install for mozinfo 14:30:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Running setup.py install for mozInstall 14:30:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:30:49 INFO - Running setup.py install for mozleak 14:30:49 INFO - Running setup.py install for mozlog 14:30:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Running setup.py install for moznetwork 14:30:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Running setup.py install for mozprocess 14:30:50 INFO - Running setup.py install for mozprofile 14:30:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Running setup.py install for mozrunner 14:30:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Running setup.py install for mozscreenshot 14:30:50 INFO - Running setup.py install for moztest 14:30:50 INFO - Running setup.py install for mozversion 14:30:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:30:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:30:50 INFO - Cleaning up... 14:30:50 INFO - Return code: 0 14:30:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:50 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')]} 14:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:50 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')]} 14:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 14:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:30:50 INFO - Using env: {'DISPLAY': ':0', 14:30:50 INFO - 'HOME': '/home/cltbld', 14:30:50 INFO - 'LANG': 'en_US.UTF-8', 14:30:50 INFO - 'LANGUAGE': 'en_US:en', 14:30:50 INFO - 'LOGNAME': 'cltbld', 14:30:50 INFO - 'MAIL': '/var/mail/cltbld', 14:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:50 INFO - 'MOZ_NO_REMOTE': '1', 14:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:50 INFO - 'NO_EM_RESTART': '1', 14:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:50 INFO - 'PWD': '/builds/slave/test', 14:30:50 INFO - 'SHELL': '/bin/bash', 14:30:50 INFO - 'SHLVL': '1', 14:30:50 INFO - 'TERM': 'linux', 14:30:50 INFO - 'TMOUT': '86400', 14:30:50 INFO - 'USER': 'cltbld', 14:30:50 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:50 INFO - '_': '/tools/buildbot/bin/python'} 14:30:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:30:51 INFO - Running setup.py (path:/tmp/pip-CmIcyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:30:51 INFO - Running setup.py (path:/tmp/pip-OyYp9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:30:51 INFO - Running setup.py (path:/tmp/pip-lgJLYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:30:51 INFO - Running setup.py (path:/tmp/pip-CKdGIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:30:51 INFO - Running setup.py (path:/tmp/pip-Z5vCiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:30:51 INFO - Running setup.py (path:/tmp/pip-OSmP7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:30:51 INFO - Running setup.py (path:/tmp/pip-4BvZsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:30:51 INFO - Running setup.py (path:/tmp/pip-xBtIos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:30:51 INFO - Running setup.py (path:/tmp/pip-onCS71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:30:51 INFO - Running setup.py (path:/tmp/pip-KvXjkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:30:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:30:51 INFO - Running setup.py (path:/tmp/pip-RGoivA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:30:51 INFO - Running setup.py (path:/tmp/pip-QX91R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:30:51 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)) 14:30:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:30:51 INFO - Running setup.py (path:/tmp/pip-r8kTX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:30:52 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)) 14:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:30:52 INFO - Running setup.py (path:/tmp/pip-qeYz09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:30:52 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)) 14:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:52 INFO - Running setup.py (path:/tmp/pip-LmYQqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:30:52 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)) 14:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:30:52 INFO - Running setup.py (path:/tmp/pip-948Fky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:30:52 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)) 14:30:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:30:52 INFO - Running setup.py (path:/tmp/pip-9nHln7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:30:52 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)) 14:30: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)) 14:30: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)) 14:30: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)) 14:30: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)) 14:30: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)) 14:30:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:30: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 14:30: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 14:30:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:30: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 14:30: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 14:30:54 INFO - Downloading blessings-1.6.tar.gz 14:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:30:54 INFO - Installing collected packages: blessings 14:30:54 INFO - Running setup.py install for blessings 14:30:54 INFO - Successfully installed blessings 14:30:54 INFO - Cleaning up... 14:30:54 INFO - Return code: 0 14:30:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:30:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:54 INFO - Reading from file tmpfile_stdout 14:30:54 INFO - Current package versions: 14:30:54 INFO - argparse == 1.2.1 14:30:54 INFO - blessings == 1.6 14:30:54 INFO - blobuploader == 1.2.4 14:30:54 INFO - docopt == 0.6.1 14:30:54 INFO - functools32 == 3.2.3-2 14:30:54 INFO - jsonschema == 2.5.1 14:30:54 INFO - manifestparser == 1.1 14:30:54 INFO - mozInstall == 1.12 14:30:54 INFO - mozcrash == 0.17 14:30:54 INFO - mozdebug == 0.1 14:30:54 INFO - mozdevice == 0.48 14:30:54 INFO - mozfile == 1.2 14:30:54 INFO - mozhttpd == 0.7 14:30:54 INFO - mozinfo == 0.9 14:30:54 INFO - mozleak == 0.1 14:30:54 INFO - mozlog == 3.3 14:30:54 INFO - moznetwork == 0.27 14:30:54 INFO - mozprocess == 0.23 14:30:54 INFO - mozprofile == 0.28 14:30:54 INFO - mozrunner == 6.12 14:30:54 INFO - mozscreenshot == 0.1 14:30:54 INFO - mozsystemmonitor == 0.3 14:30:54 INFO - moztest == 0.7 14:30:54 INFO - mozversion == 1.4 14:30:54 INFO - psutil == 3.1.1 14:30:54 INFO - requests == 1.2.3 14:30:54 INFO - wsgiref == 0.1.2 14:30:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:30:54 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')]} 14:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:54 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')]} 14:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.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/talos 14:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:30:54 INFO - Using env: {'DISPLAY': ':0', 14:30:54 INFO - 'HOME': '/home/cltbld', 14:30:54 INFO - 'LANG': 'en_US.UTF-8', 14:30:54 INFO - 'LANGUAGE': 'en_US:en', 14:30:54 INFO - 'LOGNAME': 'cltbld', 14:30:54 INFO - 'MAIL': '/var/mail/cltbld', 14:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:54 INFO - 'MOZ_NO_REMOTE': '1', 14:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:54 INFO - 'NO_EM_RESTART': '1', 14:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:54 INFO - 'PWD': '/builds/slave/test', 14:30:54 INFO - 'SHELL': '/bin/bash', 14:30:54 INFO - 'SHLVL': '1', 14:30:54 INFO - 'TERM': 'linux', 14:30:54 INFO - 'TMOUT': '86400', 14:30:54 INFO - 'USER': 'cltbld', 14:30:54 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:54 INFO - '_': '/tools/buildbot/bin/python'} 14:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:30:54 INFO - Cleaning up... 14:30:54 INFO - Return code: 0 14:30:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:30:54 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')]} 14:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:54 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')]} 14:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:30:54 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xdbd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf208a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:30:54 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.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:30:54 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.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:30:54 INFO - Using env: {'DISPLAY': ':0', 14:30:54 INFO - 'HOME': '/home/cltbld', 14:30:54 INFO - 'LANG': 'en_US.UTF-8', 14:30:54 INFO - 'LANGUAGE': 'en_US:en', 14:30:54 INFO - 'LOGNAME': 'cltbld', 14:30:54 INFO - 'MAIL': '/var/mail/cltbld', 14:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:30:54 INFO - 'MOZ_NO_REMOTE': '1', 14:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:30:54 INFO - 'NO_EM_RESTART': '1', 14:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:30:54 INFO - 'PWD': '/builds/slave/test', 14:30:54 INFO - 'SHELL': '/bin/bash', 14:30:54 INFO - 'SHLVL': '1', 14:30:54 INFO - 'TERM': 'linux', 14:30:54 INFO - 'TMOUT': '86400', 14:30:54 INFO - 'USER': 'cltbld', 14:30:54 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:30:54 INFO - '_': '/tools/buildbot/bin/python'} 14:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:30:55 INFO - Cleaning up... 14:30:55 INFO - Return code: 0 14:30:55 INFO - Running post-action listener: _resource_record_post_action 14:30:55 INFO - Running post-action listener: _start_resource_monitoring 14:30:55 INFO - Starting resource monitoring. 14:30:55 INFO - [mozharness: 2016-07-30 21:30:55.097376Z] Finished create-virtualenv step (success) 14:30:55 INFO - [mozharness: 2016-07-30 21:30:55.097900Z] Running install step. 14:30:55 INFO - Running pre-action listener: _resource_record_pre_action 14:30:55 INFO - Running main action method: install 14:30:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:30:55 INFO - Reading from file tmpfile_stdout 14:30:55 INFO - Detecting whether we're running mozinstall >=1.0... 14:30:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:30:55 INFO - Reading from file tmpfile_stdout 14:30:55 INFO - Output received: 14:30:55 INFO - Usage: mozinstall [options] installer 14:30:55 INFO - Options: 14:30:55 INFO - -h, --help show this help message and exit 14:30:55 INFO - -d DEST, --destination=DEST 14:30:55 INFO - Directory to install application into. [default: 14:30:55 INFO - "/builds/slave/test"] 14:30:55 INFO - --app=APP Application being installed. [default: firefox] 14:30:55 INFO - mkdir: /builds/slave/test/build/application 14:30:55 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'] 14:30:55 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 14:31:11 INFO - Reading from file tmpfile_stdout 14:31:11 INFO - Output received: 14:31:11 INFO - /builds/slave/test/build/application/firefox/firefox 14:31:11 INFO - Running post-action listener: _resource_record_post_action 14:31:11 INFO - [mozharness: 2016-07-30 21:31:11.422565Z] Finished install step (success) 14:31:11 INFO - [mozharness: 2016-07-30 21:31:11.422841Z] Running run-tests step. 14:31:11 INFO - Running pre-action listener: _resource_record_pre_action 14:31:11 INFO - Running main action method: run_tests 14:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:31:11 INFO - Python 2.7.3 14:31:11 INFO - Return code: 0 14:31:11 INFO - grabbing minidump binary from tooltool 14:31:11 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')]} 14:31:11 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/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107c3b0>, '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 0x107d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:31:11 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/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:31:11 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/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:31:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:31:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:31:11 INFO - Return code: 0 14:31:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:31:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:31:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:31:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:31:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:31:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:31:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-050 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:31:11 INFO - Using env: {'DISPLAY': ':0', 14:31:11 INFO - 'HOME': '/home/cltbld', 14:31:11 INFO - 'LANG': 'en_US.UTF-8', 14:31:11 INFO - 'LANGUAGE': 'en_US:en', 14:31:11 INFO - 'LOGNAME': 'cltbld', 14:31:11 INFO - 'MAIL': '/var/mail/cltbld', 14:31:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:31:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:31:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:31:11 INFO - 'MOZ_NO_REMOTE': '1', 14:31:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:31:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:31:11 INFO - 'NO_EM_RESTART': '1', 14:31:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:31:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:31:11 INFO - 'PWD': '/builds/slave/test', 14:31:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:31:11 INFO - 'SHELL': '/bin/bash', 14:31:11 INFO - 'SHLVL': '1', 14:31:11 INFO - 'TERM': 'linux', 14:31:11 INFO - 'TMOUT': '86400', 14:31:11 INFO - 'USER': 'cltbld', 14:31:11 INFO - 'XDG_SESSION_COOKIE': '6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143', 14:31:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:31:11 INFO - '_': '/tools/buildbot/bin/python'} 14:31:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-050', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:31:11 INFO - mozversion application_buildid: 20160730131225 14:31:11 INFO - mozversion application_changeset: 3b04c5fb4e57e684fd7ff59231c059ba650ab2b0 14:31:11 INFO - mozversion application_display_name: Nightly 14:31:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:31:11 INFO - mozversion application_name: Firefox 14:31:11 INFO - mozversion application_remotingname: firefox 14:31:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 14:31:11 INFO - mozversion application_vendor: Mozilla 14:31:11 INFO - mozversion application_version: 50.0a1 14:31:11 INFO - mozversion platform_buildid: 20160730131225 14:31:11 INFO - mozversion platform_changeset: 3b04c5fb4e57e684fd7ff59231c059ba650ab2b0 14:31:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 14:31:11 INFO - mozversion platform_version: 50.0a1 14:31:11 INFO - using testdate: 1469914271 14:31:11 INFO - actual date: 1469914271 14:31:11 INFO - starting webserver on 'localhost:51818' 14:31:11 INFO - SUITE-START | Running 2 tests 14:31:11 INFO - TEST-START | dromaeo_css 14:31:11 INFO - operating with platform_type : linux_ 14:31:11 INFO - Initialising browser for dromaeo_css test... 14:31:11 INFO - TEST-INFO | started process 13924 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaTbb8E/profile http://localhost:51818/getInfo.html) 14:31:15 INFO - PROCESS | 13924 | __metrics Screen width/height:1600/1200 14:31:15 INFO - PROCESS | 13924 | colorDepth:24 14:31:15 INFO - PROCESS | 13924 | Browser inner width/height: 1024/697 14:31:15 INFO - PROCESS | 13924 | __metrics 14:31:16 INFO - PROCESS | 13924 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:31:16 INFO - PROCESS | 13924 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:31:17 INFO - TEST-INFO | 13924: exit 0 14:31:17 INFO - Browser initialized. 14:31:17 INFO - Running cycle 1/1 for dromaeo_css test... 14:31:17 INFO - TEST-INFO | started process 14005 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaTbb8E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:31:29 INFO - PROCESS | 14005 | RSS: Main: 157118464 14:31:29 INFO - PROCESS | 14005 | 14:32:20 INFO - PROCESS | 14005 | 1469914340827 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:34:33 INFO - PROCESS | 14005 | Cycle 1(1): loaded http://localhost:51818/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51818/tests/dromaeo/cssquery-ext.html) 14:34:34 INFO - PROCESS | 14005 | RSS: Main: 252125184 14:34:34 INFO - PROCESS | 14005 | 14:37:37 INFO - PROCESS | 14005 | Cycle 1(1): loaded http://localhost:51818/tests/dromaeo/cssquery-ext.html (next: http://localhost:51818/tests/dromaeo/cssquery-jquery.html) 14:37:38 INFO - PROCESS | 14005 | RSS: Main: 243605504 14:37:38 INFO - PROCESS | 14005 | 14:40:40 INFO - PROCESS | 14005 | Cycle 1(1): loaded http://localhost:51818/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51818/tests/dromaeo/cssquery-mootools.html) 14:40:40 INFO - PROCESS | 14005 | RSS: Main: 188628992 14:40:40 INFO - PROCESS | 14005 | 14:43:43 INFO - PROCESS | 14005 | Cycle 1(1): loaded http://localhost:51818/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51818/tests/dromaeo/cssquery-prototype.html) 14:43:43 INFO - PROCESS | 14005 | RSS: Main: 189292544 14:43:43 INFO - PROCESS | 14005 | 14:46:46 INFO - PROCESS | 14005 | Cycle 1(1): loaded http://localhost:51818/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51818/tests/dromaeo/cssquery-yui.html) 14:46:47 INFO - PROCESS | 14005 | RSS: Main: 189460480 14:46:47 INFO - PROCESS | 14005 | 14:49:49 INFO - PROCESS | 14005 | Cycle 1(1): loaded http://localhost:51818/tests/dromaeo/cssquery-yui.html (next: http://localhost:51818/tests/dromaeo/cssquery-dojo.html) 14:49:50 INFO - PROCESS | 14005 | RSS: Main: 282333184 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | __start_tp_report 14:49:50 INFO - PROCESS | 14005 | _x_x_mozilla_page_load 14:49:50 INFO - PROCESS | 14005 | _x_x_mozilla_page_load_details 14:49:50 INFO - PROCESS | 14005 | |i|pagename|runs| 14:49:50 INFO - PROCESS | 14005 | |0;dojo.html;2609.93475163121;2618.4370360372523;2648.0202325139703;2659.2288236411455;2662.800289978251;4537.4328208973875;4639.489656137822;4673.415823022122;4718.622510199184;4738.384010078685;4652.278896770998;4721.763911804404;4738.52614738526;4740.573348398643;4777.9761101194445;4257.340112282585;4270.188664153817;4345.891352716191;4367.868963931087;4399.098184872109;4296.935545966813;4321.070969741505;4339.500957389893;4349.217140914634;4360.302351623741;4223.113146239293;4269.039466120129;4279.743215407086;4285.785710714483;4305.332673435622;4247.681423893189;4276.850310239158;4308.9784551077355;4330.805113769857;4398.164348773723;4338.023944612469;4340.891477713082;4343.674224433149;4378.605925466723;4379.0366119453665;4632.351470794058;4668.323093151508;4787.018661174451;4790.568848803624;4838.03239352131;5017.9247311290355;5039.395272567315;5054.393472783253;5096.541311282002;5100.183970564692;5065.088284108859;5090.083784918713;5127.1796512558185;5138.409082955471;5144.6655967362;4562.726236425825;4715.033418149271;4727.243641017455;4760.619150467955;4778.546038126373;5237.685738855681;5257.631965762436;5297.17893726473;5308.548773354231;5316.149416093406;5040.042391945592;5074.314967479417;5075.974620126875;5076.340075790197;5091.541761241506;4937.136001199776;5037.974810125999;5081;5128.974355128275;5188.351456067991;5074.213496907912;5075.695458272516;5077.796888124508;5110.463401342806;5122.641415100981;5035.748212589429;5043.84868453947;5056.696598204119;5057.216131499624;5087.87280317995;4162.687798415071;4205;4242.5757424257945;4322.675799315064;4364.061726728769;3729.9254014919547;3751.8311675974646;3774.358359078963;3787.4129509926006;3816.4466152407745;4333.566643335705;4345.608895199447;4346.565343465628;4347.108842687241;4389.253826849444;4931.3589233399425;4979.875503112378;5091.9236211456855;5094.465081166498;5128.589712822965;4329.0259691569145;4369.12617476506;4374.671899607479;4378.0806030733265;4431.7340959542535;4573.5426457354;4582.266837306015;4588.403507544064;4593.6095431888;4639.443266808004;4563.087382523442;4853.514648535118;4856.247281671346;4856.757162141949;4890;5055.090083784954;5118.8976220476125;5121.7695203715175;5134.666246693953;5171.586273098218;4820.68665536739;4823.855284341475;4827.131116399082;4832.8791780205775;4878.414590249193;4880.341153944314;5058.696478211319;5058.797648094035;5059.620528460454;5106.923396149135;5030.346055012825;5045.596352291881;5088.6946783193125;5103.18349064148;5142.794288228429;4961.131801934719;4962.875928101682;4969.701817890938;4975.303457516023;5025.673331233458;4802.4957379476045;4957.677750946177;4979.751012449291;4982.4768399319;5027.74861256943;4765.833195838129;4799.088173247072;4801.399825021872;4846.12769701443;4875.366202393666;4966.453690094015;4976.029674213493;4977.377827771528;4978.651494395358;5015.774290156879;4745.47799742035;4859.2954021667365;4863.975680121718;4867;4905.264210368473 14:49:50 INFO - PROCESS | 14005 | |1;ext.html;570.5692202387198;577.3475972151468;579.9971000144999;580.4050847880922;590.4066413254678;27363.5895461568;39100.41349379758;40579.594204057954;40593;43169.56830431695;40850.7957460213;41259.793701031456;41276.38085428721;41332.58667413325;41333.37999929996;7481.177070522238;7666.961665191694;7804.219578042207;7809.687612495493;7812.765617031499;9797.706068817946;10003.449810260432;10005.149562287213;10343.741406464824;10363.170946324275;9946.60213591459;10016.74958126049;10045.748856278578;10050.743657042869;10052.346597471142;9974.551145198448;10026.19790416765;10033.648822291185;10052.195824334036;10055.949720251388;9763.463009534473;9821.508924553815;9832.360896541702;9833.213342932548;9837.213022958145;38117.618823811965;38442.61557384418;38471.230575388334;38474.61525384738;38509.22981540354;3036.3320069584656;3067.4171907337536;3114.0969118955477;3243.5459035735016;3269.9182520436943;29223.853880730672;29261;29610.703892961224;29740;30223.546646800314;3245.480723084319;3280.2783387654677;3280.9459661727237;3294.472884338494;3311.7019468247972;270031.6498417515;271120;271325;271750;272275;51675.483245167816;51994.58046066051;52068;52082.17712380049;52189;32663.18342041468;33369.83315083409;33805.15487112842;35542;35576.644233557854;14820.3330850112;14996.850031499545;15006;15009.324580393908;15025.323860426308;9362.672306469241;10961.068309193648;11166.609168679057;11407.543698252142;11545.729969703494;18004.63990720178;18027.27890884376;18110.5472363192;18193.363232287073;18354;6848.6918088686125;6930.792076237721;7162.641867906688;7223.422126229889;7292.671829767673;741.1883987034189;808.9393295502861;838.9161083891684;910.6357457017247;1022.0699163760942;28389.290267743472;28809.135725928256;28859;29284.707152928197;29434.852825735736;957.6887511558771;985.9654912078187;1003.7791685829106;1023.959041638341;1040.1262939131166;14631.853681463263;15172.848271517365;15353.15557644318;15486.380544778309;15563.766543501857;388881;391982;392910;393073;393143.10285345744;49218.26172607414;49802.50197497979;49867.25199122016;49922;49945.75027124841;1078.5470102556787;1088.553692985888;1165.1436194397281;1179.3042105157997;1224.3755684600917;26358.07746728932;29030.12909612715;29824.403511930075;30077.548836767903;30318.84840575783;41742.37386439145;41993.79003104965;42002.579974201086;42221.788891055345;42313.365299521145;247288;247867;248332;248783;248990;24937.625935611344;25581.744182557937;26048.73951260539;26109.08618198354;26958;41346.79326603348;41354;41704;41913.58086419097;42612.36081458843;35592;35659;35663.64336356707;35986;36450.27099457943;35891.461628076126;35924.64075359317;36039.81980090083;36856.26287474182;36949;36126;37936.430953535164;37947.430788538746;39191;40403.797981009906;31392;32760.180995475304;33817.661823382434;34005;34130.658693413745;25433;25455.67253566861;25671.614925775753;25859.87070064713;26242.606360904985 14:49:50 INFO - PROCESS | 14005 | |2;jquery.html;343.4847728407389;346.7659329952282;360.2758455504436;362.1742427265835;388.99250940065224;12123.878761212385;16333.673326533477;16393.754093688603;17289.135543222852;18140.546486337847;17147.05691186984;17342.82657173428;17379.391721289758;17423.564410889736;17538.210780514877;5038.697678139305;5063.670861394007;5196.610254230929;5213.739313034352;5216.634835561512;3770.5852356240794;5050.141475949079;5133.743312834362;5562.527185189264;5863.296404431453;5860.648361098327;5891.587588868781;5895.823125306248;5899.115132730082;5900.763969441217;5193.402758682743;5390.973045134789;5398.865028374283;5494.395616482184;5900.291964964189;5327.30745003151;5332.520073193393;5333.35999680037;5335.8132465363915;5336.4129945705945;11717.472713727902;12028.27830330183;12134.696632584257;12268.693282667915;12378.195417297937;1670.5072003758798;1802.270080617352;1855.1280897977929;2103.568768402474;2237.2505210754416;9208.217301529377;9228.354015218938;9246.121618446237;9682.370645907993;9904;1773.2020590734091;1791.7670702808553;1804.476701756489;1827.1686382695975;1860.265195247883;66773;71818.28181718168;72010;72030.99999999948;72129;9544.809103817885;10031.147352475122;10054.74863128413;10227.335223210617;10359.378437293786;13759.312034398241;13804.516841910685;13877.861221387657;13895.16629002243;13897.235652039033;7119.323664251888;7264.527805692613;7483.550986940801;7577.469577129548;7583.928508953883;7033.753818616324;7045.577265364094;7051.964740176266;7273.781786546412;7282.308180722823;4388.385626012361;4396.164728701537;4397.340398940185;4470.664700147502;4562.954370456319;1828.0676854804146;1857.7770667519715;1895.483613109507;1913.885166889991;1932.9323473678635;813.6094674556127;859.3812455032366;898.4070513461085;905.989821349191;913.1051569461964;16896.239669214916;16975;17564.38524651631;17749.378771743184;18130;830.161536847776;841.3227351981591;847.8134810341714;853.2576658307188;857.5755001274409;9447.952760236154;9910.35582687123;10213.897861021444;10707.839382409271;10742.355458672348;372761;374448;375807;376450;405376;79513.8072928895;79690;79713;80492.59753701194;80732;876.4784952952983;887.4187407248263;889.915458031486;892.0366004714892;896.3859756066985;15407.152606606744;15763.211839407755;16503;16567.42014029503;16851.7472237919;37870;38712;39445.2110957785;40243.597564023985;40759;48013;49839.50160498494;54065.72967135139;57616;58106.12840807307;7366.263373662591;7652.961735191288;7726.575038372944;7924.920750792418;8003.879941800761;8593.742187734379;12192.939035304767;12281.508739650313;12430.502779888704;13001.219926804422;9913.950430248091;10274.383536987805;10397;10808.89191108079;10882.347059176474;10500.579976800842;10624.734381640521;10704.785904281714;10890.346579205274;10909.890901090888;10541.525631346762;10624.256302059091;10707.196960227859;10732.570697172026;11126.833097503706;7749.070111586646;7951.522908625501;8031.277185053374;8040.638171282423;8156.225158609923;8761.868571971298;10316.123129534073;10434.739131521772;10640.733981650521;10661.786764264516 14:49:50 INFO - PROCESS | 14005 | |3;mootools.html;1254.0970501239117;1332.766765815982;1397.1617029782128;1436.9640758981025;1648.5796121988892;6328.335524769896;7376.668049937753;7602.353799927013;7616.124145723244;7875.960620196905;7658.617069146549;7715.151333353327;7719.652615632296;7723.884141737878;7725.72959946402;2192.4080498265457;2219.678146668732;2219.9556008879813;2220.18963078476;2222.2222222222254;2148.903299351533;2211.3255457085584;2215.5901158285687;2216.1467834883524;2224.788645078715;2181.4873504726374;2203.118752499005;2207.0509680837313;2208.8785116818567;2219.312013275882;2150.709654196679;2155.3318471273797;2155.5257843274453;2155.8490905636613;2156.9029393677242;2134.7865213478526;2176.4667656424276;2176.9020394082304;2176.945576360588;2179.858309209896;7498;7700.229977002311;7705.845883082307;7758.961205193994;7772;4427.933580996288;4595.012072404415;4604.6776725629215;4611.953880461186;4613.723176609381;2827.915162545127;2971.5542668599883;3008.7292143707173;3014.502607069831;3026.561148633458;935.1209862729081;937.2080591899834;940.3699521320732;945.5225111318808;947.6162154327509;10221.437820919846;10311.329763565343;10314.9484252579;10325;10340.741481463023;3933.527976642821;3981.6018398159954;4021.9195616087513;4077.836886524565;4114.115465175002;8218.753437396888;8229.506229626242;8245.13426090252;8270.586470676442;8284.212999765134;4508.1659892919915;4516.277395616751;4522.977385113053;4525.977370113128;4526.502084770673;3583.605803361628;3948.6841052715713;4111.301078816608;4183.728057676241;4195.538490766014;3386.5428167197606;3417.6069751978343;3525.7003154732133;3533.7278579711283;3535.734819888519;1035.808375450529;1064.1220992680915;1071.983920241197;1075.4783929794044;1077.28898926708;600.7086563016933;626.8025571944822;674.5244602555186;687.1925487552106;700.5796522086707;4795.808167673254;4891.413030436371;5194.376674798973;5211.687298762086;5299.33758280215;383.44592064466576;389.75250715795676;393.2273083391093;393.492394810691;410.34345047923085;3411.4541673332146;3591.5151454553825;3645;3666.834992425347;3766.886993390203;9107.22588579978;9537.475438850792;9572.56923438447;9580.616775328908;9587.089226523469;9430.669926552539;9452.952735236417;9461.574229159703;9503.334766566273;9531.857022144673;7115.395191408685;7171.713131474683;7198.92801071993;7265.782026539212;7305.561666300146;5025.195968644999;5082.872928176826;5154.922676159786;5174.97412512935;5203.11547036997;8529.14708529142;8665.956670216608;8674.436161649473;8705.91294087051;8731.388802783744;8699.565021749015;8877.644894204417;8961.059088795582;8975.910240897507;8996.640134394813;5336.146216605324;6376.489880809431;6439.098526206241;6447.065175549613;6967;7182.317679820409;7299.4160467163565;7465.365443937326;7467.290607392289;7478.140013898471;6584.176977877765;6652.434543063894;6681.231658359156;6759.2564817869015;6885.51801373899;6419.903701444388;6461.2892581816905;6614.867702646016;6645.269020407654;6674.132362792806;6047.395260473917;6131.785387511416;6148.78479253224;6151.292601350723;6153.261608606996;4434.467863856229;4495.57292057254;4529.796159172779;4560.475545312332;4617.907641847211;4372.693911426297;4516.345129956072;4534.319852022223;4551.294549344857;4640.930386044144 14:49:50 INFO - PROCESS | 14005 | |4;prototype.html;284.3956592241487;286.98995535156274;291.48698291007827;293.0213088285127;293.63295880149815;4778.904421911559;4822.49363816799;4824.756488232971;4854.951450485494;4865.761749595186;4742.739149346785;4751.978324660197;4762.333273341726;4763.333133361332;4784.44978827435;3545.556805399319;3601.8019008954498;3661.9084522887006;3673.218489032817;3747.5690295615946;3727.161388687537;3732.4587934749443;3733.701303895682;3741.1395379062833;3742.7380083394173;3698.81505924704;3706.369917114097;3709.1468962138724;3712.9443058354145;3717.628237176274;3485;3558.911027224327;3685.5626305740784;3695.907602309937;3701.7964011979598;3229.677032296775;3238.740900727936;3239.8542065606866;3248.2204270974794;3268.9019329420157;4628.629709623222;4646.628269738413;4646.674732768707;4648.651351148677;4649.953500464986;3992.3612222044335;4180.184863951531;4191.11986482839;4199.0342221288965;4201.789910504463;4962.379702537183;4976.377952755905;4979.950200497985;4990.404989405489;4993.650444468889;3521.6089644590243;3528.1356067763304;3536.3104194682046;3536.65607069312;3538.8761393351365;5037.974810125999;5214.400343960455;5238.764255608468;5238.869028274324;5240.895182096337;3505.4566542186003;3540.7076417108087;3545.69861561765;3549.396602577516;3549.8640435060533;3589.4436362363876;3594.1194407370363;3602.063463499509;3602.693771029492;3603.747737658391;4144.963759060235;4150.190712811033;4150.64719498846;4151.6886233532005;4154;4876.780544875489;4928.77647186259;4950.480199579064;5016.423111342169;5016.749162541858;1245.6076335954326;1258.9055820813476;1262.6464589914838;1262.993685031569;1266.778313795082;787.5038725602833;791.0507391130551;804.8229389534293;812.4475356757343;813.772143799737;4244.342126970325;4297.419848320436;4369.825206991748;4388.407564978751;4399.626031787334;5137.588992880561;5154.201098829687;5154.376371443059;5159.2003239497935;5165.276861239429;4398.274284742982;4429.446319210098;4436.3123715824095;4438.644908407319;4446.777661116931;2637.9604305935427;2708.79684023699;2723.196656986194;2729.986350068237;2743.2867454461984;4859.514048595183;4872.269159626085;4873.220284754422;4880.511948805162;4881;5120.308758317654;5240.71176085319;5241.9213711794355;5268.894622107613;5269.815556455505;4851.932574833531;4867.269909513601;4874.488178741181;4877.146499362598;4880.1215781159735;5099.464556221544;5112.437631860566;5132.897342053213;5136.563392111563;5142.717150556755;4812.35033270504;5015.322931404359;5037.758197602032;5038.168702164248;5059.291699162194;4885.1206782779445;4891.364122664031;4910.2880082388565;4912.312276281395;4914.164592019294;3621.9275614487037;3624.691901188323;3650.6896913762625;3654.6528079832374;3658.7804731716183;5079.314292570456;5196.908649183713;5206.349206349207;5210.817621383233;5211.81758638461;5019.573336266312;5023.949760502348;5032.18197809228;5041.218611115283;5055.87360315995;4993.925091123563;5009.649324547392;5012.273220383097;5020.748962551931;5040.36995375578;4734.668573199843;4900.215965445512;4920.950790492049;4945.1346014448045;4948.901021979612;4834.299026641188;4955.727434990966;5025.39695236574;5056.6713163644245;5062.746862656926;4772.880677983078;4830.985493046359;4831.661783675249;4837.588804951619;4898.069366820435 14:49:50 INFO - PROCESS | 14005 | |5;yui.html;593.7535922592122;609.5641616244384;612.9540284478664;658.743090194824;687.8864987277097;12064;12739.872601273984;13089.803652945213;13101.213927164377;13106.80339794904;12152;12163.209391389575;12199.573014944479;12268;12288.385580720951;630.3349965786094;757.0688053693959;762.142589586715;781.7381177305609;784.0160598448939;773.5436092705303;782.6947490478718;782.8082119880637;782.9569373684445;783.3028604541962;819.4386845011156;821.3142026407957;821.5645707774889;821.6590115102225;821.9876701849477;645.7287939065603;791.7268542352879;816.8815521749343;817.3747083481142;817.9550124743137;597.9461848433662;598.6168851934765;599.8500374906273;599.8800239952027;600.3096439095076;12071.155019148664;12096.213746106476;12114.757704845853;12116.394180291038;12125.09061820367;12622.81065784014;12628;12880.162789418659;12966.351682415841;13334.730789962272;11515.827262590985;11593.188476806627;11689.941550292278;11871;12107.757844843054;4074.7555146691293;4119.052617897871;4131.277026520377;4152.231837110145;4202.957970420287;63169.368306317265;63505;63566.36433635697;64105.35894641041;64645.3535464644;10311.896881031244;10437.373757574569;10507.369557826554;10656.680299591024;10981.945090274497;17454.214560344815;17497.125143742764;17526;17747.378841740476;17749.201285942163;6830.760923367758;6946.652667366611;6965.33829286217;7082.858342833115;7123.750668726673;3799.297130030953;3802.125511132455;3808.6191380862247;3819.5034645495907;3899.3760998240714;2724.82288651241;2804.130719476969;2815.971840281571;2817.506936286142;2827.4062446885973;469.42261018946203;478.5860230900316;483.22924934729053;484.1503161950754;489.03415753886503;399.46272263805133;428.00915879738926;518.085578953148;525.136151031551;549.7306319903298;7333.963330183422;7446.813829654194;7457.85084298311;7516.736914207976;7606.239376062349;474.85042211703205;486.7931129270046;514.5960421069442;532.9626926115212;547.8931608336336;3471.8437670304897;3475.1833319169973;3525.389893948846;3542.3977923753023;3546.538949936492;57037;58767.706161468916;76379.61810190913;99099;102992;87779.20769361232;88267;93446;93994;94056.52971735098;80032;82841.58579207065;83286;84297.57851210705;84767;6671.232808227116;6894.96575513677;6927.965360173167;6929.653517324215;7104.822379440556;46895.531044689116;48268.27597586109;48861.51138488569;49102.26346604733;49852.75073624609;39499.01252468711;40252.39621405623;40498.1900361997;58441.70779146077;68074.65962670353;8864.73405797827;8885.955570222108;8986.59560319774;9129.360944733804;9171.312151588523;9269.582868770787;9272.768180795534;9285.953570232106;9326.487043212417;9432.339736218399;8749;8788.560571971504;9065.229455496357;9289;9318.95340523293;8849.646014159362;8947.731568052968;9101.726948191565;9226.492542909937;9233.630654773733;9143.010256486607;9208.539573021457;9257;9264.583093760932;9308.162265396148;7470;7478.4765066444825;7509.324160825552;7586.696532138873;7596.430267729832;7849.254320839511;7889.802754931173;7962.501424529575;8092.514449133071;8209 14:49:50 INFO - PROCESS | 14005 | __end_tp_report 14:49:50 INFO - PROCESS | 14005 | __start_cc_report 14:49:50 INFO - PROCESS | 14005 | _x_x_mozilla_cycle_collect,1021 14:49:50 INFO - PROCESS | 14005 | __end_cc_report 14:49:50 INFO - PROCESS | 14005 | __startTimestamp1469915390195__endTimestamp 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | ------- Summary: start ------- 14:49:50 INFO - PROCESS | 14005 | Number of tests: 6 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | [#0] dojo.html Cycles:165 Average:4687.56 Median:4827.13 stddev:505.65 (10.5%) stddev-sans-first:480.21 14:49:50 INFO - PROCESS | 14005 | Values: 2609.9 2618.4 2648.0 2659.2 2662.8 4537.4 4639.5 4673.4 4718.6 4738.4 4652.3 4721.8 4738.5 4740.6 4778.0 4257.3 4270.2 4345.9 4367.9 4399.1 4296.9 4321.1 4339.5 4349.2 4360.3 4223.1 4269.0 4279.7 4285.8 4305.3 4247.7 4276.9 4309.0 4330.8 4398.2 4338.0 4340.9 4343.7 4378.6 4379.0 4632.4 4668.3 4787.0 4790.6 4838.0 5017.9 5039.4 5054.4 5096.5 5100.2 5065.1 5090.1 5127.2 5138.4 5144.7 4562.7 4715.0 4727.2 4760.6 4778.5 5237.7 5257.6 5297.2 5308.5 5316.1 5040.0 5074.3 5076.0 5076.3 5091.5 4937.1 5038.0 5081.0 5129.0 5188.4 5074.2 5075.7 5077.8 5110.5 5122.6 5035.7 5043.8 5056.7 5057.2 5087.9 4162.7 4205.0 4242.6 4322.7 4364.1 3729.9 3751.8 3774.4 3787.4 3816.4 4333.6 4345.6 4346.6 4347.1 4389.3 4931.4 4979.9 5091.9 5094.5 5128.6 4329.0 4369.1 4374.7 4378.1 4431.7 4573.5 4582.3 4588.4 4593.6 4639.4 4563.1 4853.5 4856.2 4856.8 4890.0 5055.1 5118.9 5121.8 5134.7 5171.6 4820.7 4823.9 4827.1 4832.9 4878.4 4880.3 5058.7 5058.8 5059.6 5106.9 5030.3 5045.6 5088.7 5103.2 5142.8 4961.1 4962.9 4969.7 4975.3 5025.7 4802.5 4957.7 4979.8 4982.5 5027.7 4765.8 4799.1 4801.4 4846.1 4875.4 4966.5 4976.0 4977.4 4978.7 5015.8 4745.5 4859.3 4864.0 4867.0 4905.3 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | [#1] ext.html Cycles:180 Average:46006.76 Median:33811.41 stddev:81492.13 (241.0%) stddev-sans-first:81649.34 14:49:50 INFO - PROCESS | 14005 | Values: 570.6 577.3 580.0 580.4 590.4 27363.6 39100.4 40579.6 40593.0 43169.6 40850.8 41259.8 41276.4 41332.6 41333.4 7481.2 7667.0 7804.2 7809.7 7812.8 9797.7 10003.4 10005.1 10343.7 10363.2 9946.6 10016.7 10045.7 10050.7 10052.3 9974.6 10026.2 10033.6 10052.2 10055.9 9763.5 9821.5 9832.4 9833.2 9837.2 38117.6 38442.6 38471.2 38474.6 38509.2 3036.3 3067.4 3114.1 3243.5 3269.9 29223.9 29261.0 29610.7 29740.0 30223.5 3245.5 3280.3 3280.9 3294.5 3311.7 270031.6 271120.0 271325.0 271750.0 272275.0 51675.5 51994.6 52068.0 52082.2 52189.0 32663.2 33369.8 33805.2 35542.0 35576.6 14820.3 14996.9 15006.0 15009.3 15025.3 9362.7 10961.1 11166.6 11407.5 11545.7 18004.6 18027.3 18110.5 18193.4 18354.0 6848.7 6930.8 7162.6 7223.4 7292.7 741.2 808.9 838.9 910.6 1022.1 28389.3 28809.1 28859.0 29284.7 29434.9 957.7 986.0 1003.8 1024.0 1040.1 14631.9 15172.8 15353.2 15486.4 15563.8 388881.0 391982.0 392910.0 393073.0 393143.1 49218.3 49802.5 49867.3 49922.0 49945.8 1078.5 1088.6 1165.1 1179.3 1224.4 26358.1 29030.1 29824.4 30077.5 30318.8 41742.4 41993.8 42002.6 42221.8 42313.4 247288.0 247867.0 248332.0 248783.0 248990.0 24937.6 25581.7 26048.7 26109.1 26958.0 41346.8 41354.0 41704.0 41913.6 42612.4 35592.0 35659.0 35663.6 35986.0 36450.3 35891.5 35924.6 36039.8 36856.3 36949.0 36126.0 37936.4 37947.4 39191.0 40403.8 31392.0 32760.2 33817.7 34005.0 34130.7 25433.0 25455.7 25671.6 25859.9 26242.6 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | [#2] jquery.html Cycles:180 Average:24212.85 Median:4392.28 stddev:63161.07 (1438.0%) stddev-sans-first:63312.83 14:49:50 INFO - PROCESS | 14005 | Values: 343.5 346.8 360.3 362.2 389.0 12123.9 16333.7 16393.8 17289.1 18140.5 17147.1 17342.8 17379.4 17423.6 17538.2 5038.7 5063.7 5196.6 5213.7 5216.6 3770.6 5050.1 5133.7 5562.5 5863.3 5860.6 5891.6 5895.8 5899.1 5900.8 5193.4 5391.0 5398.9 5494.4 5900.3 5327.3 5332.5 5333.4 5335.8 5336.4 11717.5 12028.3 12134.7 12268.7 12378.2 1670.5 1802.3 1855.1 2103.6 2237.3 9208.2 9228.4 9246.1 9682.4 9904.0 1773.2 1791.8 1804.5 1827.2 1860.3 66773.0 71818.3 72010.0 72031.0 72129.0 9544.8 10031.1 10054.7 10227.3 10359.4 13759.3 13804.5 13877.9 13895.2 13897.2 7119.3 7264.5 7483.6 7577.5 7583.9 7033.8 7045.6 7052.0 7273.8 7282.3 4388.4 4396.2 4397.3 4470.7 4563.0 1828.1 1857.8 1895.5 1913.9 1932.9 813.6 859.4 898.4 906.0 913.1 16896.2 16975.0 17564.4 17749.4 18130.0 830.2 841.3 847.8 853.3 857.6 9448.0 9910.4 10213.9 10707.8 10742.4 372761.0 374448.0 375807.0 376450.0 405376.0 79513.8 79690.0 79713.0 80492.6 80732.0 876.5 887.4 889.9 892.0 896.4 15407.2 15763.2 16503.0 16567.4 16851.7 37870.0 38712.0 39445.2 40243.6 40759.0 48013.0 49839.5 54065.7 57616.0 58106.1 7366.3 7653.0 7726.6 7924.9 8003.9 8593.7 12192.9 12281.5 12430.5 13001.2 9914.0 10274.4 10397.0 10808.9 10882.3 10500.6 10624.7 10704.8 10890.3 10909.9 10541.5 10624.3 10707.2 10732.6 11126.8 7749.1 7951.5 8031.3 8040.6 8156.2 8761.9 10316.1 10434.7 10640.7 10661.8 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | [#3] mootools.html Cycles:180 Average:4999.79 Median:4843.61 stddev:2810.80 (58.0%) stddev-sans-first:2804.59 14:49:50 INFO - PROCESS | 14005 | Values: 1254.1 1332.8 1397.2 1437.0 1648.6 6328.3 7376.7 7602.4 7616.1 7876.0 7658.6 7715.2 7719.7 7723.9 7725.7 2192.4 2219.7 2220.0 2220.2 2222.2 2148.9 2211.3 2215.6 2216.1 2224.8 2181.5 2203.1 2207.1 2208.9 2219.3 2150.7 2155.3 2155.5 2155.8 2156.9 2134.8 2176.5 2176.9 2176.9 2179.9 7498.0 7700.2 7705.8 7759.0 7772.0 4427.9 4595.0 4604.7 4612.0 4613.7 2827.9 2971.6 3008.7 3014.5 3026.6 935.1 937.2 940.4 945.5 947.6 10221.4 10311.3 10314.9 10325.0 10340.7 3933.5 3981.6 4021.9 4077.8 4114.1 8218.8 8229.5 8245.1 8270.6 8284.2 4508.2 4516.3 4523.0 4526.0 4526.5 3583.6 3948.7 4111.3 4183.7 4195.5 3386.5 3417.6 3525.7 3533.7 3535.7 1035.8 1064.1 1072.0 1075.5 1077.3 600.7 626.8 674.5 687.2 700.6 4795.8 4891.4 5194.4 5211.7 5299.3 383.4 389.8 393.2 393.5 410.3 3411.5 3591.5 3645.0 3666.8 3766.9 9107.2 9537.5 9572.6 9580.6 9587.1 9430.7 9453.0 9461.6 9503.3 9531.9 7115.4 7171.7 7198.9 7265.8 7305.6 5025.2 5082.9 5154.9 5175.0 5203.1 8529.1 8666.0 8674.4 8705.9 8731.4 8699.6 8877.6 8961.1 8975.9 8996.6 5336.1 6376.5 6439.1 6447.1 6967.0 7182.3 7299.4 7465.4 7467.3 7478.1 6584.2 6652.4 6681.2 6759.3 6885.5 6419.9 6461.3 6614.9 6645.3 6674.1 6047.4 6131.8 6148.8 6151.3 6153.3 4434.5 4495.6 4529.8 4560.5 4617.9 4372.7 4516.3 4534.3 4551.3 4640.9 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | [#4] prototype.html Cycles:180 Average:4135.00 Median:4775.89 stddev:1216.85 (25.5%) stddev-sans-first:1185.44 14:49:50 INFO - PROCESS | 14005 | Values: 284.4 287.0 291.5 293.0 293.6 4778.9 4822.5 4824.8 4855.0 4865.8 4742.7 4752.0 4762.3 4763.3 4784.4 3545.6 3601.8 3661.9 3673.2 3747.6 3727.2 3732.5 3733.7 3741.1 3742.7 3698.8 3706.4 3709.1 3712.9 3717.6 3485.0 3558.9 3685.6 3695.9 3701.8 3229.7 3238.7 3239.9 3248.2 3268.9 4628.6 4646.6 4646.7 4648.7 4650.0 3992.4 4180.2 4191.1 4199.0 4201.8 4962.4 4976.4 4980.0 4990.4 4993.7 3521.6 3528.1 3536.3 3536.7 3538.9 5038.0 5214.4 5238.8 5238.9 5240.9 3505.5 3540.7 3545.7 3549.4 3549.9 3589.4 3594.1 3602.1 3602.7 3603.7 4145.0 4150.2 4150.6 4151.7 4154.0 4876.8 4928.8 4950.5 5016.4 5016.7 1245.6 1258.9 1262.6 1263.0 1266.8 787.5 791.1 804.8 812.4 813.8 4244.3 4297.4 4369.8 4388.4 4399.6 5137.6 5154.2 5154.4 5159.2 5165.3 4398.3 4429.4 4436.3 4438.6 4446.8 2638.0 2708.8 2723.2 2730.0 2743.3 4859.5 4872.3 4873.2 4880.5 4881.0 5120.3 5240.7 5241.9 5268.9 5269.8 4851.9 4867.3 4874.5 4877.1 4880.1 5099.5 5112.4 5132.9 5136.6 5142.7 4812.4 5015.3 5037.8 5038.2 5059.3 4885.1 4891.4 4910.3 4912.3 4914.2 3621.9 3624.7 3650.7 3654.7 3658.8 5079.3 5196.9 5206.3 5210.8 5211.8 5019.6 5023.9 5032.2 5041.2 5055.9 4993.9 5009.6 5012.3 5020.7 5040.4 4734.7 4900.2 4921.0 4945.1 4948.9 4834.3 4955.7 5025.4 5056.7 5062.7 4772.9 4831.0 4831.7 4837.6 4898.1 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - PROCESS | 14005 | [#5] yui.html Cycles:180 Average:16882.96 Median:31899.85 stddev:25099.41 (78.7%) stddev-sans-first:25140.02 14:49:50 INFO - PROCESS | 14005 | Values: 593.8 609.6 613.0 658.7 687.9 12064.0 12739.9 13089.8 13101.2 13106.8 12152.0 12163.2 12199.6 12268.0 12288.4 630.3 757.1 762.1 781.7 784.0 773.5 782.7 782.8 783.0 783.3 819.4 821.3 821.6 821.7 822.0 645.7 791.7 816.9 817.4 818.0 597.9 598.6 599.9 599.9 600.3 12071.2 12096.2 12114.8 12116.4 12125.1 12622.8 12628.0 12880.2 12966.4 13334.7 11515.8 11593.2 11689.9 11871.0 12107.8 4074.8 4119.1 4131.3 4152.2 4203.0 63169.4 63505.0 63566.4 64105.4 64645.4 10311.9 10437.4 10507.4 10656.7 10981.9 17454.2 17497.1 17526.0 17747.4 17749.2 6830.8 6946.7 6965.3 7082.9 7123.8 3799.3 3802.1 3808.6 3819.5 3899.4 2724.8 2804.1 2816.0 2817.5 2827.4 469.4 478.6 483.2 484.2 489.0 399.5 428.0 518.1 525.1 549.7 7334.0 7446.8 7457.9 7516.7 7606.2 474.9 486.8 514.6 533.0 547.9 3471.8 3475.2 3525.4 3542.4 3546.5 57037.0 58767.7 76379.6 99099.0 102992.0 87779.2 88267.0 93446.0 93994.0 94056.5 80032.0 82841.6 83286.0 84297.6 84767.0 6671.2 6895.0 6928.0 6929.7 7104.8 46895.5 48268.3 48861.5 49102.3 49852.8 39499.0 40252.4 40498.2 58441.7 68074.7 8864.7 8886.0 8986.6 9129.4 9171.3 9269.6 9272.8 9286.0 9326.5 9432.3 8749.0 8788.6 9065.2 9289.0 9319.0 8849.6 8947.7 9101.7 9226.5 9233.6 9143.0 9208.5 9257.0 9264.6 9308.2 7470.0 7478.5 7509.3 7586.7 7596.4 7849.3 7889.8 7962.5 8092.5 8209.0 14:49:50 INFO - PROCESS | 14005 | -------- Summary: end -------- 14:49:50 INFO - PROCESS | 14005 | 14:49:50 INFO - TEST-INFO | 14005: exit 0 14:49:51 INFO - TEST-OK | dromaeo_css | took 1119319ms 14:49:51 INFO - TEST-START | kraken 14:49:51 INFO - operating with platform_type : linux_ 14:49:51 INFO - Initialising browser for kraken test... 14:49:51 INFO - TEST-INFO | started process 14189 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_u4OWn/profile http://localhost:51818/getInfo.html) 14:49:55 INFO - PROCESS | 14189 | __metrics Screen width/height:1600/1200 14:49:55 INFO - PROCESS | 14189 | colorDepth:24 14:49:55 INFO - PROCESS | 14189 | Browser inner width/height: 1024/697 14:49:55 INFO - PROCESS | 14189 | __metrics 14:49:55 INFO - PROCESS | 14189 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:49:55 INFO - PROCESS | 14189 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:49:56 INFO - TEST-INFO | 14189: exit 0 14:49:56 INFO - Browser initialized. 14:49:56 INFO - Running cycle 1/1 for kraken test... 14:49:56 INFO - TEST-INFO | started process 14268 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_u4OWn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:50:08 INFO - PROCESS | 14268 | RSS: Main: 157265920 14:50:08 INFO - PROCESS | 14268 | 14:50:11 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51818/tests/kraken/driver.html?testName=audio-beat-detection) 14:50:12 INFO - PROCESS | 14268 | RSS: Main: 213475328 14:50:12 INFO - PROCESS | 14268 | 14:50:15 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51818/tests/kraken/driver.html?testName=audio-dft) 14:50:15 INFO - PROCESS | 14268 | RSS: Main: 187576320 14:50:15 INFO - PROCESS | 14268 | 14:50:19 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51818/tests/kraken/driver.html?testName=audio-fft) 14:50:19 INFO - PROCESS | 14268 | RSS: Main: 270229504 14:50:19 INFO - PROCESS | 14268 | 14:50:21 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51818/tests/kraken/driver.html?testName=audio-oscillator) 14:50:22 INFO - PROCESS | 14268 | RSS: Main: 180129792 14:50:22 INFO - PROCESS | 14268 | 14:50:24 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51818/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:50:25 INFO - PROCESS | 14268 | RSS: Main: 185106432 14:50:25 INFO - PROCESS | 14268 | 14:50:28 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51818/tests/kraken/driver.html?testName=imaging-darkroom) 14:50:29 INFO - PROCESS | 14268 | RSS: Main: 220991488 14:50:29 INFO - PROCESS | 14268 | 14:50:32 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51818/tests/kraken/driver.html?testName=imaging-desaturate) 14:50:33 INFO - PROCESS | 14268 | RSS: Main: 215076864 14:50:33 INFO - PROCESS | 14268 | 14:50:36 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51818/tests/kraken/driver.html?testName=json-parse-financial) 14:50:36 INFO - PROCESS | 14268 | RSS: Main: 217972736 14:50:36 INFO - PROCESS | 14268 | 14:50:38 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51818/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:50:39 INFO - PROCESS | 14268 | RSS: Main: 184152064 14:50:39 INFO - PROCESS | 14268 | 14:50:40 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:50:41 INFO - PROCESS | 14268 | RSS: Main: 189460480 14:50:41 INFO - PROCESS | 14268 | 14:50:43 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:50:44 INFO - PROCESS | 14268 | RSS: Main: 194109440 14:50:44 INFO - PROCESS | 14268 | 14:50:47 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:50:47 INFO - PROCESS | 14268 | RSS: Main: 189251584 14:50:47 INFO - PROCESS | 14268 | 14:50:50 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:50:51 INFO - PROCESS | 14268 | RSS: Main: 191905792 14:50:51 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | Cycle 1(1): loaded http://localhost:51818/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51818/tests/kraken/driver.html?testName=ai-astar) 14:50:53 INFO - PROCESS | 14268 | RSS: Main: 242270208 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | __start_tp_report 14:50:53 INFO - PROCESS | 14268 | _x_x_mozilla_page_load 14:50:53 INFO - PROCESS | 14268 | _x_x_mozilla_page_load_details 14:50:53 INFO - PROCESS | 14268 | |i|pagename|runs| 14:50:53 INFO - PROCESS | 14268 | |0;ai-astar;104;114;113;113;116;116;114;112;112;115 14:50:53 INFO - PROCESS | 14268 | |1;audio-beat-detection;147;124;129;193;129;130;150;128;158;123 14:50:53 INFO - PROCESS | 14268 | |2;audio-dft;161;161;160;162;163;160;162;161;161;160 14:50:53 INFO - PROCESS | 14268 | |3;audio-fft;76;77;78;127;78;79;77;75;102;75 14:50:53 INFO - PROCESS | 14268 | |4;audio-oscillator;86;86;118;85;93;86;132;85;86;125 14:50:53 INFO - PROCESS | 14268 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 14:50:53 INFO - PROCESS | 14268 | |6;imaging-darkroom;147;147;146;146;147;147;146;146;148;146 14:50:53 INFO - PROCESS | 14268 | |7;imaging-desaturate;95;95;95;96;95;96;95;94;95;95 14:50:53 INFO - PROCESS | 14268 | |8;json-parse-financial;61;61;62;61;61;69;60;61;60;60 14:50:53 INFO - PROCESS | 14268 | |9;json-stringify-tinderbox;66;66;65;65;65;65;65;65;65;70 14:50:53 INFO - PROCESS | 14268 | |10;stanford-crypto-aes;80;79;88;78;80;80;79;79;79;83 14:50:53 INFO - PROCESS | 14268 | |11;stanford-crypto-ccm;139;146;139;130;138;129;139;127;170;132 14:50:53 INFO - PROCESS | 14268 | |12;stanford-crypto-pbkdf2;166;168;170;169;163;163;165;168;164;163 14:50:53 INFO - PROCESS | 14268 | |13;stanford-crypto-sha256-iterative;68;68;67;66;68;66;67;67;70;66 14:50:53 INFO - PROCESS | 14268 | __end_tp_report 14:50:53 INFO - PROCESS | 14268 | __start_cc_report 14:50:53 INFO - PROCESS | 14268 | _x_x_mozilla_cycle_collect,1930 14:50:53 INFO - PROCESS | 14268 | __end_cc_report 14:50:53 INFO - PROCESS | 14268 | __startTimestamp1469915453308__endTimestamp 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | ------- Summary: start ------- 14:50:53 INFO - PROCESS | 14268 | Number of tests: 14 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.00 stddev:3.45 (3.0%) stddev-sans-first:1.54 14:50:53 INFO - PROCESS | 14268 | Values: 104.0 114.0 113.0 113.0 116.0 116.0 114.0 112.0 112.0 115.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#1] audio-beat-detection Cycles:10 Average:141.10 Median:138.50 stddev:21.86 (15.8%) stddev-sans-first:23.08 14:50:53 INFO - PROCESS | 14268 | Values: 147.0 124.0 129.0 193.0 129.0 130.0 150.0 128.0 158.0 123.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 14:50:53 INFO - PROCESS | 14268 | Values: 161.0 161.0 160.0 162.0 163.0 160.0 162.0 161.0 161.0 160.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#3] audio-fft Cycles:10 Average:84.40 Median:77.00 stddev:16.97 (22.0%) stddev-sans-first:17.73 14:50:53 INFO - PROCESS | 14268 | Values: 76.0 77.0 78.0 127.0 78.0 79.0 77.0 75.0 102.0 75.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#4] audio-oscillator Cycles:10 Average:98.20 Median:86.00 stddev:18.93 (22.0%) stddev-sans-first:19.55 14:50:53 INFO - PROCESS | 14268 | Values: 86.0 86.0 118.0 85.0 93.0 86.0 132.0 85.0 86.0 125.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:50:53 INFO - PROCESS | 14268 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:50:53 INFO - PROCESS | 14268 | Values: 147.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 148.0 146.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 14:50:53 INFO - PROCESS | 14268 | Values: 95.0 95.0 95.0 96.0 95.0 96.0 95.0 94.0 95.0 95.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:61.00 stddev:2.67 (4.4%) stddev-sans-first:2.83 14:50:53 INFO - PROCESS | 14268 | Values: 61.0 61.0 62.0 61.0 61.0 69.0 60.0 61.0 60.0 60.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#9] json-stringify-tinderbox Cycles:10 Average:65.70 Median:65.00 stddev:1.57 (2.4%) stddev-sans-first:1.66 14:50:53 INFO - PROCESS | 14268 | Values: 66.0 66.0 65.0 65.0 65.0 65.0 65.0 65.0 65.0 70.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#10] stanford-crypto-aes Cycles:10 Average:80.50 Median:80.00 stddev:2.95 (3.7%) stddev-sans-first:3.13 14:50:53 INFO - PROCESS | 14268 | Values: 80.0 79.0 88.0 78.0 80.0 80.0 79.0 79.0 79.0 83.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#11] stanford-crypto-ccm Cycles:10 Average:138.90 Median:139.00 stddev:12.40 (8.9%) stddev-sans-first:13.16 14:50:53 INFO - PROCESS | 14268 | Values: 139.0 146.0 139.0 130.0 138.0 129.0 139.0 127.0 170.0 132.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:167.00 stddev:2.69 (1.6%) stddev-sans-first:2.85 14:50:53 INFO - PROCESS | 14268 | Values: 166.0 168.0 170.0 169.0 163.0 163.0 165.0 168.0 164.0 163.0 14:50:53 INFO - PROCESS | 14268 | 14:50:53 INFO - PROCESS | 14268 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.25 (1.9%) stddev-sans-first:1.30 14:50:53 INFO - PROCESS | 14268 | Values: 68.0 68.0 67.0 66.0 68.0 66.0 67.0 67.0 70.0 66.0 14:50:53 INFO - PROCESS | 14268 | -------- Summary: end -------- 14:50:53 INFO - PROCESS | 14268 | 14:50:54 INFO - TEST-INFO | 14268: exit 0 14:50:54 INFO - TEST-OK | kraken | took 63430ms 14:50:54 INFO - SUITE-END | took 1182s 14:50:54 INFO - Completed test suite (00:19:42) 14:50:54 INFO - javascript benchmark 14:50:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2609.93475163121, 2618.4370360372523, 2648.0202325139703, 2659.2288236411455, 2662.800289978251, 4537.4328208973875, 4639.489656137822, 4673.415823022122, 4718.622510199184, 4738.384010078685, 4652.278896770998, 4721.763911804404, 4738.52614738526, 4740.573348398643, 4777.9761101194445, 4257.340112282585, 4270.188664153817, 4345.891352716191, 4367.868963931087, 4399.098184872109, 4296.935545966813, 4321.070969741505, 4339.500957389893, 4349.217140914634, 4360.302351623741, 4223.113146239293, 4269.039466120129, 4279.743215407086, 4285.785710714483, 4305.332673435622, 4247.681423893189, 4276.850310239158, 4308.9784551077355, 4330.805113769857, 4398.164348773723, 4338.023944612469, 4340.891477713082, 4343.674224433149, 4378.605925466723, 4379.0366119453665, 4632.351470794058, 4668.323093151508, 4787.018661174451, 4790.568848803624, 4838.03239352131, 5017.9247311290355, 5039.395272567315, 5054.393472783253, 5096.541311282002, 5100.183970564692, 5065.088284108859, 5090.083784918713, 5127.1796512558185, 5138.409082955471, 5144.6655967362, 4562.726236425825, 4715.033418149271, 4727.243641017455, 4760.619150467955, 4778.546038126373, 5237.685738855681, 5257.631965762436, 5297.17893726473, 5308.548773354231, 5316.149416093406, 5040.042391945592, 5074.314967479417, 5075.974620126875, 5076.340075790197, 5091.541761241506, 4937.136001199776, 5037.974810125999, 5081.0, 5128.974355128275, 5188.351456067991, 5074.213496907912, 5075.695458272516, 5077.796888124508, 5110.463401342806, 5122.641415100981, 5035.748212589429, 5043.84868453947, 5056.696598204119, 5057.216131499624, 5087.87280317995, 4162.687798415071, 4205.0, 4242.5757424257945, 4322.675799315064, 4364.061726728769, 3729.9254014919547, 3751.8311675974646, 3774.358359078963, 3787.4129509926006, 3816.4466152407745, 4333.566643335705, 4345.608895199447, 4346.565343465628, 4347.108842687241, 4389.253826849444, 4931.3589233399425, 4979.875503112378, 5091.9236211456855, 5094.465081166498, 5128.589712822965, 4329.0259691569145, 4369.12617476506, 4374.671899607479, 4378.0806030733265, 4431.7340959542535, 4573.5426457354, 4582.266837306015, 4588.403507544064, 4593.6095431888, 4639.443266808004, 4563.087382523442, 4853.514648535118, 4856.247281671346, 4856.757162141949, 4890.0, 5055.090083784954, 5118.8976220476125, 5121.7695203715175, 5134.666246693953, 5171.586273098218, 4820.68665536739, 4823.855284341475, 4827.131116399082, 4832.8791780205775, 4878.414590249193, 4880.341153944314, 5058.696478211319, 5058.797648094035, 5059.620528460454, 5106.923396149135, 5030.346055012825, 5045.596352291881, 5088.6946783193125, 5103.18349064148, 5142.794288228429, 4961.131801934719, 4962.875928101682, 4969.701817890938, 4975.303457516023, 5025.673331233458, 4802.4957379476045, 4957.677750946177, 4979.751012449291, 4982.4768399319, 5027.74861256943, 4765.833195838129, 4799.088173247072, 4801.399825021872, 4846.12769701443, 4875.366202393666, 4966.453690094015, 4976.029674213493, 4977.377827771528, 4978.651494395358, 5015.774290156879, 4745.47799742035, 4859.2954021667365, 4863.975680121718, 4867.0, 4905.264210368473], "value": 4654.676462710485, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.5692202387198, 577.3475972151468, 579.9971000144999, 580.4050847880922, 590.4066413254678, 27363.5895461568, 39100.41349379758, 40579.594204057954, 40593.0, 43169.56830431695, 40850.7957460213, 41259.793701031456, 41276.38085428721, 41332.58667413325, 41333.37999929996, 7481.177070522238, 7666.961665191694, 7804.219578042207, 7809.687612495493, 7812.765617031499, 9797.706068817946, 10003.449810260432, 10005.149562287213, 10343.741406464824, 10363.170946324275, 9946.60213591459, 10016.74958126049, 10045.748856278578, 10050.743657042869, 10052.346597471142, 9974.551145198448, 10026.19790416765, 10033.648822291185, 10052.195824334036, 10055.949720251388, 9763.463009534473, 9821.508924553815, 9832.360896541702, 9833.213342932548, 9837.213022958145, 38117.618823811965, 38442.61557384418, 38471.230575388334, 38474.61525384738, 38509.22981540354, 3036.3320069584656, 3067.4171907337536, 3114.0969118955477, 3243.5459035735016, 3269.9182520436943, 29223.853880730672, 29261.0, 29610.703892961224, 29740.0, 30223.546646800314, 3245.480723084319, 3280.2783387654677, 3280.9459661727237, 3294.472884338494, 3311.7019468247972, 270031.6498417515, 271120.0, 271325.0, 271750.0, 272275.0, 51675.483245167816, 51994.58046066051, 52068.0, 52082.17712380049, 52189.0, 32663.18342041468, 33369.83315083409, 33805.15487112842, 35542.0, 35576.644233557854, 14820.3330850112, 14996.850031499545, 15006.0, 15009.324580393908, 15025.323860426308, 9362.672306469241, 10961.068309193648, 11166.609168679057, 11407.543698252142, 11545.729969703494, 18004.63990720178, 18027.27890884376, 18110.5472363192, 18193.363232287073, 18354.0, 6848.6918088686125, 6930.792076237721, 7162.641867906688, 7223.422126229889, 7292.671829767673, 741.1883987034189, 808.9393295502861, 838.9161083891684, 910.6357457017247, 1022.0699163760942, 28389.290267743472, 28809.135725928256, 28859.0, 29284.707152928197, 29434.852825735736, 957.6887511558771, 985.9654912078187, 1003.7791685829106, 1023.959041638341, 1040.1262939131166, 14631.853681463263, 15172.848271517365, 15353.15557644318, 15486.380544778309, 15563.766543501857, 388881.0, 391982.0, 392910.0, 393073.0, 393143.10285345744, 49218.26172607414, 49802.50197497979, 49867.25199122016, 49922.0, 49945.75027124841, 1078.5470102556787, 1088.553692985888, 1165.1436194397281, 1179.3042105157997, 1224.3755684600917, 26358.07746728932, 29030.12909612715, 29824.403511930075, 30077.548836767903, 30318.84840575783, 41742.37386439145, 41993.79003104965, 42002.579974201086, 42221.788891055345, 42313.365299521145, 247288.0, 247867.0, 248332.0, 248783.0, 248990.0, 24937.625935611344, 25581.744182557937, 26048.73951260539, 26109.08618198354, 26958.0, 41346.79326603348, 41354.0, 41704.0, 41913.58086419097, 42612.36081458843, 35592.0, 35659.0, 35663.64336356707, 35986.0, 36450.27099457943, 35891.461628076126, 35924.64075359317, 36039.81980090083, 36856.26287474182, 36949.0, 36126.0, 37936.430953535164, 37947.430788538746, 39191.0, 40403.797981009906, 31392.0, 32760.180995475304, 33817.661823382434, 34005.0, 34130.658693413745, 25433.0, 25455.67253566861, 25671.614925775753, 25859.87070064713, 26242.606360904985], "value": 17786.179917659352, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.4847728407389, 346.7659329952282, 360.2758455504436, 362.1742427265835, 388.99250940065224, 12123.878761212385, 16333.673326533477, 16393.754093688603, 17289.135543222852, 18140.546486337847, 17147.05691186984, 17342.82657173428, 17379.391721289758, 17423.564410889736, 17538.210780514877, 5038.697678139305, 5063.670861394007, 5196.610254230929, 5213.739313034352, 5216.634835561512, 3770.5852356240794, 5050.141475949079, 5133.743312834362, 5562.527185189264, 5863.296404431453, 5860.648361098327, 5891.587588868781, 5895.823125306248, 5899.115132730082, 5900.763969441217, 5193.402758682743, 5390.973045134789, 5398.865028374283, 5494.395616482184, 5900.291964964189, 5327.30745003151, 5332.520073193393, 5333.35999680037, 5335.8132465363915, 5336.4129945705945, 11717.472713727902, 12028.27830330183, 12134.696632584257, 12268.693282667915, 12378.195417297937, 1670.5072003758798, 1802.270080617352, 1855.1280897977929, 2103.568768402474, 2237.2505210754416, 9208.217301529377, 9228.354015218938, 9246.121618446237, 9682.370645907993, 9904.0, 1773.2020590734091, 1791.7670702808553, 1804.476701756489, 1827.1686382695975, 1860.265195247883, 66773.0, 71818.28181718168, 72010.0, 72030.99999999948, 72129.0, 9544.809103817885, 10031.147352475122, 10054.74863128413, 10227.335223210617, 10359.378437293786, 13759.312034398241, 13804.516841910685, 13877.861221387657, 13895.16629002243, 13897.235652039033, 7119.323664251888, 7264.527805692613, 7483.550986940801, 7577.469577129548, 7583.928508953883, 7033.753818616324, 7045.577265364094, 7051.964740176266, 7273.781786546412, 7282.308180722823, 4388.385626012361, 4396.164728701537, 4397.340398940185, 4470.664700147502, 4562.954370456319, 1828.0676854804146, 1857.7770667519715, 1895.483613109507, 1913.885166889991, 1932.9323473678635, 813.6094674556127, 859.3812455032366, 898.4070513461085, 905.989821349191, 913.1051569461964, 16896.239669214916, 16975.0, 17564.38524651631, 17749.378771743184, 18130.0, 830.161536847776, 841.3227351981591, 847.8134810341714, 853.2576658307188, 857.5755001274409, 9447.952760236154, 9910.35582687123, 10213.897861021444, 10707.839382409271, 10742.355458672348, 372761.0, 374448.0, 375807.0, 376450.0, 405376.0, 79513.8072928895, 79690.0, 79713.0, 80492.59753701194, 80732.0, 876.4784952952983, 887.4187407248263, 889.915458031486, 892.0366004714892, 896.3859756066985, 15407.152606606744, 15763.211839407755, 16503.0, 16567.42014029503, 16851.7472237919, 37870.0, 38712.0, 39445.2110957785, 40243.597564023985, 40759.0, 48013.0, 49839.50160498494, 54065.72967135139, 57616.0, 58106.12840807307, 7366.263373662591, 7652.961735191288, 7726.575038372944, 7924.920750792418, 8003.879941800761, 8593.742187734379, 12192.939035304767, 12281.508739650313, 12430.502779888704, 13001.219926804422, 9913.950430248091, 10274.383536987805, 10397.0, 10808.89191108079, 10882.347059176474, 10500.579976800842, 10624.734381640521, 10704.785904281714, 10890.346579205274, 10909.890901090888, 10541.525631346762, 10624.256302059091, 10707.196960227859, 10732.570697172026, 11126.833097503706, 7749.070111586646, 7951.522908625501, 8031.277185053374, 8040.638171282423, 8156.225158609923, 8761.868571971298, 10316.123129534073, 10434.739131521772, 10640.733981650521, 10661.786764264516], "value": 8220.603073831846, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1254.0970501239117, 1332.766765815982, 1397.1617029782128, 1436.9640758981025, 1648.5796121988892, 6328.335524769896, 7376.668049937753, 7602.353799927013, 7616.124145723244, 7875.960620196905, 7658.617069146549, 7715.151333353327, 7719.652615632296, 7723.884141737878, 7725.72959946402, 2192.4080498265457, 2219.678146668732, 2219.9556008879813, 2220.18963078476, 2222.2222222222254, 2148.903299351533, 2211.3255457085584, 2215.5901158285687, 2216.1467834883524, 2224.788645078715, 2181.4873504726374, 2203.118752499005, 2207.0509680837313, 2208.8785116818567, 2219.312013275882, 2150.709654196679, 2155.3318471273797, 2155.5257843274453, 2155.8490905636613, 2156.9029393677242, 2134.7865213478526, 2176.4667656424276, 2176.9020394082304, 2176.945576360588, 2179.858309209896, 7498.0, 7700.229977002311, 7705.845883082307, 7758.961205193994, 7772.0, 4427.933580996288, 4595.012072404415, 4604.6776725629215, 4611.953880461186, 4613.723176609381, 2827.915162545127, 2971.5542668599883, 3008.7292143707173, 3014.502607069831, 3026.561148633458, 935.1209862729081, 937.2080591899834, 940.3699521320732, 945.5225111318808, 947.6162154327509, 10221.437820919846, 10311.329763565343, 10314.9484252579, 10325.0, 10340.741481463023, 3933.527976642821, 3981.6018398159954, 4021.9195616087513, 4077.836886524565, 4114.115465175002, 8218.753437396888, 8229.506229626242, 8245.13426090252, 8270.586470676442, 8284.212999765134, 4508.1659892919915, 4516.277395616751, 4522.977385113053, 4525.977370113128, 4526.502084770673, 3583.605803361628, 3948.6841052715713, 4111.301078816608, 4183.728057676241, 4195.538490766014, 3386.5428167197606, 3417.6069751978343, 3525.7003154732133, 3533.7278579711283, 3535.734819888519, 1035.808375450529, 1064.1220992680915, 1071.983920241197, 1075.4783929794044, 1077.28898926708, 600.7086563016933, 626.8025571944822, 674.5244602555186, 687.1925487552106, 700.5796522086707, 4795.808167673254, 4891.413030436371, 5194.376674798973, 5211.687298762086, 5299.33758280215, 383.44592064466576, 389.75250715795676, 393.2273083391093, 393.492394810691, 410.34345047923085, 3411.4541673332146, 3591.5151454553825, 3645.0, 3666.834992425347, 3766.886993390203, 9107.22588579978, 9537.475438850792, 9572.56923438447, 9580.616775328908, 9587.089226523469, 9430.669926552539, 9452.952735236417, 9461.574229159703, 9503.334766566273, 9531.857022144673, 7115.395191408685, 7171.713131474683, 7198.92801071993, 7265.782026539212, 7305.561666300146, 5025.195968644999, 5082.872928176826, 5154.922676159786, 5174.97412512935, 5203.11547036997, 8529.14708529142, 8665.956670216608, 8674.436161649473, 8705.91294087051, 8731.388802783744, 8699.565021749015, 8877.644894204417, 8961.059088795582, 8975.910240897507, 8996.640134394813, 5336.146216605324, 6376.489880809431, 6439.098526206241, 6447.065175549613, 6967.0, 7182.317679820409, 7299.4160467163565, 7465.365443937326, 7467.290607392289, 7478.140013898471, 6584.176977877765, 6652.434543063894, 6681.231658359156, 6759.2564817869015, 6885.51801373899, 6419.903701444388, 6461.2892581816905, 6614.867702646016, 6645.269020407654, 6674.132362792806, 6047.395260473917, 6131.785387511416, 6148.78479253224, 6151.292601350723, 6153.261608606996, 4434.467863856229, 4495.57292057254, 4529.796159172779, 4560.475545312332, 4617.907641847211, 4372.693911426297, 4516.345129956072, 4534.319852022223, 4551.294549344857, 4640.930386044144], "value": 3944.921563530957, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [284.3956592241487, 286.98995535156274, 291.48698291007827, 293.0213088285127, 293.63295880149815, 4778.904421911559, 4822.49363816799, 4824.756488232971, 4854.951450485494, 4865.761749595186, 4742.739149346785, 4751.978324660197, 4762.333273341726, 4763.333133361332, 4784.44978827435, 3545.556805399319, 3601.8019008954498, 3661.9084522887006, 3673.218489032817, 3747.5690295615946, 3727.161388687537, 3732.4587934749443, 3733.701303895682, 3741.1395379062833, 3742.7380083394173, 3698.81505924704, 3706.369917114097, 3709.1468962138724, 3712.9443058354145, 3717.628237176274, 3485.0, 3558.911027224327, 3685.5626305740784, 3695.907602309937, 3701.7964011979598, 3229.677032296775, 3238.740900727936, 3239.8542065606866, 3248.2204270974794, 3268.9019329420157, 4628.629709623222, 4646.628269738413, 4646.674732768707, 4648.651351148677, 4649.953500464986, 3992.3612222044335, 4180.184863951531, 4191.11986482839, 4199.0342221288965, 4201.789910504463, 4962.379702537183, 4976.377952755905, 4979.950200497985, 4990.404989405489, 4993.650444468889, 3521.6089644590243, 3528.1356067763304, 3536.3104194682046, 3536.65607069312, 3538.8761393351365, 5037.974810125999, 5214.400343960455, 5238.764255608468, 5238.869028274324, 5240.895182096337, 3505.4566542186003, 3540.7076417108087, 3545.69861561765, 3549.396602577516, 3549.8640435060533, 3589.4436362363876, 3594.1194407370363, 3602.063463499509, 3602.693771029492, 3603.747737658391, 4144.963759060235, 4150.190712811033, 4150.64719498846, 4151.6886233532005, 4154.0, 4876.780544875489, 4928.77647186259, 4950.480199579064, 5016.423111342169, 5016.749162541858, 1245.6076335954326, 1258.9055820813476, 1262.6464589914838, 1262.993685031569, 1266.778313795082, 787.5038725602833, 791.0507391130551, 804.8229389534293, 812.4475356757343, 813.772143799737, 4244.342126970325, 4297.419848320436, 4369.825206991748, 4388.407564978751, 4399.626031787334, 5137.588992880561, 5154.201098829687, 5154.376371443059, 5159.2003239497935, 5165.276861239429, 4398.274284742982, 4429.446319210098, 4436.3123715824095, 4438.644908407319, 4446.777661116931, 2637.9604305935427, 2708.79684023699, 2723.196656986194, 2729.986350068237, 2743.2867454461984, 4859.514048595183, 4872.269159626085, 4873.220284754422, 4880.511948805162, 4881.0, 5120.308758317654, 5240.71176085319, 5241.9213711794355, 5268.894622107613, 5269.815556455505, 4851.932574833531, 4867.269909513601, 4874.488178741181, 4877.146499362598, 4880.1215781159735, 5099.464556221544, 5112.437631860566, 5132.897342053213, 5136.563392111563, 5142.717150556755, 4812.35033270504, 5015.322931404359, 5037.758197602032, 5038.168702164248, 5059.291699162194, 4885.1206782779445, 4891.364122664031, 4910.2880082388565, 4912.312276281395, 4914.164592019294, 3621.9275614487037, 3624.691901188323, 3650.6896913762625, 3654.6528079832374, 3658.7804731716183, 5079.314292570456, 5196.908649183713, 5206.349206349207, 5210.817621383233, 5211.81758638461, 5019.573336266312, 5023.949760502348, 5032.18197809228, 5041.218611115283, 5055.87360315995, 4993.925091123563, 5009.649324547392, 5012.273220383097, 5020.748962551931, 5040.36995375578, 4734.668573199843, 4900.215965445512, 4920.950790492049, 4945.1346014448045, 4948.901021979612, 4834.299026641188, 4955.727434990966, 5025.39695236574, 5056.6713163644245, 5062.746862656926, 4772.880677983078, 4830.985493046359, 4831.661783675249, 4837.588804951619, 4898.069366820435], "value": 3743.618132701401, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.7535922592122, 609.5641616244384, 612.9540284478664, 658.743090194824, 687.8864987277097, 12064.0, 12739.872601273984, 13089.803652945213, 13101.213927164377, 13106.80339794904, 12152.0, 12163.209391389575, 12199.573014944479, 12268.0, 12288.385580720951, 630.3349965786094, 757.0688053693959, 762.142589586715, 781.7381177305609, 784.0160598448939, 773.5436092705303, 782.6947490478718, 782.8082119880637, 782.9569373684445, 783.3028604541962, 819.4386845011156, 821.3142026407957, 821.5645707774889, 821.6590115102225, 821.9876701849477, 645.7287939065603, 791.7268542352879, 816.8815521749343, 817.3747083481142, 817.9550124743137, 597.9461848433662, 598.6168851934765, 599.8500374906273, 599.8800239952027, 600.3096439095076, 12071.155019148664, 12096.213746106476, 12114.757704845853, 12116.394180291038, 12125.09061820367, 12622.81065784014, 12628.0, 12880.162789418659, 12966.351682415841, 13334.730789962272, 11515.827262590985, 11593.188476806627, 11689.941550292278, 11871.0, 12107.757844843054, 4074.7555146691293, 4119.052617897871, 4131.277026520377, 4152.231837110145, 4202.957970420287, 63169.368306317265, 63505.0, 63566.36433635697, 64105.35894641041, 64645.3535464644, 10311.896881031244, 10437.373757574569, 10507.369557826554, 10656.680299591024, 10981.945090274497, 17454.214560344815, 17497.125143742764, 17526.0, 17747.378841740476, 17749.201285942163, 6830.760923367758, 6946.652667366611, 6965.33829286217, 7082.858342833115, 7123.750668726673, 3799.297130030953, 3802.125511132455, 3808.6191380862247, 3819.5034645495907, 3899.3760998240714, 2724.82288651241, 2804.130719476969, 2815.971840281571, 2817.506936286142, 2827.4062446885973, 469.42261018946203, 478.5860230900316, 483.22924934729053, 484.1503161950754, 489.03415753886503, 399.46272263805133, 428.00915879738926, 518.085578953148, 525.136151031551, 549.7306319903298, 7333.963330183422, 7446.813829654194, 7457.85084298311, 7516.736914207976, 7606.239376062349, 474.85042211703205, 486.7931129270046, 514.5960421069442, 532.9626926115212, 547.8931608336336, 3471.8437670304897, 3475.1833319169973, 3525.389893948846, 3542.3977923753023, 3546.538949936492, 57037.0, 58767.706161468916, 76379.61810190913, 99099.0, 102992.0, 87779.20769361232, 88267.0, 93446.0, 93994.0, 94056.52971735098, 80032.0, 82841.58579207065, 83286.0, 84297.57851210705, 84767.0, 6671.232808227116, 6894.96575513677, 6927.965360173167, 6929.653517324215, 7104.822379440556, 46895.531044689116, 48268.27597586109, 48861.51138488569, 49102.26346604733, 49852.75073624609, 39499.01252468711, 40252.39621405623, 40498.1900361997, 58441.70779146077, 68074.65962670353, 8864.73405797827, 8885.955570222108, 8986.59560319774, 9129.360944733804, 9171.312151588523, 9269.582868770787, 9272.768180795534, 9285.953570232106, 9326.487043212417, 9432.339736218399, 8749.0, 8788.560571971504, 9065.229455496357, 9289.0, 9318.95340523293, 8849.646014159362, 8947.731568052968, 9101.726948191565, 9226.492542909937, 9233.630654773733, 9143.010256486607, 9208.539573021457, 9257.0, 9264.583093760932, 9308.162265396148, 7470.0, 7478.4765066444825, 7509.324160825552, 7586.696532138873, 7596.430267729832, 7849.254320839511, 7889.802754931173, 7962.501424529575, 8092.514449133071, 8209.0], "value": 6091.840876789509, "unit": "score"}], "name": "dromaeo_css", "value": 6278.191131793142, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 113.0, 113.0, 116.0, 116.0, 114.0, 112.0, 112.0, 115.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 124.0, 129.0, 193.0, 129.0, 130.0, 150.0, 128.0, 158.0, 123.0], "value": 141.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 162.0, 163.0, 160.0, 162.0, 161.0, 161.0, 160.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 127.0, 78.0, 79.0, 77.0, 75.0, 102.0, 75.0], "value": 84.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 118.0, 85.0, 93.0, 86.0, 132.0, 85.0, 86.0, 125.0], "value": 98.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 148.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 62.0, 61.0, 61.0, 69.0, 60.0, 61.0, 60.0, 60.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 65.0, 70.0], "value": 65.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 88.0, 78.0, 80.0, 80.0, 79.0, 79.0, 79.0, 83.0], "value": 80.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 146.0, 139.0, 130.0, 138.0, 129.0, 139.0, 127.0, 170.0, 132.0], "value": 138.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 168.0, 170.0, 169.0, 163.0, 163.0, 165.0, 168.0, 164.0, 163.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 66.0, 68.0, 66.0, 67.0, 67.0, 70.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1536.2000000000003, "alertThreshold": 2.0}]} 14:50:54 INFO - Return code: 0 14:50:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:50:54 INFO - # TBPL SUCCESS # 14:50:54 INFO - Running post-action listener: _resource_record_post_action 14:50:54 INFO - [mozharness: 2016-07-30 21:50:54.616611Z] Finished run-tests step (success) 14:50:54 INFO - Running post-run listener: _resource_record_post_run 14:50:54 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 8192; Write bytes: 539168768; Read time: 48; Write time: 397016 14:50:54 INFO - TinderboxPrint: CPU usage
13.0% 14:50:54 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 48 14:50:54 INFO - TinderboxPrint: I/O write bytes / time
539,168,768 / 397,016 14:50:54 INFO - TinderboxPrint: CPU idle
8,329.2 (87.0%) 14:50:54 INFO - TinderboxPrint: CPU user
1,197.4 (12.5%) 14:50:54 INFO - TinderboxPrint: Swap in / out
0 / 0 14:50:54 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 236892160; Read time: 0; Write time: 201460 14:50:54 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 4096; Write bytes: 284581888; Read time: 36; Write time: 186252 14:50:55 INFO - Running post-run listener: _upload_blobber_files 14:50:55 INFO - Blob upload gear active. 14:50:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:50:55 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 14:50:55 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', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:50:55 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 Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:50:55 INFO - (blobuploader) - INFO - Open directory for files ... 14:50:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:50:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:50:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:50:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:50:56 INFO - (blobuploader) - INFO - Done attempting. 14:50:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:50:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:50:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:00 INFO - (blobuploader) - INFO - Done attempting. 14:51:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:51:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:51:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:51:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:51:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:51:00 INFO - (blobuploader) - INFO - Done attempting. 14:51:00 INFO - (blobuploader) - INFO - Iteration through files over. 14:51:00 INFO - Return code: 0 14:51:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:51:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:51:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2824a2f19d3e5f6218e6faa621b773b79ffd0887d7d59b4f30a633440d0e3838abcb26ba10e98ddcb797ff7c59d89d2d5b91c8dcd3eafb36f91cc133f90bccbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bc47f7e940a5d7022dff3c947d62dbb53fd5359c3093283e9a3f0fad902466f64dea0b87a86d844e663ac071576ccd4d0e394f8fc9f81e1fae81e1eb21f3ad98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5d132d51a1ce5cc465dbe58a8878635d19075aa48f8ed730052e444d465ce5baae0d8fdc7949739544a956101d842194faecb939952c5ee1b4bcb3ce1fc73e68"} 14:51:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:51:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:51:00 INFO - Contents: 14:51:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2824a2f19d3e5f6218e6faa621b773b79ffd0887d7d59b4f30a633440d0e3838abcb26ba10e98ddcb797ff7c59d89d2d5b91c8dcd3eafb36f91cc133f90bccbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bc47f7e940a5d7022dff3c947d62dbb53fd5359c3093283e9a3f0fad902466f64dea0b87a86d844e663ac071576ccd4d0e394f8fc9f81e1fae81e1eb21f3ad98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5d132d51a1ce5cc465dbe58a8878635d19075aa48f8ed730052e444d465ce5baae0d8fdc7949739544a956101d842194faecb939952c5ee1b4bcb3ce1fc73e68"} 14:51:00 INFO - Running post-run listener: copy_logs_to_upload_dir 14:51:00 INFO - Copying logs to upload dir... 14:51:00 INFO - mkdir: /builds/slave/test/build/upload/logs 14:51:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.185233 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-30 14:50:59.991239) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 14:50:59.994408) ========= 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: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2824a2f19d3e5f6218e6faa621b773b79ffd0887d7d59b4f30a633440d0e3838abcb26ba10e98ddcb797ff7c59d89d2d5b91c8dcd3eafb36f91cc133f90bccbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bc47f7e940a5d7022dff3c947d62dbb53fd5359c3093283e9a3f0fad902466f64dea0b87a86d844e663ac071576ccd4d0e394f8fc9f81e1fae81e1eb21f3ad98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5d132d51a1ce5cc465dbe58a8878635d19075aa48f8ed730052e444d465ce5baae0d8fdc7949739544a956101d842194faecb939952c5ee1b4bcb3ce1fc73e68"} build_url:https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/YdQ8f7MRTGW4mqJvfAPLVA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/2824a2f19d3e5f6218e6faa621b773b79ffd0887d7d59b4f30a633440d0e3838abcb26ba10e98ddcb797ff7c59d89d2d5b91c8dcd3eafb36f91cc133f90bccbf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/bc47f7e940a5d7022dff3c947d62dbb53fd5359c3093283e9a3f0fad902466f64dea0b87a86d844e663ac071576ccd4d0e394f8fc9f81e1fae81e1eb21f3ad98", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/5d132d51a1ce5cc465dbe58a8878635d19075aa48f8ed730052e444d465ce5baae0d8fdc7949739544a956101d842194faecb939952c5ee1b4bcb3ce1fc73e68"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-30 14:51:00.020009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 14:51:00.020381) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 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=6dbc10da6d83fee962c624be000001a8-1469902517.40460-642472143 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004448 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-30 14:51:00.102974) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 14:51:00.103625) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-30 14:51:00.104005) ========= ========= Total master_lag: 0.19 =========