builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-030 starttime: 1469637291.68 results: success (0) buildid: 20160727074713 builduid: 3c4f771bcdf74039981dcadefcceab42 revision: 6b54e59980fb1cad35f72c88a729f4199b8402b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.677931) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.678408) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.678688) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.694729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.695131) ========= 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=4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 09:34:51-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 66.8M=0s 2016-07-27 09:34:51 (66.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.074116 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.783650) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.784032) ========= 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=4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016678 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:51.833781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 09:34:51.834146) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6b54e59980fb1cad35f72c88a729f4199b8402b8 --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 6b54e59980fb1cad35f72c88a729f4199b8402b8 --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=4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 09:34:51,955 truncating revision to first 12 chars 2016-07-27 09:34:51,955 Setting DEBUG logging. 2016-07-27 09:34:51,955 attempt 1/10 2016-07-27 09:34:51,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6b54e59980fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 09:34:52,696 unpacking tar archive at: autoland-6b54e59980fb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.063278 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-27 09:34:52.910839) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:52.911179) ========= script_repo_revision: 6b54e59980fb1cad35f72c88a729f4199b8402b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:52.911649) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:52.911964) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 09:34:52.929446) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-27 09:34:52.929848) ========= /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=4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:34:53 INFO - MultiFileLogger online at 20160727 09:34:53 in /builds/slave/test 09:34:53 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 09:34:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:34:53 INFO - {'append_to_log': False, 09:34:53 INFO - 'base_work_dir': '/builds/slave/test', 09:34:53 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 09:34:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:34:53 INFO - 'branch': 'Autoland-Non-PGO', 09:34:53 INFO - 'buildbot_json_path': 'buildprops.json', 09:34:53 INFO - 'config_files': ('talos/linux_config.py',), 09:34:53 INFO - 'default_actions': ('clobber', 09:34:53 INFO - 'read-buildbot-config', 09:34:53 INFO - 'download-and-extract', 09:34:53 INFO - 'populate-webroot', 09:34:53 INFO - 'create-virtualenv', 09:34:53 INFO - 'install', 09:34:53 INFO - 'run-tests'), 09:34:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:34:53 INFO - 'download_minidump_stackwalk': True, 09:34:53 INFO - 'download_symbols': 'ondemand', 09:34:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:34:53 INFO - 'tooltool.py': '/tools/tooltool.py', 09:34:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:34:53 INFO - '/tools/misc-python/virtualenv.py')}, 09:34:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:34:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:34:53 INFO - 'installer_path': 'installer.exe', 09:34:53 INFO - 'log_level': 'info', 09:34:53 INFO - 'log_name': 'talos', 09:34:53 INFO - 'log_to_console': True, 09:34:53 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:34:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:34:53 INFO - 'opt_config_files': (), 09:34:53 INFO - 'pip_index': False, 09:34:53 INFO - 'sps_profile': False, 09:34:53 INFO - 'sps_profile_interval': 0, 09:34:53 INFO - 'suite': 'dromaeojs', 09:34:53 INFO - 'system_bits': '32', 09:34:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:34:53 INFO - 'title': 'talos-linux64-ix-030', 09:34:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:34:53 INFO - 'use_talos_json': True, 09:34:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:34:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:34:53 INFO - 'work_dir': 'build'} 09:34:53 INFO - [mozharness: 2016-07-27 16:34:53.138757Z] Running clobber step. 09:34:53 INFO - Running pre-action listener: _resource_record_pre_action 09:34:53 INFO - Running main action method: clobber 09:34:53 INFO - rmtree: /builds/slave/test/build 09:34:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:34:53 INFO - Running post-action listener: _resource_record_post_action 09:34:53 INFO - [mozharness: 2016-07-27 16:34:53.611499Z] Finished clobber step (success) 09:34:53 INFO - [mozharness: 2016-07-27 16:34:53.611600Z] Running read-buildbot-config step. 09:34:53 INFO - Running pre-action listener: _resource_record_pre_action 09:34:53 INFO - Running main action method: read_buildbot_config 09:34:53 INFO - Using buildbot properties: 09:34:53 INFO - { 09:34:53 INFO - "project": "", 09:34:53 INFO - "product": "firefox", 09:34:53 INFO - "script_repo_revision": "production", 09:34:53 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 09:34:53 INFO - "repository": "", 09:34:53 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 09:34:53 INFO - "buildid": "20160727074713", 09:34:53 INFO - "pgo_build": "False", 09:34:53 INFO - "basedir": "/builds/slave/test", 09:34:53 INFO - "buildnumber": 250, 09:34:53 INFO - "slavename": "talos-linux64-ix-030", 09:34:53 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:34:53 INFO - "platform": "ubuntu64_hw", 09:34:53 INFO - "scheduler": "tests-autoland-linux64-talos", 09:34:53 INFO - "branch": "autoland", 09:34:53 INFO - "repo_path": "integration/autoland", 09:34:53 INFO - "revision": "6b54e59980fb1cad35f72c88a729f4199b8402b8", 09:34:53 INFO - "stage_platform": "linux64", 09:34:53 INFO - "builduid": "3c4f771bcdf74039981dcadefcceab42", 09:34:53 INFO - "slavebuilddir": "test" 09:34:53 INFO - } 09:34:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:34:53 INFO - Running post-action listener: _resource_record_post_action 09:34:53 INFO - [mozharness: 2016-07-27 16:34:53.614073Z] Finished read-buildbot-config step (success) 09:34:53 INFO - [mozharness: 2016-07-27 16:34:53.614164Z] Running download-and-extract step. 09:34:53 INFO - Running pre-action listener: _resource_record_pre_action 09:34:53 INFO - Running main action method: download_and_extract 09:34:53 INFO - mkdir: /builds/slave/test/build/tests 09:34:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:34:53 INFO - https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:34:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:34:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:34:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:54 INFO - Downloaded 1361 bytes. 09:34:54 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:34:54 INFO - Using the following test package requirements: 09:34:54 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:34:54 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:34:54 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:34:54 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'jsshell-linux-x86_64.zip'], 09:34:54 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:34:54 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:34:54 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:34:54 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:34:54 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:34:54 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:34:54 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:34:54 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:34: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')]} 09:34:54 INFO - https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:34:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:34:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:34:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:55 INFO - Downloaded 22746774 bytes. 09:34:55 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'] 09:34:55 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 09:34:57 INFO - Return code: 0 09:34:57 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 09:34:57 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')]} 09:34:57 INFO - https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:34:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:34:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:34:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:58 INFO - Downloaded 22746774 bytes. 09:34:58 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'] 09:34:58 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 09:34:59 INFO - Return code: 0 09:34:59 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')]} 09:34:59 INFO - https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:34:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:34:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:34:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:34:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:35:00 INFO - Downloaded 14413725 bytes. 09:35:00 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'] 09:35:00 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 09:35:00 INFO - Return code: 0 09:35:00 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')]} 09:35:00 INFO - https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:35:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:35:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:35:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:35:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OXN65moXRT25kZYSm-cd3w/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 09:35:01 INFO - Downloaded 57158173 bytes. 09:35:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:35:01 INFO - mkdir: /builds/slave/test/properties 09:35:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:35:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:35:01 INFO - Contents: 09:35:01 INFO - build_url:https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:35:02 INFO - Running post-action listener: _resource_record_post_action 09:35:02 INFO - Running post-action listener: set_extra_try_arguments 09:35:02 INFO - [mozharness: 2016-07-27 16:35:02.633910Z] Finished download-and-extract step (success) 09:35:02 INFO - [mozharness: 2016-07-27 16:35:02.634144Z] Running populate-webroot step. 09:35:02 INFO - Running pre-action listener: _resource_record_pre_action 09:35:02 INFO - Running main action method: populate_webroot 09:35:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:35:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:35:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:35:02 INFO - '--tppagecycles', 09:35:02 INFO - '7'], 09:35:02 INFO - 'tests': ['tsvgm']}}, 09:35:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:35:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:35:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:35:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:35:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['damp', 'tps']}, 09:35:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'tests': ['damp', 'tps']}, 09:35:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['dromaeo_dom']}, 09:35:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:35:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['basic_compositor_video']}, 09:35:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:35:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['a11yr', 09:35:02 INFO - 'ts_paint', 09:35:02 INFO - 'tpaint', 09:35:02 INFO - 'sessionrestore', 09:35:02 INFO - 'sessionrestore_no_auto_restore', 09:35:02 INFO - 'tabpaint']}, 09:35:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:35:02 INFO - 'ts_paint', 09:35:02 INFO - 'tpaint', 09:35:02 INFO - 'sessionrestore', 09:35:02 INFO - 'sessionrestore_no_auto_restore', 09:35:02 INFO - 'tabpaint']}, 09:35:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:35:02 INFO - 'ts_paint', 09:35:02 INFO - 'tpaint', 09:35:02 INFO - 'sessionrestore', 09:35:02 INFO - 'sessionrestore_no_auto_restore', 09:35:02 INFO - 'tabpaint']}, 09:35:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:35:02 INFO - 'ts_paint', 09:35:02 INFO - 'tpaint', 09:35:02 INFO - 'sessionrestore', 09:35:02 INFO - 'sessionrestore_no_auto_restore', 09:35:02 INFO - 'tabpaint']}, 09:35:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['a11yr', 09:35:02 INFO - 'ts_paint', 09:35:02 INFO - 'tpaint', 09:35:02 INFO - 'sessionrestore', 09:35:02 INFO - 'sessionrestore_no_auto_restore', 09:35:02 INFO - 'tabpaint']}, 09:35:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['a11yr', 09:35:02 INFO - 'ts_paint', 09:35:02 INFO - 'tpaint', 09:35:02 INFO - 'sessionrestore', 09:35:02 INFO - 'sessionrestore_no_auto_restore', 09:35:02 INFO - 'tabpaint']}, 09:35:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['tsvgx', 09:35:02 INFO - 'tsvgr_opacity', 09:35:02 INFO - 'tart', 09:35:02 INFO - 'tscrollx', 09:35:02 INFO - 'cart']}, 09:35:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:35:02 INFO - 'tsvgr_opacity', 09:35:02 INFO - 'tart', 09:35:02 INFO - 'tscrollx', 09:35:02 INFO - 'cart']}, 09:35:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'talos_options': ['--disable-e10s'], 09:35:02 INFO - 'tests': ['tp5o']}, 09:35:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'tests': ['tp5o']}, 09:35:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'talos_options': ['--disable-e10s', 09:35:02 INFO - '--xperf_path', 09:35:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:02 INFO - 'tests': ['tp5n']}, 09:35:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:02 INFO - 'talos_options': ['--xperf_path', 09:35:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:02 INFO - 'tests': ['tp5n']}}, 09:35:02 INFO - 'talos.zip': {'path': '', 09:35:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:35:02 INFO - Running post-action listener: _resource_record_post_action 09:35:02 INFO - [mozharness: 2016-07-27 16:35:02.653001Z] Finished populate-webroot step (success) 09:35:02 INFO - [mozharness: 2016-07-27 16:35:02.653091Z] Running create-virtualenv step. 09:35:02 INFO - Running pre-action listener: _resource_record_pre_action 09:35:02 INFO - Running main action method: create_virtualenv 09:35:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:35:02 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 09:35:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:35:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:35:02 INFO - Using real prefix '/usr' 09:35:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:35:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:35:05 INFO - Installing pip.................done. 09:35:05 INFO - Return code: 0 09:35:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:35:05 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')]} 09:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:05 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')]} 09:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:05 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:05 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 09:35:05 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 09:35:05 INFO - Using env: {'DISPLAY': ':0', 09:35:05 INFO - 'HOME': '/home/cltbld', 09:35:05 INFO - 'LANG': 'en_US.UTF-8', 09:35:05 INFO - 'LANGUAGE': 'en_US:en', 09:35:05 INFO - 'LOGNAME': 'cltbld', 09:35:05 INFO - 'MAIL': '/var/mail/cltbld', 09:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:05 INFO - 'MOZ_NO_REMOTE': '1', 09:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:05 INFO - 'NO_EM_RESTART': '1', 09:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:05 INFO - 'PWD': '/builds/slave/test', 09:35:05 INFO - 'SHELL': '/bin/bash', 09:35:05 INFO - 'SHLVL': '1', 09:35:05 INFO - 'TERM': 'linux', 09:35:05 INFO - 'TMOUT': '86400', 09:35:05 INFO - 'USER': 'cltbld', 09:35:05 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:05 INFO - '_': '/tools/buildbot/bin/python'} 09:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:05 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 09:35:05 INFO - Cleaning up... 09:35:05 INFO - Return code: 0 09:35:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:35:05 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')]} 09:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:05 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')]} 09:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:05 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:05 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 09:35:05 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 09:35:05 INFO - Using env: {'DISPLAY': ':0', 09:35:05 INFO - 'HOME': '/home/cltbld', 09:35:05 INFO - 'LANG': 'en_US.UTF-8', 09:35:05 INFO - 'LANGUAGE': 'en_US:en', 09:35:05 INFO - 'LOGNAME': 'cltbld', 09:35:05 INFO - 'MAIL': '/var/mail/cltbld', 09:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:05 INFO - 'MOZ_NO_REMOTE': '1', 09:35:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:05 INFO - 'NO_EM_RESTART': '1', 09:35:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:05 INFO - 'PWD': '/builds/slave/test', 09:35:05 INFO - 'SHELL': '/bin/bash', 09:35:05 INFO - 'SHLVL': '1', 09:35:05 INFO - 'TERM': 'linux', 09:35:05 INFO - 'TMOUT': '86400', 09:35:05 INFO - 'USER': 'cltbld', 09:35:05 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:05 INFO - '_': '/tools/buildbot/bin/python'} 09:35:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:05 INFO - Downloading/unpacking psutil>=3.1.1 09:35:05 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 09:35:05 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 09:35:05 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 09:35:05 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 09:35:05 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 09:35:05 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 09:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:35:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:07 INFO - Installing collected packages: psutil 09:35:07 INFO - Running setup.py install for psutil 09:35:07 INFO - building 'psutil._psutil_linux' extension 09:35:07 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 09:35:07 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 09:35:07 INFO - building 'psutil._psutil_posix' extension 09:35:07 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 09:35:08 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 09:35:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:08 INFO - Successfully installed psutil 09:35:08 INFO - Cleaning up... 09:35:08 INFO - Return code: 0 09:35:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:35:08 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')]} 09:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:08 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')]} 09:35:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:08 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:08 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 09:35:08 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 09:35:08 INFO - Using env: {'DISPLAY': ':0', 09:35:08 INFO - 'HOME': '/home/cltbld', 09:35:08 INFO - 'LANG': 'en_US.UTF-8', 09:35:08 INFO - 'LANGUAGE': 'en_US:en', 09:35:08 INFO - 'LOGNAME': 'cltbld', 09:35:08 INFO - 'MAIL': '/var/mail/cltbld', 09:35:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:08 INFO - 'MOZ_NO_REMOTE': '1', 09:35:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:08 INFO - 'NO_EM_RESTART': '1', 09:35:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:08 INFO - 'PWD': '/builds/slave/test', 09:35:08 INFO - 'SHELL': '/bin/bash', 09:35:08 INFO - 'SHLVL': '1', 09:35:08 INFO - 'TERM': 'linux', 09:35:08 INFO - 'TMOUT': '86400', 09:35:08 INFO - 'USER': 'cltbld', 09:35:08 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:08 INFO - '_': '/tools/buildbot/bin/python'} 09:35:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:08 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 09:35:08 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 09:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:35:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:35:10 INFO - Installing collected packages: mozsystemmonitor 09:35:10 INFO - Running setup.py install for mozsystemmonitor 09:35:10 INFO - Successfully installed mozsystemmonitor 09:35:10 INFO - Cleaning up... 09:35:10 INFO - Return code: 0 09:35:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:35:10 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')]} 09:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:10 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')]} 09:35:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:10 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:10 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 09:35:10 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 09:35:10 INFO - Using env: {'DISPLAY': ':0', 09:35:10 INFO - 'HOME': '/home/cltbld', 09:35:10 INFO - 'LANG': 'en_US.UTF-8', 09:35:10 INFO - 'LANGUAGE': 'en_US:en', 09:35:10 INFO - 'LOGNAME': 'cltbld', 09:35:10 INFO - 'MAIL': '/var/mail/cltbld', 09:35:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:10 INFO - 'MOZ_NO_REMOTE': '1', 09:35:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:10 INFO - 'NO_EM_RESTART': '1', 09:35:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:10 INFO - 'PWD': '/builds/slave/test', 09:35:10 INFO - 'SHELL': '/bin/bash', 09:35:10 INFO - 'SHLVL': '1', 09:35:10 INFO - 'TERM': 'linux', 09:35:10 INFO - 'TMOUT': '86400', 09:35:10 INFO - 'USER': 'cltbld', 09:35:10 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:10 INFO - '_': '/tools/buildbot/bin/python'} 09:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:10 INFO - Downloading/unpacking jsonschema==2.5.1 09:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:10 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 09:35:10 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 09:35:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:35:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 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 09:35:12 INFO - Downloading functools32-3.2.3-2.tar.gz 09:35:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:35:12 INFO - warning: no files found matching '*.txt' 09:35:12 INFO - no previously-included directories found matching 'build' 09:35:12 INFO - no previously-included directories found matching 'dist' 09:35:12 INFO - no previously-included directories found matching '.git*' 09:35:12 INFO - Installing collected packages: jsonschema, functools32 09:35:13 INFO - Running setup.py install for functools32 09:35:13 INFO - warning: no files found matching '*.txt' 09:35:13 INFO - no previously-included directories found matching 'build' 09:35:13 INFO - no previously-included directories found matching 'dist' 09:35:13 INFO - no previously-included directories found matching '.git*' 09:35:13 INFO - Successfully installed jsonschema functools32 09:35:13 INFO - Cleaning up... 09:35:13 INFO - Return code: 0 09:35:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:35:13 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')]} 09:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:13 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')]} 09:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:13 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:13 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 09:35:13 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 09:35:13 INFO - Using env: {'DISPLAY': ':0', 09:35:13 INFO - 'HOME': '/home/cltbld', 09:35:13 INFO - 'LANG': 'en_US.UTF-8', 09:35:13 INFO - 'LANGUAGE': 'en_US:en', 09:35:13 INFO - 'LOGNAME': 'cltbld', 09:35:13 INFO - 'MAIL': '/var/mail/cltbld', 09:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:13 INFO - 'MOZ_NO_REMOTE': '1', 09:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:13 INFO - 'NO_EM_RESTART': '1', 09:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:13 INFO - 'PWD': '/builds/slave/test', 09:35:13 INFO - 'SHELL': '/bin/bash', 09:35:13 INFO - 'SHLVL': '1', 09:35:13 INFO - 'TERM': 'linux', 09:35:13 INFO - 'TMOUT': '86400', 09:35:13 INFO - 'USER': 'cltbld', 09:35:13 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:13 INFO - '_': '/tools/buildbot/bin/python'} 09:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:35:13 INFO - Cleaning up... 09:35:13 INFO - Return code: 0 09:35:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:35:13 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')]} 09:35:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:13 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')]} 09:35:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:13 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:13 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 09:35:13 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 09:35:13 INFO - Using env: {'DISPLAY': ':0', 09:35:13 INFO - 'HOME': '/home/cltbld', 09:35:13 INFO - 'LANG': 'en_US.UTF-8', 09:35:13 INFO - 'LANGUAGE': 'en_US:en', 09:35:13 INFO - 'LOGNAME': 'cltbld', 09:35:13 INFO - 'MAIL': '/var/mail/cltbld', 09:35:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:13 INFO - 'MOZ_NO_REMOTE': '1', 09:35:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:13 INFO - 'NO_EM_RESTART': '1', 09:35:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:13 INFO - 'PWD': '/builds/slave/test', 09:35:13 INFO - 'SHELL': '/bin/bash', 09:35:13 INFO - 'SHLVL': '1', 09:35:13 INFO - 'TERM': 'linux', 09:35:13 INFO - 'TMOUT': '86400', 09:35:13 INFO - 'USER': 'cltbld', 09:35:13 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:13 INFO - '_': '/tools/buildbot/bin/python'} 09:35:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:35:13 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 09:35:13 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 09:35:13 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 09:35:13 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 09:35:13 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 09:35:13 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 09:35:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:35:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:35:15 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 09:35:15 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 09:35:15 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 09:35:15 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 09:35:15 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 09:35:15 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 09:35:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:35:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:35:16 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 09:35:16 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 09:35:16 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 09:35:16 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 09:35:16 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 09:35:16 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 09:35:16 INFO - Downloading docopt-0.6.1.tar.gz 09:35:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:35:16 INFO - Installing collected packages: blobuploader, requests, docopt 09:35:16 INFO - Running setup.py install for blobuploader 09:35:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:35:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:35:16 INFO - Running setup.py install for requests 09:35:16 INFO - Running setup.py install for docopt 09:35:16 INFO - Successfully installed blobuploader requests docopt 09:35:16 INFO - Cleaning up... 09:35:16 INFO - Return code: 0 09:35:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:16 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')]} 09:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:16 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')]} 09:35:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:16 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:16 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 09:35:16 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 09:35:16 INFO - Using env: {'DISPLAY': ':0', 09:35:16 INFO - 'HOME': '/home/cltbld', 09:35:16 INFO - 'LANG': 'en_US.UTF-8', 09:35:16 INFO - 'LANGUAGE': 'en_US:en', 09:35:16 INFO - 'LOGNAME': 'cltbld', 09:35:16 INFO - 'MAIL': '/var/mail/cltbld', 09:35:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:16 INFO - 'MOZ_NO_REMOTE': '1', 09:35:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:16 INFO - 'NO_EM_RESTART': '1', 09:35:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:16 INFO - 'PWD': '/builds/slave/test', 09:35:16 INFO - 'SHELL': '/bin/bash', 09:35:16 INFO - 'SHLVL': '1', 09:35:16 INFO - 'TERM': 'linux', 09:35:16 INFO - 'TMOUT': '86400', 09:35:16 INFO - 'USER': 'cltbld', 09:35:16 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:16 INFO - '_': '/tools/buildbot/bin/python'} 09:35:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:17 INFO - Running setup.py (path:/tmp/pip-wuqA_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:17 INFO - Running setup.py (path:/tmp/pip-FGRp7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:17 INFO - Running setup.py (path:/tmp/pip-UevaQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:17 INFO - Running setup.py (path:/tmp/pip-Uw39ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:17 INFO - Running setup.py (path:/tmp/pip-uIjpmC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:17 INFO - Running setup.py (path:/tmp/pip-IttB1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:17 INFO - Running setup.py (path:/tmp/pip-hMv6Yx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:17 INFO - Running setup.py (path:/tmp/pip-Nl0zZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:17 INFO - Running setup.py (path:/tmp/pip-RfAcf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:17 INFO - Running setup.py (path:/tmp/pip-D8kZNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:17 INFO - Running setup.py (path:/tmp/pip-orah0k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:17 INFO - Running setup.py (path:/tmp/pip-7EhDiP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:18 INFO - Running setup.py (path:/tmp/pip-4h5sgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:18 INFO - Running setup.py (path:/tmp/pip-ylgIMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:18 INFO - Running setup.py (path:/tmp/pip-cj4NP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:18 INFO - Running setup.py (path:/tmp/pip-63jDxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:18 INFO - Running setup.py (path:/tmp/pip-oQFldz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:35:18 INFO - Running setup.py install for manifestparser 09:35:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:35:18 INFO - Running setup.py install for mozcrash 09:35:18 INFO - Running setup.py install for mozdebug 09:35:18 INFO - Running setup.py install for mozdevice 09:35:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:35:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:35:18 INFO - Running setup.py install for mozfile 09:35:18 INFO - Running setup.py install for mozhttpd 09:35:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for mozinfo 09:35:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for mozInstall 09:35:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for mozleak 09:35:19 INFO - Running setup.py install for mozlog 09:35:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for moznetwork 09:35:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for mozprocess 09:35:19 INFO - Running setup.py install for mozprofile 09:35:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for mozrunner 09:35:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:35:19 INFO - Running setup.py install for mozscreenshot 09:35:19 INFO - Running setup.py install for moztest 09:35:20 INFO - Running setup.py install for mozversion 09:35:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:35:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:35:20 INFO - Cleaning up... 09:35:20 INFO - Return code: 0 09:35:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35: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')]} 09:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:20 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:20 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 09:35:20 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 09:35:20 INFO - Using env: {'DISPLAY': ':0', 09:35:20 INFO - 'HOME': '/home/cltbld', 09:35:20 INFO - 'LANG': 'en_US.UTF-8', 09:35:20 INFO - 'LANGUAGE': 'en_US:en', 09:35:20 INFO - 'LOGNAME': 'cltbld', 09:35:20 INFO - 'MAIL': '/var/mail/cltbld', 09:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:20 INFO - 'MOZ_NO_REMOTE': '1', 09:35:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:20 INFO - 'NO_EM_RESTART': '1', 09:35:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:20 INFO - 'PWD': '/builds/slave/test', 09:35:20 INFO - 'SHELL': '/bin/bash', 09:35:20 INFO - 'SHLVL': '1', 09:35:20 INFO - 'TERM': 'linux', 09:35:20 INFO - 'TMOUT': '86400', 09:35:20 INFO - 'USER': 'cltbld', 09:35:20 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:20 INFO - '_': '/tools/buildbot/bin/python'} 09:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:20 INFO - Running setup.py (path:/tmp/pip-R2NfC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:20 INFO - Running setup.py (path:/tmp/pip-LiEXKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:20 INFO - Running setup.py (path:/tmp/pip-68dNwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:20 INFO - Running setup.py (path:/tmp/pip-QkrrkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:20 INFO - Running setup.py (path:/tmp/pip-jfpVvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:20 INFO - Running setup.py (path:/tmp/pip-EB2ZRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:20 INFO - Running setup.py (path:/tmp/pip-8i5gX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:20 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)) 09:35:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:20 INFO - Running setup.py (path:/tmp/pip-PS9tC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:21 INFO - Running setup.py (path:/tmp/pip-DyOzX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:21 INFO - Running setup.py (path:/tmp/pip-FhDoDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:21 INFO - Running setup.py (path:/tmp/pip-1pZuGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:21 INFO - Running setup.py (path:/tmp/pip-5eMBPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:21 INFO - Running setup.py (path:/tmp/pip-ttYatu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:21 INFO - Running setup.py (path:/tmp/pip-eqzzHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:21 INFO - Running setup.py (path:/tmp/pip-R2r5hL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:21 INFO - Running setup.py (path:/tmp/pip-7JseBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:21 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)) 09:35:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:21 INFO - Running setup.py (path:/tmp/pip-KEeVwF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:21 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)) 09:35:21 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)) 09:35:21 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)) 09:35:21 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)) 09:35:21 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)) 09:35:21 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)) 09:35:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:35:21 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 09:35:21 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 09:35:21 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 09:35:21 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 09:35:21 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 09:35:21 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 09:35:23 INFO - Downloading blessings-1.6.tar.gz 09:35:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:35:23 INFO - Installing collected packages: blessings 09:35:23 INFO - Running setup.py install for blessings 09:35:23 INFO - Successfully installed blessings 09:35:23 INFO - Cleaning up... 09:35:23 INFO - Return code: 0 09:35:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:35:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:24 INFO - Reading from file tmpfile_stdout 09:35:24 INFO - Current package versions: 09:35:24 INFO - argparse == 1.2.1 09:35:24 INFO - blessings == 1.6 09:35:24 INFO - blobuploader == 1.2.4 09:35:24 INFO - docopt == 0.6.1 09:35:24 INFO - functools32 == 3.2.3-2 09:35:24 INFO - jsonschema == 2.5.1 09:35:24 INFO - manifestparser == 1.1 09:35:24 INFO - mozInstall == 1.12 09:35:24 INFO - mozcrash == 0.17 09:35:24 INFO - mozdebug == 0.1 09:35:24 INFO - mozdevice == 0.48 09:35:24 INFO - mozfile == 1.2 09:35:24 INFO - mozhttpd == 0.7 09:35:24 INFO - mozinfo == 0.9 09:35:24 INFO - mozleak == 0.1 09:35:24 INFO - mozlog == 3.3 09:35:24 INFO - moznetwork == 0.27 09:35:24 INFO - mozprocess == 0.23 09:35:24 INFO - mozprofile == 0.28 09:35:24 INFO - mozrunner == 6.12 09:35:24 INFO - mozscreenshot == 0.1 09:35:24 INFO - mozsystemmonitor == 0.3 09:35:24 INFO - moztest == 0.7 09:35:24 INFO - mozversion == 1.4 09:35:24 INFO - psutil == 3.1.1 09:35:24 INFO - requests == 1.2.3 09:35:24 INFO - wsgiref == 0.1.2 09:35:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:24 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')]} 09:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:24 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')]} 09:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:24 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:24 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 09:35:24 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 09:35:24 INFO - Using env: {'DISPLAY': ':0', 09:35:24 INFO - 'HOME': '/home/cltbld', 09:35:24 INFO - 'LANG': 'en_US.UTF-8', 09:35:24 INFO - 'LANGUAGE': 'en_US:en', 09:35:24 INFO - 'LOGNAME': 'cltbld', 09:35:24 INFO - 'MAIL': '/var/mail/cltbld', 09:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:24 INFO - 'MOZ_NO_REMOTE': '1', 09:35:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:24 INFO - 'NO_EM_RESTART': '1', 09:35:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:24 INFO - 'PWD': '/builds/slave/test', 09:35:24 INFO - 'SHELL': '/bin/bash', 09:35:24 INFO - 'SHLVL': '1', 09:35:24 INFO - 'TERM': 'linux', 09:35:24 INFO - 'TMOUT': '86400', 09:35:24 INFO - 'USER': 'cltbld', 09:35:24 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:24 INFO - '_': '/tools/buildbot/bin/python'} 09:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 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)) 09:35:24 INFO - Cleaning up... 09:35:24 INFO - Return code: 0 09:35:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:35:24 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')]} 09:35:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:24 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')]} 09:35:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:24 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 0x279e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29018a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, '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': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', '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 09:35:24 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 09:35:24 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 09:35:24 INFO - Using env: {'DISPLAY': ':0', 09:35:24 INFO - 'HOME': '/home/cltbld', 09:35:24 INFO - 'LANG': 'en_US.UTF-8', 09:35:24 INFO - 'LANGUAGE': 'en_US:en', 09:35:24 INFO - 'LOGNAME': 'cltbld', 09:35:24 INFO - 'MAIL': '/var/mail/cltbld', 09:35:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:24 INFO - 'MOZ_NO_REMOTE': '1', 09:35:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:24 INFO - 'NO_EM_RESTART': '1', 09:35:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:24 INFO - 'PWD': '/builds/slave/test', 09:35:24 INFO - 'SHELL': '/bin/bash', 09:35:24 INFO - 'SHLVL': '1', 09:35:24 INFO - 'TERM': 'linux', 09:35:24 INFO - 'TMOUT': '86400', 09:35:24 INFO - 'USER': 'cltbld', 09:35:24 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:24 INFO - '_': '/tools/buildbot/bin/python'} 09:35:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:35:24 INFO - Cleaning up... 09:35:24 INFO - Return code: 0 09:35:24 INFO - Running post-action listener: _resource_record_post_action 09:35:24 INFO - Running post-action listener: _start_resource_monitoring 09:35:24 INFO - Starting resource monitoring. 09:35:24 INFO - [mozharness: 2016-07-27 16:35:24.556648Z] Finished create-virtualenv step (success) 09:35:24 INFO - [mozharness: 2016-07-27 16:35:24.557135Z] Running install step. 09:35:24 INFO - Running pre-action listener: _resource_record_pre_action 09:35:24 INFO - Running main action method: install 09:35:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:24 INFO - Reading from file tmpfile_stdout 09:35:24 INFO - Detecting whether we're running mozinstall >=1.0... 09:35:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:35:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:35:24 INFO - Reading from file tmpfile_stdout 09:35:24 INFO - Output received: 09:35:24 INFO - Usage: mozinstall [options] installer 09:35:24 INFO - Options: 09:35:24 INFO - -h, --help show this help message and exit 09:35:24 INFO - -d DEST, --destination=DEST 09:35:24 INFO - Directory to install application into. [default: 09:35:24 INFO - "/builds/slave/test"] 09:35:24 INFO - --app=APP Application being installed. [default: firefox] 09:35:24 INFO - mkdir: /builds/slave/test/build/application 09:35:24 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'] 09:35:24 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 09:35:41 INFO - Reading from file tmpfile_stdout 09:35:41 INFO - Output received: 09:35:41 INFO - /builds/slave/test/build/application/firefox/firefox 09:35:41 INFO - Running post-action listener: _resource_record_post_action 09:35:41 INFO - [mozharness: 2016-07-27 16:35:41.247694Z] Finished install step (success) 09:35:41 INFO - [mozharness: 2016-07-27 16:35:41.247970Z] Running run-tests step. 09:35:41 INFO - Running pre-action listener: _resource_record_pre_action 09:35:41 INFO - Running main action method: run_tests 09:35:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:35:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:35:41 INFO - Python 2.7.3 09:35:41 INFO - Return code: 0 09:35:41 INFO - grabbing minidump binary from tooltool 09:35: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')]} 09:35:41 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 0x2a5d3b0>, '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 0x2a5e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:35:41 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 09:35:41 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 09:35:41 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 09:35:41 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:35:41 INFO - Return code: 0 09:35:41 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:35:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:35:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:35:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:35:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:35:41 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/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 09:35:41 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/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-030 --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 09:35:41 INFO - Using env: {'DISPLAY': ':0', 09:35:41 INFO - 'HOME': '/home/cltbld', 09:35:41 INFO - 'LANG': 'en_US.UTF-8', 09:35:41 INFO - 'LANGUAGE': 'en_US:en', 09:35:41 INFO - 'LOGNAME': 'cltbld', 09:35:41 INFO - 'MAIL': '/var/mail/cltbld', 09:35:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:35:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:35:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:41 INFO - 'MOZ_NO_REMOTE': '1', 09:35:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:35:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:35:41 INFO - 'NO_EM_RESTART': '1', 09:35:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:35:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:41 INFO - 'PWD': '/builds/slave/test', 09:35:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:35:41 INFO - 'SHELL': '/bin/bash', 09:35:41 INFO - 'SHLVL': '1', 09:35:41 INFO - 'TERM': 'linux', 09:35:41 INFO - 'TMOUT': '86400', 09:35:41 INFO - 'USER': 'cltbld', 09:35:41 INFO - 'XDG_SESSION_COOKIE': '4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461', 09:35:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:41 INFO - '_': '/tools/buildbot/bin/python'} 09:35:41 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/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-030', '--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 09:35:41 INFO - mozversion application_buildid: 20160727074713 09:35:41 INFO - mozversion application_changeset: 6b54e59980fb1cad35f72c88a729f4199b8402b8 09:35:41 INFO - mozversion application_display_name: Nightly 09:35:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:35:41 INFO - mozversion application_name: Firefox 09:35:41 INFO - mozversion application_remotingname: firefox 09:35:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 09:35:41 INFO - mozversion application_vendor: Mozilla 09:35:41 INFO - mozversion application_version: 50.0a1 09:35:41 INFO - mozversion platform_buildid: 20160727074713 09:35:41 INFO - mozversion platform_changeset: 6b54e59980fb1cad35f72c88a729f4199b8402b8 09:35:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 09:35:41 INFO - mozversion platform_version: 50.0a1 09:35:41 INFO - using testdate: 1469637341 09:35:41 INFO - actual date: 1469637341 09:35:41 INFO - starting webserver on 'localhost:60871' 09:35:41 INFO - SUITE-START | Running 2 tests 09:35:41 INFO - TEST-START | dromaeo_css 09:35:41 INFO - operating with platform_type : linux_ 09:35:41 INFO - Initialising browser for dromaeo_css test... 09:35:41 INFO - TEST-INFO | started process 1751 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoQIpgG/profile http://localhost:60871/getInfo.html) 09:35:44 INFO - PROCESS | 1751 | JavaScript error: , line 0: uncaught exception: undefined 09:35:45 INFO - PROCESS | 1751 | __metrics Screen width/height:1600/1200 09:35:45 INFO - PROCESS | 1751 | colorDepth:24 09:35:45 INFO - PROCESS | 1751 | Browser inner width/height: 1024/697 09:35:45 INFO - PROCESS | 1751 | __metrics 09:35:46 INFO - PROCESS | 1751 | 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" 09:35:46 INFO - PROCESS | 1751 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:35:47 INFO - TEST-INFO | 1751: exit 0 09:35:47 INFO - Browser initialized. 09:35:47 INFO - Running cycle 1/1 for dromaeo_css test... 09:35:47 INFO - TEST-INFO | started process 1827 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoQIpgG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:59 INFO - PROCESS | 1827 | RSS: Main: 159068160 09:35:59 INFO - PROCESS | 1827 | 09:36:50 INFO - PROCESS | 1827 | 1469637410847 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] 09:39:03 INFO - PROCESS | 1827 | Cycle 1(1): loaded http://localhost:60871/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60871/tests/dromaeo/cssquery-ext.html) 09:39:04 INFO - PROCESS | 1827 | RSS: Main: 268677120 09:39:04 INFO - PROCESS | 1827 | 09:42:07 INFO - PROCESS | 1827 | Cycle 1(1): loaded http://localhost:60871/tests/dromaeo/cssquery-ext.html (next: http://localhost:60871/tests/dromaeo/cssquery-jquery.html) 09:42:07 INFO - PROCESS | 1827 | RSS: Main: 244998144 09:42:07 INFO - PROCESS | 1827 | 09:45:10 INFO - PROCESS | 1827 | Cycle 1(1): loaded http://localhost:60871/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60871/tests/dromaeo/cssquery-mootools.html) 09:45:10 INFO - PROCESS | 1827 | RSS: Main: 191873024 09:45:10 INFO - PROCESS | 1827 | 09:48:12 INFO - PROCESS | 1827 | Cycle 1(1): loaded http://localhost:60871/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60871/tests/dromaeo/cssquery-prototype.html) 09:48:13 INFO - PROCESS | 1827 | RSS: Main: 191799296 09:48:13 INFO - PROCESS | 1827 | 09:51:16 INFO - PROCESS | 1827 | Cycle 1(1): loaded http://localhost:60871/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60871/tests/dromaeo/cssquery-yui.html) 09:51:17 INFO - PROCESS | 1827 | RSS: Main: 192118784 09:51:17 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | Cycle 1(1): loaded http://localhost:60871/tests/dromaeo/cssquery-yui.html (next: http://localhost:60871/tests/dromaeo/cssquery-dojo.html) 09:54:20 INFO - PROCESS | 1827 | RSS: Main: 279240704 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | __start_tp_report 09:54:20 INFO - PROCESS | 1827 | _x_x_mozilla_page_load 09:54:20 INFO - PROCESS | 1827 | _x_x_mozilla_page_load_details 09:54:20 INFO - PROCESS | 1827 | |i|pagename|runs| 09:54:20 INFO - PROCESS | 1827 | |0;dojo.html;2609.2563619368484;2617.882195301211;2641.141628970585;2655.5352813257673;2656.840589564626;4654.6276297896175;4707.764611769407;4734.573888350048;4795.448523419807;4803.279508073791;4706.12936606728;4748.192807222772;4795.496472870351;4808.3989501312335;4816.951830481694;4362.738235705852;4382.496012958502;4391.824327026915;4410.206162890679;4410.536893626171;4416.712913660618;4419.734815911055;4463.129689710491;4497.437820272466;4539.364488971547;4527.479339875907;4549.363089167519;4564.6348292136545;4569.520200378962;4576.54234576543;4310.676699247538;4421.712588681726;4424.048829501673;4437.0682156747125;4513.887152821173;4320.135972805451;4321.092570560186;4353.62994145498;4386.144701783153;4408.030233348658;4743.146233677937;4792.640551958617;4832.540908613676;4847.854564363075;4847.903041939141;5095.184770436712;5148.124818780816;5186.273921650972;5203.895922081537;5212.921806172911;5005.624578156653;5185.740712964337;5211.635185536976;5231.3199284092825;5249.160134378481;4776.188048031878;4829.348038014893;4831.51684831514;4885.1206782779445;4890.193118135468;5278.102722537178;5337.119375303031;5338.092524270884;5345.679259244458;5351.86620334487;4885.364902562644;4934.53121953421;5130.48695130484;5132.101882170605;5142.357205349332;5030.974845125824;5069.467705890828;5087.440381558025;5130.692158470429;5156.123459011977;5035.320231768737;5041.6470847040355;5051.06555287273;5054;5102.132637451642;4917.1149193145575;5051.494850514919;5064.620153488503;5065.544101030925;5068.746562671926;4339.392485052095;4344.066025804467;4367.956320436819;4378.365137055172;4443.577860103285;3798.734088613826;3803.7717736935874;3805.4101614249835;3848.749831260959;3851.0757418219987;4431.7340959542535;4432.268675668543;4435.911281774346;4439.62263207631;4469.329600559942;4973;5083.847484575469;5128.743562821918;5150.175971844487;5210.166373380241;4418.756968366706;4436.689431739812;4444.422225110715;4445.444319460067;4482.910341793146;4648.140094082605;4652.95347046532;4658.208104622222;4659.2312268475325;4715.599174070242;4717.5282471753235;4815.6147508199965;4868.634852386014;4877.146499362669;4927.778249978688;5182.97408512955;5216.165413533817;5217;5218.8173413931845;5219.8434046978655;4843.854684359334;4887.218045112765;4889.559939605452;4897.461279259207;4931.802727890844;4960.47914968923;4993.350864387751;5054.494550544916;5054.570361519312;5102.566281866083;5060.1650727631;5067.87330316745;5083.771230294719;5084.771185296597;5144.074066668036;4946.258061290835;4950.975245123751;4951.142014874284;4952.752362381794;4953.801847926043;4888.53558911917;4951.1088004158155;4962.3052772611145;4985.451600324034;5056.443791183041;4962.404511458648;4972.403311602631;5018.42288136869;5028.522290382408;5033.119204139188;5045.041442125985;5056.0140772549;5056.924146137737;5059.696418214919;5100.770465328995;4793.832215872428;4827.831025914076;4864.3189953405845;4889.3888263967;4892.633052521146 09:54:20 INFO - PROCESS | 1827 | |1;ext.html;572.330373463048;576.8557860534867;581.7963712700555;587.7032098790111;593.6171169595609;26818.865905670467;37864.81067594662;39150.021249468744;39228.60771392285;43239.56760432395;39539.20921581574;39574.20851582974;39864;39869.60130398695;39912.210534735874;7655.961720191392;7799.064112306532;7804.687812487494;7806.336461400788;7808;7732.806679832993;7786.922130778705;7824.5305281682995;7828.843423131534;7834.921650783504;7836;7887.881681774749;7887.881681774778;7889.566073865935;7890.329322007637;7800.80497987552;7822.9608851955945;7836.138024817265;7837.294643482114;7842.333401660894;7735.574543400111;7803.921960780376;7814.0623125224765;7824.726134585262;7825.0218722659665;37865;38021.809890950644;38061.43262808453;38170.23659526794;38173.61826381756;2762.3646561290816;2817.21118086936;2818.422223444189;2835.036087730161;3187.346593948235;28632;29685.851570742223;29698.70301296981;29699.40601187986;32394.514082288784;3296.2583418730833;3389.3560223557447;3400.2349471368993;3404.1197812206274;3475.826208689555;261817;264309;265250;266351;266641;50544.49455505471;51741.48258517442;51775;51775.74112129415;51807.22289165665;33351.666483334855;34600.30799383998;34618.48072278918;36762.26475470529;36765;14362.569122926328;14559.27203639835;14585.708285834224;14633.268336583129;14639.121652700873;10991.450427478594;11034.337939723642;11079.279846809932;11084.057855082247;11087.611933582286;19120.808791912183;19247.007228294013;19376;19467.76120746104;19589.706154407697;7300.306470885257;7451.403887688972;7458.440616953751;7503.362214211744;7641.885371719429;794.9602519873984;928.1182876267573;937.9671711490065;967.5259123029665;999.9700008999741;28234.882383821296;28282.15153545397;28425.43149137047;28907;29171.562426563618;914.3919293669635;997.2570435025953;1062.3678911047914;1067.8024565455416;1074.6614816332985;14794.926025369805;15278.851682230039;15376.923115384352;15422.768658470131;15763.684726305639;420915;423725;424551;425426;426291;48233.5176648229;48742.26886596633;48809.511904881205;48817.51182488201;48865.755671221414;1074.1514203778927;1144.9427528623535;1165.7435364219857;1204.361688305182;1206.6802297391023;28348.85825570859;29114.70885291205;29662.851685741432;29688;29840.25399365033;40825.795871020455;41353.793231034855;41358;41373.37939930843;41477.1704565913;251695;252248.17131400312;252930;253174;253593;22654.093836246364;24549.140780072612;25164.622530662422;25772;27028;41346.58653413427;41390.37914431346;41650.375244371964;41778.37332439955;41960.79019604882;35865.46201806923;36254;36269.09327266734;36904.44643330406;37064.25871482609;35915.00007445638;36333.63666363303;36565.634343656224;36576;37366.62633373631;34392.656073439946;34692.653073468944;34760.47859282164;35182.29635407226;35267.470987934685;30802;32825.67174328226;33054;33131;33276;24555.754442456062;25243.242702718948;25336.366590835376;25554.361140971625;25569.23292301234 09:54:20 INFO - PROCESS | 1827 | |2;jquery.html;342.28974877129946;354.2083443503769;355.7971955985088;366.30402235752086;378.4380195409816;16022.999999999985;16947.237374318156;17142.82857171428;17301;17356.132193390342;16159.111248881307;16458.423955161572;17118;17181.91409042953;17222.311107555684;5026.346574945261;5241.18761591952;5248.291480650101;5255.947440525584;5268.288781014571;3715.405535114382;3880.6895448364203;5197.610179236553;5855.61938474;6128.11142384354;6210.31686514483;6218.533609979247;6218.813435596917;6229.408206220402;6235.251769787609;5964.612300200495;6128.877422451548;6143.508519318444;6148.262208534959;6150.723217455203;5159.277701121846;5277.20841873722;5349.572034237291;5361.919571206435;5366.865828354284;20612.15614330631;20873;20942.685859712117;20956.895215523826;21314.14743410277;1894.4222012286248;1924.788273289937;2000.2798992362739;2197.450637340665;2280.521090570982;12844.486220551167;13267.469301227939;13355.465781368734;13656.180629162282;13692.31538423075;1764.5412192829829;1830.1581272614612;1865.2818664814008;1959.657060014506;1963.342280336089;68441;69002.3099769006;69699;70108.64945675239;71549;9589;9812.31937045168;9840.261980351503;9941.453220072965;10160.796784064425;13430.261335626634;13450.86549134496;13465.057445978688;13469.595912122651;13474.124181928144;6847.1783385993;6982.301769823079;7013.228544860061;7042.084529011298;7139.143302803593;6614.654169264829;6670.199576050905;6758.938523750303;6896.448284137357;6931.202911665122;4192.392103145027;4204.306289462204;4234.78826058702;4269.5516970718245;4304.397384366192;1659.6846599146124;1672.1304921440783;1697.1853510315118;1723.8965662060316;1773.140027086862;795.9890938508063;796.9641366138537;798.648594618366;872.6727477196051;905.3436258712512;16180.838191617933;16393.180340982904;16969.745453818203;17215.22531486061;17697.646047079244;795.5107608820533;798.876174193001;848.325581162977;853.9274161696326;868.0104680664048;9186.540672966325;9460.385074970105;9668.806623867624;9766.951165244129;9807.950960245153;401556;403374;416749;416907;418865;74900.25099748933;76987;77009;77584.22415775883;81324;878.6529320918202;890.3500444675424;905.1129892705186;918.3250311021394;920.2224120618067;11627.883721163018;15468.303926323568;16035.35858565691;16109.275082621545;16315.755263671292;39130.2173956525;39209.803950980066;39416.40875386814;39652;41516.1696766069;48471.5152848467;50641.240381393574;50929.23606145837;50999.74500127624;51403.74298128486;5075.670081444694;6663.566868153554;7485.326320631169;7502.3622992046285;7506.3994880408345;10553.683389498327;11164.441777911234;11756.70608234801;11779.175457717878;12052.337121458404;9935.900640993497;10096.495175241356;10205.795884082425;10691.09125724322;10693.358398496115;8310.085890551914;9887.950560247153;10329.74175645615;10403.791924161626;10411.219158562986;9791.804163916539;9834.75413114678;10025.799484010426;10041.748956276151;10222.795544088927;7217.747378841926;7429.92570074314;7437.962810185914;7523.023561774855;7732.613369331623;8849.380543361904;10037.297389182979;10050.497475126069;10217.095863679237;10595.152387808803 09:54:20 INFO - PROCESS | 1827 | |3;mootools.html;1331.9800202996958;1390.4090761426394;1443.3432788081425;1650.4141029934365;1685.5870311773617;7426.888596671048;7940.325072368842;7948.403869709766;7948.483348582338;7949.443538952269;6637.269900310974;6894.931050689492;7302.415806735462;7550.320471157608;7781.9221807781905;2155.849090563665;2180.6292930201826;2181.225664888965;2184.2791878679996;2188.485705859122;2132.4029271803843;2147.8281737460966;2151.62346589347;2152.074607918594;2157.4067131538795;2133.57328534293;2146.624340740372;2152.4080877758583;2153.06341741343;2164.632012557869;2134.8612340197915;2141.0900367343893;2146.677998300252;2148.581026699794;2148.60250853591;2095.2038225474375;2096.3815674376096;2103.3479621317288;2104.5264815416563;2106.0417510033053;7776.494527855672;7783.3384162346265;7816.53100813953;7839.412044096715;7848.175941526171;4369.388285640013;4484.955150448486;4487.967767413513;4489.393931819195;4491.505934347219;2774.5838124281318;2866.756325712317;2932.912012639624;2936.7063293670676;2937.4712551740677;875.4572165257517;917.9816403671889;924.3390975452584;933.0948979489884;943.3773709351794;10356.896431035744;10460.372377657364;10488.213383996155;10513;10546.103581195608;3938.3107956107565;4091.0795071109355;4215.620594146541;4222.408862759216;4225.619694227535;9020.458772473672;9059.68291109808;9063.048379920145;9072.138146876036;9076.364654474255;4490.528494508029;4493.078918821634;4497.977510112429;4499.55004499554;4529.048899731093;3470.9652903471147;3904.590018048121;4072.266991941479;4079.0618157823865;4092.7135100543273;3489.3021395720957;3493.7205023598053;3606.7294952878638;3610.151614370621;3616.4936908832783;1098.7253186703324;1127.4531852051748;1130.6325444230658;1136.0968030415838;1150.2523359816187;770.1720650300837;771.7260372567657;777.6267391652038;793.3335997761908;797.7885860247039;4934.136526107916;4936.851894443173;5098.821541246039;5123.666961647556;5365.731713414314;391.33863770228226;391.5829641431866;399.49264434169027;404.54084613963363;417.7931923697789;3243.61076670801;3254.365398747269;3329.084501762035;3363.075154332578;3409.232922592397;8662.696805611773;9299.581518831668;9329.813403732023;9407.717768466957;9418.670346537976;9368.156865882103;9368.156865882103;9371.531423428802;9381.577828997712;9382.155605995493;6622.205335359788;6887.2079710832895;6906.998485219615;7045.225025247219;7079.256678048834;4959.305697202322;5097.974510127426;5133.939714648116;5254.369475662945;5411.891762164813;8892.15524525195;9005.954970225106;9013.098690130935;9052.094790520896;9124.27005839518;8401.62192701342;8681.8697719533;8779.868301975348;9072.501012444114;9095.95452022762;5621.831345059655;6301.1808464901105;6594.076829243813;6757.358050985148;6767.0526126341365;7127.073480447509;7428.294312040348;7430.96284518574;7431.702731890664;7439.776806695808;6377.808665740035;6416.295605779655;6451.1290975717675;6514.73941042353;6637.502187336066;6581.967090164519;6652.966735166293;6662.200535935719;6692.665366731741;6707.429868461236;5768.105943578584;5871.178035075008;5876.647401155944;5878.3239927408895;5882.029465138205;4512.345709872116;4561.954380456153;4570.314452832102;4629.722216667011;4647.744374059459;4562.520935301745;4588;4589.5639914208095;4609.815607375667;4737.573618374363 09:54:20 INFO - PROCESS | 1827 | |4;prototype.html;285.19755147468004;293.6285598717622;299.28918817807704;308.64814111912426;309.87295208964326;4961.975190124049;4995.825146119886;5002.224655178453;5022.271770593266;5039.697618142914;4889;4927.3101765752735;4942.6293028022865;4950.059488697145;4952.108620448318;3629.927401451969;3745.7565258258264;3749.6876093367378;3836.0985168485386;3861.613838616144;3835.0987517933263;3845.8269377878064;3859.5368555773207;3877.9224415511812;3880.4955355803845;3800.619938006205;3828.846846126152;3835.7342077114627;3837.520309961255;3843.1160833008425;3636.9272614547826;3700.7964561949084;3791.1280405506614;3827.3876179811373;3844.4233364995193;3384;3393.1686736749407;3393.1686736749652;3407.7444191685477;3414.3683418567643;4736.881577960544;4748.121597504472;4762.761861906925;4764.475367882279;4782.641301902371;4252.808623611945;4382.671299652507;4401.097774956158;4416.447944006999;4435.334699795024;5086.643934924557;5098.643094983353;5100.183970564692;5103.719295438749;5109.718965456898;3724.348239058154;3725.385311423639;3726.4224045273027;3757.6993840492696;3761.680257178116;5194.3247377840635;5383.865403364947;5417.29355253094;5431.592630552685;5435.728213589305;3782;3787.981060094682;3795.8671446499793;3806.4861243731743;3823.9426408603895;3888.747231429948;3889.844406223776;3891.2412079644764;3893.4159876018257;3897.3179693553516;4398.670099742468;4399.934000989988;4400.273954797487;4410.823567057346;4411.382406463098;4889.48660390661;5091.363579552556;5100.897982040338;5144.408393034774;5192.143296356059;1286.2411177405188;1302.1210682789078;1302.3618426970907;1304.9086563940432;1309.7380523895258;821.8479581277483;822.366777581259;822.8930239068883;825.7398919340509;843.1526316052328;4446.444194475691;4459.420275364182;4465.642748580106;4562.338460923148;4573.382593349922;5293.364796224478;5294.470552944752;5303;5308.256844041837;5341.973290133525;4399.934000989988;4529.89359947626;4541.545845415432;4555.567221113989;4582.221022426195;2808.66296044476;2822.3066160304065;2827.2931767058235;2833.4191490744347;2835.8582070896787;4966.255061740768;4966.304717339575;4983.632774432188;5032.622553308516;5034.219695947134;5257.947420525746;5390.51485366319;5452.882159157363;5484.478974497416;5487.091841324104;4933.186024305949;4943.887625284426;4947.925781113287;4963.60291176705;4979.502049794992;5295.391030031596;5298.377303234769;5311.256424100707;5364.731763411735;5373.382061062872;5091.261767043832;5194.3766747990485;5215.713135777569;5228.9738551307;5247.317848679647;4910.607151427948;4914.533119353656;4918.458969513279;4923.753812309298;4952.306677065142;3366.444536651425;3418.4188687922615;3426.623071462187;3439.9656003440646;3458.636843131435;4575.862724118282;4730.2431610942085;4753.47711751714;4762.595179409789;4771.6898401603785;4584.243599805995;4584.959692936744;4599.333096700892;4604.447466304065;4634;4562.467830354052;4590.357349971074;4605.378273932976;4605.7466839322815;4630.606398456169;4271.444712187395;4537.727736335831;4550.453945526558;4559.521250268674;4617.445906491243;4663.341895119749;4903.975480122576;5001.699898006132;5132.7433628317685;5161.354830646169;4993.525615066563;5015.1975683890405;5035.571976382049;5042.546170844495;5080.49195080489 09:54:20 INFO - PROCESS | 1827 | |5;yui.html;597.4981015946605;640.4172203295;658.1969996604147;679.0561120043141;715.7709532949457;11872;12698.238105713652;12734.61796146117;12794.808077878839;12825.679358016054;12619.369031548431;12759.106862519628;12778.68053298668;12784.360781960911;12796.616101516945;616.1927874484439;634.5241069198102;754.1026178847181;766.7584710816103;782.1279273609941;770.8381239939591;788.6214616983851;788.6648174525832;788.7909703928464;789.1161898673494;820.5322965909434;823.9382046346548;824.1058451580043;824.7855557555049;826.1036775099025;646.5700309294314;777.4518964130275;805.613305613303;823.7240524424325;824.0605709491155;588.9411058894119;589.9734511946973;590.5334785519434;590.8079874040911;590.9409059094056;12659.683507912283;12694.492220311176;12726.427310771038;12770.553030643976;12792.48830046797;11576;11798.174127811057;11991.400429978466;12028.27830330183;12612.180208286523;11476.4835582399;11551.53793848245;11564.710882227928;11607.07143428532;11777;3980.0646847990697;3987.182627561372;4070.1656160487028;4149.688773342012;4182.435371224876;61953.38046619521;62081.37918620801;63459;63759.68120159462;64656.67671661659;9036.45781253127;9151.816963660689;9414.058594140668;9596.136347728738;9612;17735.82264177342;18038.5490362742;18088.27646894136;18102.818971810375;18266.26934922615;6403.903941440882;6518.217813862272;6651.800445986628;6748.156480439945;6841.760538381232;3645.872394466234;3646.872359467406;3661.908452288661;3676.356637588411;3752;2663.7868970482314;2736.493748656505;2753;2763.4749397614387;2768.29408500835;488.97555122243745;489.1781806565005;490.2009724149613;505.05050505051;508.16153346977046;541.8645338665334;546.8386825886373;574.0442163797314;576.8266591212574;579.3655946738314;7446.702131914771;7631.847363052819;7821.960890195513;7890.210978902179;7978.162292959156;479.4055371339584;492.6576029659352;501.8544622059635;512.3518748782718;514.1157209599482;3595.2989167112164;3720.795356255378;3726.087108658396;3726.888193354204;3852.171783066654;55028;66344.66827665927;97399.07802765928;100634.4968275154;100837;84942;86240.56879715687;87568.5621571888;87630.56184719036;87969.12030879737;73551.63224183951;75341.62329188429;75851;75936;79738.60130699309;6715.865682686222;6836.453083753388;6939.930600694131;7031.894521582078;7101.786946391617;45819.31271031004;46741;46840.06319873652;47070.76464617655;49076.50923490719;38187.23625527418;47282.52717472919;53296.46703532915;55037.174442382595;55364.44635553593;11069.944650276697;11408.429578521207;11654.242474239147;11801.29192248468;11877.168598198388;13033.283169425773;13146.671333216746;13484.05611607178;13639.249841258428;14022.579322620337;11283.548658053915;11777.352245626573;12187.57343493009;12680.492780289054;12683.42924568415;11580.88419115798;11856.288622682667;12145.332006739714;12253.203541769757;12297.32364719949;11597.536098555964;11936.4031798408;12272.263664180178;12322.137450378388;12355.75288494243;10170.745731356776;10221.488925553842;10331.845022324822;10364.689059328231;10532.789344213226;8478.872816907628;8968.506732129796;9037.441110835607;9045.68340108093;9154.862677059717 09:54:20 INFO - PROCESS | 1827 | __end_tp_report 09:54:20 INFO - PROCESS | 1827 | __start_cc_report 09:54:20 INFO - PROCESS | 1827 | _x_x_mozilla_cycle_collect,1082 09:54:20 INFO - PROCESS | 1827 | __end_cc_report 09:54:20 INFO - PROCESS | 1827 | __startTimestamp1469638460510__endTimestamp 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | ------- Summary: start ------- 09:54:20 INFO - PROCESS | 1827 | Number of tests: 6 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | [#0] dojo.html Cycles:165 Average:4747.60 Median:4885.36 stddev:498.67 (10.2%) stddev-sans-first:471.14 09:54:20 INFO - PROCESS | 1827 | Values: 2609.3 2617.9 2641.1 2655.5 2656.8 4654.6 4707.8 4734.6 4795.4 4803.3 4706.1 4748.2 4795.5 4808.4 4817.0 4362.7 4382.5 4391.8 4410.2 4410.5 4416.7 4419.7 4463.1 4497.4 4539.4 4527.5 4549.4 4564.6 4569.5 4576.5 4310.7 4421.7 4424.0 4437.1 4513.9 4320.1 4321.1 4353.6 4386.1 4408.0 4743.1 4792.6 4832.5 4847.9 4847.9 5095.2 5148.1 5186.3 5203.9 5212.9 5005.6 5185.7 5211.6 5231.3 5249.2 4776.2 4829.3 4831.5 4885.1 4890.2 5278.1 5337.1 5338.1 5345.7 5351.9 4885.4 4934.5 5130.5 5132.1 5142.4 5031.0 5069.5 5087.4 5130.7 5156.1 5035.3 5041.6 5051.1 5054.0 5102.1 4917.1 5051.5 5064.6 5065.5 5068.7 4339.4 4344.1 4368.0 4378.4 4443.6 3798.7 3803.8 3805.4 3848.7 3851.1 4431.7 4432.3 4435.9 4439.6 4469.3 4973.0 5083.8 5128.7 5150.2 5210.2 4418.8 4436.7 4444.4 4445.4 4482.9 4648.1 4653.0 4658.2 4659.2 4715.6 4717.5 4815.6 4868.6 4877.1 4927.8 5183.0 5216.2 5217.0 5218.8 5219.8 4843.9 4887.2 4889.6 4897.5 4931.8 4960.5 4993.4 5054.5 5054.6 5102.6 5060.2 5067.9 5083.8 5084.8 5144.1 4946.3 4951.0 4951.1 4952.8 4953.8 4888.5 4951.1 4962.3 4985.5 5056.4 4962.4 4972.4 5018.4 5028.5 5033.1 5045.0 5056.0 5056.9 5059.7 5100.8 4793.8 4827.8 4864.3 4889.4 4892.6 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | [#1] ext.html Cycles:180 Average:46410.07 Median:36301.36 stddev:85335.84 (235.1%) stddev-sans-first:85505.83 09:54:20 INFO - PROCESS | 1827 | Values: 572.3 576.9 581.8 587.7 593.6 26818.9 37864.8 39150.0 39228.6 43239.6 39539.2 39574.2 39864.0 39869.6 39912.2 7656.0 7799.1 7804.7 7806.3 7808.0 7732.8 7786.9 7824.5 7828.8 7834.9 7836.0 7887.9 7887.9 7889.6 7890.3 7800.8 7823.0 7836.1 7837.3 7842.3 7735.6 7803.9 7814.1 7824.7 7825.0 37865.0 38021.8 38061.4 38170.2 38173.6 2762.4 2817.2 2818.4 2835.0 3187.3 28632.0 29685.9 29698.7 29699.4 32394.5 3296.3 3389.4 3400.2 3404.1 3475.8 261817.0 264309.0 265250.0 266351.0 266641.0 50544.5 51741.5 51775.0 51775.7 51807.2 33351.7 34600.3 34618.5 36762.3 36765.0 14362.6 14559.3 14585.7 14633.3 14639.1 10991.5 11034.3 11079.3 11084.1 11087.6 19120.8 19247.0 19376.0 19467.8 19589.7 7300.3 7451.4 7458.4 7503.4 7641.9 795.0 928.1 938.0 967.5 1000.0 28234.9 28282.2 28425.4 28907.0 29171.6 914.4 997.3 1062.4 1067.8 1074.7 14794.9 15278.9 15376.9 15422.8 15763.7 420915.0 423725.0 424551.0 425426.0 426291.0 48233.5 48742.3 48809.5 48817.5 48865.8 1074.2 1144.9 1165.7 1204.4 1206.7 28348.9 29114.7 29662.9 29688.0 29840.3 40825.8 41353.8 41358.0 41373.4 41477.2 251695.0 252248.2 252930.0 253174.0 253593.0 22654.1 24549.1 25164.6 25772.0 27028.0 41346.6 41390.4 41650.4 41778.4 41960.8 35865.5 36254.0 36269.1 36904.4 37064.3 35915.0 36333.6 36565.6 36576.0 37366.6 34392.7 34692.7 34760.5 35182.3 35267.5 30802.0 32825.7 33054.0 33131.0 33276.0 24555.8 25243.2 25336.4 25554.4 25569.2 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | [#2] jquery.html Cycles:180 Average:25065.74 Median:4286.97 stddev:67843.86 (1582.6%) stddev-sans-first:68008.79 09:54:20 INFO - PROCESS | 1827 | Values: 342.3 354.2 355.8 366.3 378.4 16023.0 16947.2 17142.8 17301.0 17356.1 16159.1 16458.4 17118.0 17181.9 17222.3 5026.3 5241.2 5248.3 5255.9 5268.3 3715.4 3880.7 5197.6 5855.6 6128.1 6210.3 6218.5 6218.8 6229.4 6235.3 5964.6 6128.9 6143.5 6148.3 6150.7 5159.3 5277.2 5349.6 5361.9 5366.9 20612.2 20873.0 20942.7 20956.9 21314.1 1894.4 1924.8 2000.3 2197.5 2280.5 12844.5 13267.5 13355.5 13656.2 13692.3 1764.5 1830.2 1865.3 1959.7 1963.3 68441.0 69002.3 69699.0 70108.6 71549.0 9589.0 9812.3 9840.3 9941.5 10160.8 13430.3 13450.9 13465.1 13469.6 13474.1 6847.2 6982.3 7013.2 7042.1 7139.1 6614.7 6670.2 6758.9 6896.4 6931.2 4192.4 4204.3 4234.8 4269.6 4304.4 1659.7 1672.1 1697.2 1723.9 1773.1 796.0 797.0 798.6 872.7 905.3 16180.8 16393.2 16969.7 17215.2 17697.6 795.5 798.9 848.3 853.9 868.0 9186.5 9460.4 9668.8 9767.0 9808.0 401556.0 403374.0 416749.0 416907.0 418865.0 74900.3 76987.0 77009.0 77584.2 81324.0 878.7 890.4 905.1 918.3 920.2 11627.9 15468.3 16035.4 16109.3 16315.8 39130.2 39209.8 39416.4 39652.0 41516.2 48471.5 50641.2 50929.2 50999.7 51403.7 5075.7 6663.6 7485.3 7502.4 7506.4 10553.7 11164.4 11756.7 11779.2 12052.3 9935.9 10096.5 10205.8 10691.1 10693.4 8310.1 9888.0 10329.7 10403.8 10411.2 9791.8 9834.8 10025.8 10041.7 10222.8 7217.7 7429.9 7438.0 7523.0 7732.6 8849.4 10037.3 10050.5 10217.1 10595.2 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | [#3] mootools.html Cycles:180 Average:5006.78 Median:4935.49 stddev:2838.49 (57.5%) stddev-sans-first:2833.02 09:54:20 INFO - PROCESS | 1827 | Values: 1332.0 1390.4 1443.3 1650.4 1685.6 7426.9 7940.3 7948.4 7948.5 7949.4 6637.3 6894.9 7302.4 7550.3 7781.9 2155.8 2180.6 2181.2 2184.3 2188.5 2132.4 2147.8 2151.6 2152.1 2157.4 2133.6 2146.6 2152.4 2153.1 2164.6 2134.9 2141.1 2146.7 2148.6 2148.6 2095.2 2096.4 2103.3 2104.5 2106.0 7776.5 7783.3 7816.5 7839.4 7848.2 4369.4 4485.0 4488.0 4489.4 4491.5 2774.6 2866.8 2932.9 2936.7 2937.5 875.5 918.0 924.3 933.1 943.4 10356.9 10460.4 10488.2 10513.0 10546.1 3938.3 4091.1 4215.6 4222.4 4225.6 9020.5 9059.7 9063.0 9072.1 9076.4 4490.5 4493.1 4498.0 4499.6 4529.0 3471.0 3904.6 4072.3 4079.1 4092.7 3489.3 3493.7 3606.7 3610.2 3616.5 1098.7 1127.5 1130.6 1136.1 1150.3 770.2 771.7 777.6 793.3 797.8 4934.1 4936.9 5098.8 5123.7 5365.7 391.3 391.6 399.5 404.5 417.8 3243.6 3254.4 3329.1 3363.1 3409.2 8662.7 9299.6 9329.8 9407.7 9418.7 9368.2 9368.2 9371.5 9381.6 9382.2 6622.2 6887.2 6907.0 7045.2 7079.3 4959.3 5098.0 5133.9 5254.4 5411.9 8892.2 9006.0 9013.1 9052.1 9124.3 8401.6 8681.9 8779.9 9072.5 9096.0 5621.8 6301.2 6594.1 6757.4 6767.1 7127.1 7428.3 7431.0 7431.7 7439.8 6377.8 6416.3 6451.1 6514.7 6637.5 6582.0 6653.0 6662.2 6692.7 6707.4 5768.1 5871.2 5876.6 5878.3 5882.0 4512.3 4562.0 4570.3 4629.7 4647.7 4562.5 4588.0 4589.6 4609.8 4737.6 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | [#4] prototype.html Cycles:180 Average:4203.56 Median:4604.91 stddev:1213.85 (26.4%) stddev-sans-first:1181.09 09:54:20 INFO - PROCESS | 1827 | Values: 285.2 293.6 299.3 308.6 309.9 4962.0 4995.8 5002.2 5022.3 5039.7 4889.0 4927.3 4942.6 4950.1 4952.1 3629.9 3745.8 3749.7 3836.1 3861.6 3835.1 3845.8 3859.5 3877.9 3880.5 3800.6 3828.8 3835.7 3837.5 3843.1 3636.9 3700.8 3791.1 3827.4 3844.4 3384.0 3393.2 3393.2 3407.7 3414.4 4736.9 4748.1 4762.8 4764.5 4782.6 4252.8 4382.7 4401.1 4416.4 4435.3 5086.6 5098.6 5100.2 5103.7 5109.7 3724.3 3725.4 3726.4 3757.7 3761.7 5194.3 5383.9 5417.3 5431.6 5435.7 3782.0 3788.0 3795.9 3806.5 3823.9 3888.7 3889.8 3891.2 3893.4 3897.3 4398.7 4399.9 4400.3 4410.8 4411.4 4889.5 5091.4 5100.9 5144.4 5192.1 1286.2 1302.1 1302.4 1304.9 1309.7 821.8 822.4 822.9 825.7 843.2 4446.4 4459.4 4465.6 4562.3 4573.4 5293.4 5294.5 5303.0 5308.3 5342.0 4399.9 4529.9 4541.5 4555.6 4582.2 2808.7 2822.3 2827.3 2833.4 2835.9 4966.3 4966.3 4983.6 5032.6 5034.2 5257.9 5390.5 5452.9 5484.5 5487.1 4933.2 4943.9 4947.9 4963.6 4979.5 5295.4 5298.4 5311.3 5364.7 5373.4 5091.3 5194.4 5215.7 5229.0 5247.3 4910.6 4914.5 4918.5 4923.8 4952.3 3366.4 3418.4 3426.6 3440.0 3458.6 4575.9 4730.2 4753.5 4762.6 4771.7 4584.2 4585.0 4599.3 4604.4 4634.0 4562.5 4590.4 4605.4 4605.7 4630.6 4271.4 4537.7 4550.5 4559.5 4617.4 4663.3 4904.0 5001.7 5132.7 5161.4 4993.5 5015.2 5035.6 5042.5 5080.5 09:54:20 INFO - PROCESS | 1827 | 09:54:20 INFO - PROCESS | 1827 | [#5] yui.html Cycles:180 Average:17171.01 Median:489.69 stddev:24352.25 (4973.0%) stddev-sans-first:24388.77 09:54:20 INFO - PROCESS | 1827 | Values: 597.5 640.4 658.2 679.1 715.8 11872.0 12698.2 12734.6 12794.8 12825.7 12619.4 12759.1 12778.7 12784.4 12796.6 616.2 634.5 754.1 766.8 782.1 770.8 788.6 788.7 788.8 789.1 820.5 823.9 824.1 824.8 826.1 646.6 777.5 805.6 823.7 824.1 588.9 590.0 590.5 590.8 590.9 12659.7 12694.5 12726.4 12770.6 12792.5 11576.0 11798.2 11991.4 12028.3 12612.2 11476.5 11551.5 11564.7 11607.1 11777.0 3980.1 3987.2 4070.2 4149.7 4182.4 61953.4 62081.4 63459.0 63759.7 64656.7 9036.5 9151.8 9414.1 9596.1 9612.0 17735.8 18038.5 18088.3 18102.8 18266.3 6403.9 6518.2 6651.8 6748.2 6841.8 3645.9 3646.9 3661.9 3676.4 3752.0 2663.8 2736.5 2753.0 2763.5 2768.3 489.0 489.2 490.2 505.1 508.2 541.9 546.8 574.0 576.8 579.4 7446.7 7631.8 7822.0 7890.2 7978.2 479.4 492.7 501.9 512.4 514.1 3595.3 3720.8 3726.1 3726.9 3852.2 55028.0 66344.7 97399.1 100634.5 100837.0 84942.0 86240.6 87568.6 87630.6 87969.1 73551.6 75341.6 75851.0 75936.0 79738.6 6715.9 6836.5 6939.9 7031.9 7101.8 45819.3 46741.0 46840.1 47070.8 49076.5 38187.2 47282.5 53296.5 55037.2 55364.4 11069.9 11408.4 11654.2 11801.3 11877.2 13033.3 13146.7 13484.1 13639.2 14022.6 11283.5 11777.4 12187.6 12680.5 12683.4 11580.9 11856.3 12145.3 12253.2 12297.3 11597.5 11936.4 12272.3 12322.1 12355.8 10170.7 10221.5 10331.8 10364.7 10532.8 8478.9 8968.5 9037.4 9045.7 9154.9 09:54:20 INFO - PROCESS | 1827 | -------- Summary: end -------- 09:54:20 INFO - PROCESS | 1827 | 09:54:21 INFO - TEST-INFO | 1827: exit 0 09:54:21 INFO - TEST-OK | dromaeo_css | took 1119859ms 09:54:21 INFO - TEST-START | kraken 09:54:21 INFO - operating with platform_type : linux_ 09:54:21 INFO - Initialising browser for kraken test... 09:54:21 INFO - TEST-INFO | started process 2012 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXkjd9i/profile http://localhost:60871/getInfo.html) 09:54:24 INFO - PROCESS | 2012 | JavaScript error: , line 0: uncaught exception: undefined 09:54:25 INFO - PROCESS | 2012 | __metrics Screen width/height:1600/1200 09:54:25 INFO - PROCESS | 2012 | colorDepth:24 09:54:25 INFO - PROCESS | 2012 | Browser inner width/height: 1024/697 09:54:25 INFO - PROCESS | 2012 | __metrics 09:54:26 INFO - PROCESS | 2012 | 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" 09:54:26 INFO - PROCESS | 2012 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:54:27 INFO - TEST-INFO | 2012: exit 0 09:54:27 INFO - Browser initialized. 09:54:27 INFO - Running cycle 1/1 for kraken test... 09:54:27 INFO - TEST-INFO | started process 2092 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXkjd9i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:54:39 INFO - PROCESS | 2092 | RSS: Main: 158920704 09:54:39 INFO - PROCESS | 2092 | 09:54:42 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60871/tests/kraken/driver.html?testName=audio-beat-detection) 09:54:42 INFO - PROCESS | 2092 | RSS: Main: 214519808 09:54:42 INFO - PROCESS | 2092 | 09:54:45 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60871/tests/kraken/driver.html?testName=audio-dft) 09:54:46 INFO - PROCESS | 2092 | RSS: Main: 191938560 09:54:46 INFO - PROCESS | 2092 | 09:54:49 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60871/tests/kraken/driver.html?testName=audio-fft) 09:54:50 INFO - PROCESS | 2092 | RSS: Main: 272588800 09:54:50 INFO - PROCESS | 2092 | 09:54:52 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60871/tests/kraken/driver.html?testName=audio-oscillator) 09:54:52 INFO - PROCESS | 2092 | RSS: Main: 182644736 09:54:52 INFO - PROCESS | 2092 | 09:54:55 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60871/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:54:55 INFO - PROCESS | 2092 | RSS: Main: 186167296 09:54:55 INFO - PROCESS | 2092 | 09:54:58 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60871/tests/kraken/driver.html?testName=imaging-darkroom) 09:54:59 INFO - PROCESS | 2092 | RSS: Main: 222924800 09:54:59 INFO - PROCESS | 2092 | 09:55:03 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60871/tests/kraken/driver.html?testName=imaging-desaturate) 09:55:03 INFO - PROCESS | 2092 | RSS: Main: 222085120 09:55:03 INFO - PROCESS | 2092 | 09:55:06 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60871/tests/kraken/driver.html?testName=json-parse-financial) 09:55:07 INFO - PROCESS | 2092 | RSS: Main: 222220288 09:55:07 INFO - PROCESS | 2092 | 09:55:08 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60871/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:55:09 INFO - PROCESS | 2092 | RSS: Main: 186736640 09:55:09 INFO - PROCESS | 2092 | 09:55:11 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:55:11 INFO - PROCESS | 2092 | RSS: Main: 191320064 09:55:11 INFO - PROCESS | 2092 | 09:55:14 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:55:14 INFO - PROCESS | 2092 | RSS: Main: 196587520 09:55:14 INFO - PROCESS | 2092 | 09:55:17 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:55:18 INFO - PROCESS | 2092 | RSS: Main: 190070784 09:55:18 INFO - PROCESS | 2092 | 09:55:21 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:55:21 INFO - PROCESS | 2092 | RSS: Main: 194785280 09:55:21 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | Cycle 1(1): loaded http://localhost:60871/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60871/tests/kraken/driver.html?testName=ai-astar) 09:55:23 INFO - PROCESS | 2092 | RSS: Main: 245624832 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | __start_tp_report 09:55:23 INFO - PROCESS | 2092 | _x_x_mozilla_page_load 09:55:23 INFO - PROCESS | 2092 | _x_x_mozilla_page_load_details 09:55:23 INFO - PROCESS | 2092 | |i|pagename|runs| 09:55:23 INFO - PROCESS | 2092 | |0;ai-astar;104;115;114;114;112;114;114;114;114;113 09:55:23 INFO - PROCESS | 2092 | |1;audio-beat-detection;141;127;123;191;123;123;149;127;180;123 09:55:23 INFO - PROCESS | 2092 | |2;audio-dft;163;169;161;161;164;161;161;162;161;162 09:55:23 INFO - PROCESS | 2092 | |3;audio-fft;76;75;76;120;76;76;74;76;105;76 09:55:23 INFO - PROCESS | 2092 | |4;audio-oscillator;85;85;126;84;85;84;120;84;84;123 09:55:23 INFO - PROCESS | 2092 | |5;imaging-gaussian-blur;117;117;117;117;116;117;116;116;116;117 09:55:23 INFO - PROCESS | 2092 | |6;imaging-darkroom;147;146;146;146;147;146;146;146;149;146 09:55:23 INFO - PROCESS | 2092 | |7;imaging-desaturate;95;95;94;94;95;95;94;95;95;95 09:55:23 INFO - PROCESS | 2092 | |8;json-parse-financial;64;62;63;62;61;62;75;61;62;62 09:55:23 INFO - PROCESS | 2092 | |9;json-stringify-tinderbox;65;66;65;65;67;65;65;65;65;65 09:55:23 INFO - PROCESS | 2092 | |10;stanford-crypto-aes;79;79;80;84;78;79;79;79;80;83 09:55:23 INFO - PROCESS | 2092 | |11;stanford-crypto-ccm;133;132;140;128;141;125;135;128;202;130 09:55:23 INFO - PROCESS | 2092 | |12;stanford-crypto-pbkdf2;169;171;174;167;175;167;169;175;170;164 09:55:23 INFO - PROCESS | 2092 | |13;stanford-crypto-sha256-iterative;74;67;68;71;65;65;66;70;67;65 09:55:23 INFO - PROCESS | 2092 | __end_tp_report 09:55:23 INFO - PROCESS | 2092 | __start_cc_report 09:55:23 INFO - PROCESS | 2092 | _x_x_mozilla_cycle_collect,1938 09:55:23 INFO - PROCESS | 2092 | __end_cc_report 09:55:23 INFO - PROCESS | 2092 | __startTimestamp1469638523749__endTimestamp 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | ------- Summary: start ------- 09:55:23 INFO - PROCESS | 2092 | Number of tests: 14 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 09:55:23 INFO - PROCESS | 2092 | Values: 104.0 115.0 114.0 114.0 112.0 114.0 114.0 114.0 114.0 113.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#1] audio-beat-detection Cycles:10 Average:140.70 Median:134.00 stddev:25.32 (18.9%) stddev-sans-first:26.86 09:55:23 INFO - PROCESS | 2092 | Values: 141.0 127.0 123.0 191.0 123.0 123.0 149.0 127.0 180.0 123.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#2] audio-dft Cycles:10 Average:162.50 Median:162.00 stddev:2.51 (1.5%) stddev-sans-first:2.65 09:55:23 INFO - PROCESS | 2092 | Values: 163.0 169.0 161.0 161.0 164.0 161.0 161.0 162.0 161.0 162.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#3] audio-fft Cycles:10 Average:83.00 Median:76.00 stddev:15.96 (21.0%) stddev-sans-first:16.72 09:55:23 INFO - PROCESS | 2092 | Values: 76.0 75.0 76.0 120.0 76.0 76.0 74.0 76.0 105.0 76.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#4] audio-oscillator Cycles:10 Average:96.00 Median:84.00 stddev:18.69 (22.3%) stddev-sans-first:19.40 09:55:23 INFO - PROCESS | 2092 | Values: 85.0 85.0 126.0 84.0 85.0 84.0 120.0 84.0 84.0 123.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:55:23 INFO - PROCESS | 2092 | Values: 117.0 117.0 117.0 117.0 116.0 117.0 116.0 116.0 116.0 117.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:146.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 09:55:23 INFO - PROCESS | 2092 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 146.0 146.0 149.0 146.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 09:55:23 INFO - PROCESS | 2092 | Values: 95.0 95.0 94.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:62.00 stddev:4.17 (6.7%) stddev-sans-first:4.42 09:55:23 INFO - PROCESS | 2092 | Values: 64.0 62.0 63.0 62.0 61.0 62.0 75.0 61.0 62.0 62.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:65.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 09:55:23 INFO - PROCESS | 2092 | Values: 65.0 66.0 65.0 65.0 67.0 65.0 65.0 65.0 65.0 65.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.50 stddev:1.94 (2.4%) stddev-sans-first:2.03 09:55:23 INFO - PROCESS | 2092 | Values: 79.0 79.0 80.0 84.0 78.0 79.0 79.0 79.0 80.0 83.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:134.00 stddev:22.59 (16.9%) stddev-sans-first:23.84 09:55:23 INFO - PROCESS | 2092 | Values: 133.0 132.0 140.0 128.0 141.0 125.0 135.0 128.0 202.0 130.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.10 Median:170.50 stddev:3.70 (2.2%) stddev-sans-first:3.90 09:55:23 INFO - PROCESS | 2092 | Values: 169.0 171.0 174.0 167.0 175.0 167.0 169.0 175.0 170.0 164.0 09:55:23 INFO - PROCESS | 2092 | 09:55:23 INFO - PROCESS | 2092 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:3.01 (4.5%) stddev-sans-first:2.20 09:55:23 INFO - PROCESS | 2092 | Values: 74.0 67.0 68.0 71.0 65.0 65.0 66.0 70.0 67.0 65.0 09:55:23 INFO - PROCESS | 2092 | -------- Summary: end -------- 09:55:23 INFO - PROCESS | 2092 | 09:55:24 INFO - TEST-INFO | 2092: exit 0 09:55:24 INFO - TEST-OK | kraken | took 63527ms 09:55:24 INFO - SUITE-END | took 1183s 09:55:24 INFO - Completed test suite (00:19:43) 09:55:24 INFO - javascript benchmark 09:55:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2609.2563619368484, 2617.882195301211, 2641.141628970585, 2655.5352813257673, 2656.840589564626, 4654.6276297896175, 4707.764611769407, 4734.573888350048, 4795.448523419807, 4803.279508073791, 4706.12936606728, 4748.192807222772, 4795.496472870351, 4808.3989501312335, 4816.951830481694, 4362.738235705852, 4382.496012958502, 4391.824327026915, 4410.206162890679, 4410.536893626171, 4416.712913660618, 4419.734815911055, 4463.129689710491, 4497.437820272466, 4539.364488971547, 4527.479339875907, 4549.363089167519, 4564.6348292136545, 4569.520200378962, 4576.54234576543, 4310.676699247538, 4421.712588681726, 4424.048829501673, 4437.0682156747125, 4513.887152821173, 4320.135972805451, 4321.092570560186, 4353.62994145498, 4386.144701783153, 4408.030233348658, 4743.146233677937, 4792.640551958617, 4832.540908613676, 4847.854564363075, 4847.903041939141, 5095.184770436712, 5148.124818780816, 5186.273921650972, 5203.895922081537, 5212.921806172911, 5005.624578156653, 5185.740712964337, 5211.635185536976, 5231.3199284092825, 5249.160134378481, 4776.188048031878, 4829.348038014893, 4831.51684831514, 4885.1206782779445, 4890.193118135468, 5278.102722537178, 5337.119375303031, 5338.092524270884, 5345.679259244458, 5351.86620334487, 4885.364902562644, 4934.53121953421, 5130.48695130484, 5132.101882170605, 5142.357205349332, 5030.974845125824, 5069.467705890828, 5087.440381558025, 5130.692158470429, 5156.123459011977, 5035.320231768737, 5041.6470847040355, 5051.06555287273, 5054.0, 5102.132637451642, 4917.1149193145575, 5051.494850514919, 5064.620153488503, 5065.544101030925, 5068.746562671926, 4339.392485052095, 4344.066025804467, 4367.956320436819, 4378.365137055172, 4443.577860103285, 3798.734088613826, 3803.7717736935874, 3805.4101614249835, 3848.749831260959, 3851.0757418219987, 4431.7340959542535, 4432.268675668543, 4435.911281774346, 4439.62263207631, 4469.329600559942, 4973.0, 5083.847484575469, 5128.743562821918, 5150.175971844487, 5210.166373380241, 4418.756968366706, 4436.689431739812, 4444.422225110715, 4445.444319460067, 4482.910341793146, 4648.140094082605, 4652.95347046532, 4658.208104622222, 4659.2312268475325, 4715.599174070242, 4717.5282471753235, 4815.6147508199965, 4868.634852386014, 4877.146499362669, 4927.778249978688, 5182.97408512955, 5216.165413533817, 5217.0, 5218.8173413931845, 5219.8434046978655, 4843.854684359334, 4887.218045112765, 4889.559939605452, 4897.461279259207, 4931.802727890844, 4960.47914968923, 4993.350864387751, 5054.494550544916, 5054.570361519312, 5102.566281866083, 5060.1650727631, 5067.87330316745, 5083.771230294719, 5084.771185296597, 5144.074066668036, 4946.258061290835, 4950.975245123751, 4951.142014874284, 4952.752362381794, 4953.801847926043, 4888.53558911917, 4951.1088004158155, 4962.3052772611145, 4985.451600324034, 5056.443791183041, 4962.404511458648, 4972.403311602631, 5018.42288136869, 5028.522290382408, 5033.119204139188, 5045.041442125985, 5056.0140772549, 5056.924146137737, 5059.696418214919, 5100.770465328995, 4793.832215872428, 4827.831025914076, 4864.3189953405845, 4889.3888263967, 4892.633052521146], "value": 4715.252836765342, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [572.330373463048, 576.8557860534867, 581.7963712700555, 587.7032098790111, 593.6171169595609, 26818.865905670467, 37864.81067594662, 39150.021249468744, 39228.60771392285, 43239.56760432395, 39539.20921581574, 39574.20851582974, 39864.0, 39869.60130398695, 39912.210534735874, 7655.961720191392, 7799.064112306532, 7804.687812487494, 7806.336461400788, 7808.0, 7732.806679832993, 7786.922130778705, 7824.5305281682995, 7828.843423131534, 7834.921650783504, 7836.0, 7887.881681774749, 7887.881681774778, 7889.566073865935, 7890.329322007637, 7800.80497987552, 7822.9608851955945, 7836.138024817265, 7837.294643482114, 7842.333401660894, 7735.574543400111, 7803.921960780376, 7814.0623125224765, 7824.726134585262, 7825.0218722659665, 37865.0, 38021.809890950644, 38061.43262808453, 38170.23659526794, 38173.61826381756, 2762.3646561290816, 2817.21118086936, 2818.422223444189, 2835.036087730161, 3187.346593948235, 28632.0, 29685.851570742223, 29698.70301296981, 29699.40601187986, 32394.514082288784, 3296.2583418730833, 3389.3560223557447, 3400.2349471368993, 3404.1197812206274, 3475.826208689555, 261817.0, 264309.0, 265250.0, 266351.0, 266641.0, 50544.49455505471, 51741.48258517442, 51775.0, 51775.74112129415, 51807.22289165665, 33351.666483334855, 34600.30799383998, 34618.48072278918, 36762.26475470529, 36765.0, 14362.569122926328, 14559.27203639835, 14585.708285834224, 14633.268336583129, 14639.121652700873, 10991.450427478594, 11034.337939723642, 11079.279846809932, 11084.057855082247, 11087.611933582286, 19120.808791912183, 19247.007228294013, 19376.0, 19467.76120746104, 19589.706154407697, 7300.306470885257, 7451.403887688972, 7458.440616953751, 7503.362214211744, 7641.885371719429, 794.9602519873984, 928.1182876267573, 937.9671711490065, 967.5259123029665, 999.9700008999741, 28234.882383821296, 28282.15153545397, 28425.43149137047, 28907.0, 29171.562426563618, 914.3919293669635, 997.2570435025953, 1062.3678911047914, 1067.8024565455416, 1074.6614816332985, 14794.926025369805, 15278.851682230039, 15376.923115384352, 15422.768658470131, 15763.684726305639, 420915.0, 423725.0, 424551.0, 425426.0, 426291.0, 48233.5176648229, 48742.26886596633, 48809.511904881205, 48817.51182488201, 48865.755671221414, 1074.1514203778927, 1144.9427528623535, 1165.7435364219857, 1204.361688305182, 1206.6802297391023, 28348.85825570859, 29114.70885291205, 29662.851685741432, 29688.0, 29840.25399365033, 40825.795871020455, 41353.793231034855, 41358.0, 41373.37939930843, 41477.1704565913, 251695.0, 252248.17131400312, 252930.0, 253174.0, 253593.0, 22654.093836246364, 24549.140780072612, 25164.622530662422, 25772.0, 27028.0, 41346.58653413427, 41390.37914431346, 41650.375244371964, 41778.37332439955, 41960.79019604882, 35865.46201806923, 36254.0, 36269.09327266734, 36904.44643330406, 37064.25871482609, 35915.00007445638, 36333.63666363303, 36565.634343656224, 36576.0, 37366.62633373631, 34392.656073439946, 34692.653073468944, 34760.47859282164, 35182.29635407226, 35267.470987934685, 30802.0, 32825.67174328226, 33054.0, 33131.0, 33276.0, 24555.754442456062, 25243.242702718948, 25336.366590835376, 25554.361140971625, 25569.23292301234], "value": 17316.255544443567, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.28974877129946, 354.2083443503769, 355.7971955985088, 366.30402235752086, 378.4380195409816, 16022.999999999985, 16947.237374318156, 17142.82857171428, 17301.0, 17356.132193390342, 16159.111248881307, 16458.423955161572, 17118.0, 17181.91409042953, 17222.311107555684, 5026.346574945261, 5241.18761591952, 5248.291480650101, 5255.947440525584, 5268.288781014571, 3715.405535114382, 3880.6895448364203, 5197.610179236553, 5855.61938474, 6128.11142384354, 6210.31686514483, 6218.533609979247, 6218.813435596917, 6229.408206220402, 6235.251769787609, 5964.612300200495, 6128.877422451548, 6143.508519318444, 6148.262208534959, 6150.723217455203, 5159.277701121846, 5277.20841873722, 5349.572034237291, 5361.919571206435, 5366.865828354284, 20612.15614330631, 20873.0, 20942.685859712117, 20956.895215523826, 21314.14743410277, 1894.4222012286248, 1924.788273289937, 2000.2798992362739, 2197.450637340665, 2280.521090570982, 12844.486220551167, 13267.469301227939, 13355.465781368734, 13656.180629162282, 13692.31538423075, 1764.5412192829829, 1830.1581272614612, 1865.2818664814008, 1959.657060014506, 1963.342280336089, 68441.0, 69002.3099769006, 69699.0, 70108.64945675239, 71549.0, 9589.0, 9812.31937045168, 9840.261980351503, 9941.453220072965, 10160.796784064425, 13430.261335626634, 13450.86549134496, 13465.057445978688, 13469.595912122651, 13474.124181928144, 6847.1783385993, 6982.301769823079, 7013.228544860061, 7042.084529011298, 7139.143302803593, 6614.654169264829, 6670.199576050905, 6758.938523750303, 6896.448284137357, 6931.202911665122, 4192.392103145027, 4204.306289462204, 4234.78826058702, 4269.5516970718245, 4304.397384366192, 1659.6846599146124, 1672.1304921440783, 1697.1853510315118, 1723.8965662060316, 1773.140027086862, 795.9890938508063, 796.9641366138537, 798.648594618366, 872.6727477196051, 905.3436258712512, 16180.838191617933, 16393.180340982904, 16969.745453818203, 17215.22531486061, 17697.646047079244, 795.5107608820533, 798.876174193001, 848.325581162977, 853.9274161696326, 868.0104680664048, 9186.540672966325, 9460.385074970105, 9668.806623867624, 9766.951165244129, 9807.950960245153, 401556.0, 403374.0, 416749.0, 416907.0, 418865.0, 74900.25099748933, 76987.0, 77009.0, 77584.22415775883, 81324.0, 878.6529320918202, 890.3500444675424, 905.1129892705186, 918.3250311021394, 920.2224120618067, 11627.883721163018, 15468.303926323568, 16035.35858565691, 16109.275082621545, 16315.755263671292, 39130.2173956525, 39209.803950980066, 39416.40875386814, 39652.0, 41516.1696766069, 48471.5152848467, 50641.240381393574, 50929.23606145837, 50999.74500127624, 51403.74298128486, 5075.670081444694, 6663.566868153554, 7485.326320631169, 7502.3622992046285, 7506.3994880408345, 10553.683389498327, 11164.441777911234, 11756.70608234801, 11779.175457717878, 12052.337121458404, 9935.900640993497, 10096.495175241356, 10205.795884082425, 10691.09125724322, 10693.358398496115, 8310.085890551914, 9887.950560247153, 10329.74175645615, 10403.791924161626, 10411.219158562986, 9791.804163916539, 9834.75413114678, 10025.799484010426, 10041.748956276151, 10222.795544088927, 7217.747378841926, 7429.92570074314, 7437.962810185914, 7523.023561774855, 7732.613369331623, 8849.380543361904, 10037.297389182979, 10050.497475126069, 10217.095863679237, 10595.152387808803], "value": 8270.776193316477, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.9800202996958, 1390.4090761426394, 1443.3432788081425, 1650.4141029934365, 1685.5870311773617, 7426.888596671048, 7940.325072368842, 7948.403869709766, 7948.483348582338, 7949.443538952269, 6637.269900310974, 6894.931050689492, 7302.415806735462, 7550.320471157608, 7781.9221807781905, 2155.849090563665, 2180.6292930201826, 2181.225664888965, 2184.2791878679996, 2188.485705859122, 2132.4029271803843, 2147.8281737460966, 2151.62346589347, 2152.074607918594, 2157.4067131538795, 2133.57328534293, 2146.624340740372, 2152.4080877758583, 2153.06341741343, 2164.632012557869, 2134.8612340197915, 2141.0900367343893, 2146.677998300252, 2148.581026699794, 2148.60250853591, 2095.2038225474375, 2096.3815674376096, 2103.3479621317288, 2104.5264815416563, 2106.0417510033053, 7776.494527855672, 7783.3384162346265, 7816.53100813953, 7839.412044096715, 7848.175941526171, 4369.388285640013, 4484.955150448486, 4487.967767413513, 4489.393931819195, 4491.505934347219, 2774.5838124281318, 2866.756325712317, 2932.912012639624, 2936.7063293670676, 2937.4712551740677, 875.4572165257517, 917.9816403671889, 924.3390975452584, 933.0948979489884, 943.3773709351794, 10356.896431035744, 10460.372377657364, 10488.213383996155, 10513.0, 10546.103581195608, 3938.3107956107565, 4091.0795071109355, 4215.620594146541, 4222.408862759216, 4225.619694227535, 9020.458772473672, 9059.68291109808, 9063.048379920145, 9072.138146876036, 9076.364654474255, 4490.528494508029, 4493.078918821634, 4497.977510112429, 4499.55004499554, 4529.048899731093, 3470.9652903471147, 3904.590018048121, 4072.266991941479, 4079.0618157823865, 4092.7135100543273, 3489.3021395720957, 3493.7205023598053, 3606.7294952878638, 3610.151614370621, 3616.4936908832783, 1098.7253186703324, 1127.4531852051748, 1130.6325444230658, 1136.0968030415838, 1150.2523359816187, 770.1720650300837, 771.7260372567657, 777.6267391652038, 793.3335997761908, 797.7885860247039, 4934.136526107916, 4936.851894443173, 5098.821541246039, 5123.666961647556, 5365.731713414314, 391.33863770228226, 391.5829641431866, 399.49264434169027, 404.54084613963363, 417.7931923697789, 3243.61076670801, 3254.365398747269, 3329.084501762035, 3363.075154332578, 3409.232922592397, 8662.696805611773, 9299.581518831668, 9329.813403732023, 9407.717768466957, 9418.670346537976, 9368.156865882103, 9368.156865882103, 9371.531423428802, 9381.577828997712, 9382.155605995493, 6622.205335359788, 6887.2079710832895, 6906.998485219615, 7045.225025247219, 7079.256678048834, 4959.305697202322, 5097.974510127426, 5133.939714648116, 5254.369475662945, 5411.891762164813, 8892.15524525195, 9005.954970225106, 9013.098690130935, 9052.094790520896, 9124.27005839518, 8401.62192701342, 8681.8697719533, 8779.868301975348, 9072.501012444114, 9095.95452022762, 5621.831345059655, 6301.1808464901105, 6594.076829243813, 6757.358050985148, 6767.0526126341365, 7127.073480447509, 7428.294312040348, 7430.96284518574, 7431.702731890664, 7439.776806695808, 6377.808665740035, 6416.295605779655, 6451.1290975717675, 6514.73941042353, 6637.502187336066, 6581.967090164519, 6652.966735166293, 6662.200535935719, 6692.665366731741, 6707.429868461236, 5768.105943578584, 5871.178035075008, 5876.647401155944, 5878.3239927408895, 5882.029465138205, 4512.345709872116, 4561.954380456153, 4570.314452832102, 4629.722216667011, 4647.744374059459, 4562.520935301745, 4588.0, 4589.5639914208095, 4609.815607375667, 4737.573618374363], "value": 3959.9740513778534, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.19755147468004, 293.6285598717622, 299.28918817807704, 308.64814111912426, 309.87295208964326, 4961.975190124049, 4995.825146119886, 5002.224655178453, 5022.271770593266, 5039.697618142914, 4889.0, 4927.3101765752735, 4942.6293028022865, 4950.059488697145, 4952.108620448318, 3629.927401451969, 3745.7565258258264, 3749.6876093367378, 3836.0985168485386, 3861.613838616144, 3835.0987517933263, 3845.8269377878064, 3859.5368555773207, 3877.9224415511812, 3880.4955355803845, 3800.619938006205, 3828.846846126152, 3835.7342077114627, 3837.520309961255, 3843.1160833008425, 3636.9272614547826, 3700.7964561949084, 3791.1280405506614, 3827.3876179811373, 3844.4233364995193, 3384.0, 3393.1686736749407, 3393.1686736749652, 3407.7444191685477, 3414.3683418567643, 4736.881577960544, 4748.121597504472, 4762.761861906925, 4764.475367882279, 4782.641301902371, 4252.808623611945, 4382.671299652507, 4401.097774956158, 4416.447944006999, 4435.334699795024, 5086.643934924557, 5098.643094983353, 5100.183970564692, 5103.719295438749, 5109.718965456898, 3724.348239058154, 3725.385311423639, 3726.4224045273027, 3757.6993840492696, 3761.680257178116, 5194.3247377840635, 5383.865403364947, 5417.29355253094, 5431.592630552685, 5435.728213589305, 3782.0, 3787.981060094682, 3795.8671446499793, 3806.4861243731743, 3823.9426408603895, 3888.747231429948, 3889.844406223776, 3891.2412079644764, 3893.4159876018257, 3897.3179693553516, 4398.670099742468, 4399.934000989988, 4400.273954797487, 4410.823567057346, 4411.382406463098, 4889.48660390661, 5091.363579552556, 5100.897982040338, 5144.408393034774, 5192.143296356059, 1286.2411177405188, 1302.1210682789078, 1302.3618426970907, 1304.9086563940432, 1309.7380523895258, 821.8479581277483, 822.366777581259, 822.8930239068883, 825.7398919340509, 843.1526316052328, 4446.444194475691, 4459.420275364182, 4465.642748580106, 4562.338460923148, 4573.382593349922, 5293.364796224478, 5294.470552944752, 5303.0, 5308.256844041837, 5341.973290133525, 4399.934000989988, 4529.89359947626, 4541.545845415432, 4555.567221113989, 4582.221022426195, 2808.66296044476, 2822.3066160304065, 2827.2931767058235, 2833.4191490744347, 2835.8582070896787, 4966.255061740768, 4966.304717339575, 4983.632774432188, 5032.622553308516, 5034.219695947134, 5257.947420525746, 5390.51485366319, 5452.882159157363, 5484.478974497416, 5487.091841324104, 4933.186024305949, 4943.887625284426, 4947.925781113287, 4963.60291176705, 4979.502049794992, 5295.391030031596, 5298.377303234769, 5311.256424100707, 5364.731763411735, 5373.382061062872, 5091.261767043832, 5194.3766747990485, 5215.713135777569, 5228.9738551307, 5247.317848679647, 4910.607151427948, 4914.533119353656, 4918.458969513279, 4923.753812309298, 4952.306677065142, 3366.444536651425, 3418.4188687922615, 3426.623071462187, 3439.9656003440646, 3458.636843131435, 4575.862724118282, 4730.2431610942085, 4753.47711751714, 4762.595179409789, 4771.6898401603785, 4584.243599805995, 4584.959692936744, 4599.333096700892, 4604.447466304065, 4634.0, 4562.467830354052, 4590.357349971074, 4605.378273932976, 4605.7466839322815, 4630.606398456169, 4271.444712187395, 4537.727736335831, 4550.453945526558, 4559.521250268674, 4617.445906491243, 4663.341895119749, 4903.975480122576, 5001.699898006132, 5132.7433628317685, 5161.354830646169, 4993.525615066563, 5015.1975683890405, 5035.571976382049, 5042.546170844495, 5080.49195080489], "value": 3815.399406161156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.4981015946605, 640.4172203295, 658.1969996604147, 679.0561120043141, 715.7709532949457, 11872.0, 12698.238105713652, 12734.61796146117, 12794.808077878839, 12825.679358016054, 12619.369031548431, 12759.106862519628, 12778.68053298668, 12784.360781960911, 12796.616101516945, 616.1927874484439, 634.5241069198102, 754.1026178847181, 766.7584710816103, 782.1279273609941, 770.8381239939591, 788.6214616983851, 788.6648174525832, 788.7909703928464, 789.1161898673494, 820.5322965909434, 823.9382046346548, 824.1058451580043, 824.7855557555049, 826.1036775099025, 646.5700309294314, 777.4518964130275, 805.613305613303, 823.7240524424325, 824.0605709491155, 588.9411058894119, 589.9734511946973, 590.5334785519434, 590.8079874040911, 590.9409059094056, 12659.683507912283, 12694.492220311176, 12726.427310771038, 12770.553030643976, 12792.48830046797, 11576.0, 11798.174127811057, 11991.400429978466, 12028.27830330183, 12612.180208286523, 11476.4835582399, 11551.53793848245, 11564.710882227928, 11607.07143428532, 11777.0, 3980.0646847990697, 3987.182627561372, 4070.1656160487028, 4149.688773342012, 4182.435371224876, 61953.38046619521, 62081.37918620801, 63459.0, 63759.68120159462, 64656.67671661659, 9036.45781253127, 9151.816963660689, 9414.058594140668, 9596.136347728738, 9612.0, 17735.82264177342, 18038.5490362742, 18088.27646894136, 18102.818971810375, 18266.26934922615, 6403.903941440882, 6518.217813862272, 6651.800445986628, 6748.156480439945, 6841.760538381232, 3645.872394466234, 3646.872359467406, 3661.908452288661, 3676.356637588411, 3752.0, 2663.7868970482314, 2736.493748656505, 2753.0, 2763.4749397614387, 2768.29408500835, 488.97555122243745, 489.1781806565005, 490.2009724149613, 505.05050505051, 508.16153346977046, 541.8645338665334, 546.8386825886373, 574.0442163797314, 576.8266591212574, 579.3655946738314, 7446.702131914771, 7631.847363052819, 7821.960890195513, 7890.210978902179, 7978.162292959156, 479.4055371339584, 492.6576029659352, 501.8544622059635, 512.3518748782718, 514.1157209599482, 3595.2989167112164, 3720.795356255378, 3726.087108658396, 3726.888193354204, 3852.171783066654, 55028.0, 66344.66827665927, 97399.07802765928, 100634.4968275154, 100837.0, 84942.0, 86240.56879715687, 87568.5621571888, 87630.56184719036, 87969.12030879737, 73551.63224183951, 75341.62329188429, 75851.0, 75936.0, 79738.60130699309, 6715.865682686222, 6836.453083753388, 6939.930600694131, 7031.894521582078, 7101.786946391617, 45819.31271031004, 46741.0, 46840.06319873652, 47070.76464617655, 49076.50923490719, 38187.23625527418, 47282.52717472919, 53296.46703532915, 55037.174442382595, 55364.44635553593, 11069.944650276697, 11408.429578521207, 11654.242474239147, 11801.29192248468, 11877.168598198388, 13033.283169425773, 13146.671333216746, 13484.05611607178, 13639.249841258428, 14022.579322620337, 11283.548658053915, 11777.352245626573, 12187.57343493009, 12680.492780289054, 12683.42924568415, 11580.88419115798, 11856.288622682667, 12145.332006739714, 12253.203541769757, 12297.32364719949, 11597.536098555964, 11936.4031798408, 12272.263664180178, 12322.137450378388, 12355.75288494243, 10170.745731356776, 10221.488925553842, 10331.845022324822, 10364.689059328231, 10532.789344213226, 8478.872816907628, 8968.506732129796, 9037.441110835607, 9045.68340108093, 9154.862677059717], "value": 6402.574063662189, "unit": "score"}], "name": "dromaeo_css", "value": 6346.346335452818, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 114.0, 114.0, 112.0, 114.0, 114.0, 114.0, 114.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 127.0, 123.0, 191.0, 123.0, 123.0, 149.0, 127.0, 180.0, 123.0], "value": 140.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 169.0, 161.0, 161.0, 164.0, 161.0, 161.0, 162.0, 161.0, 162.0], "value": 162.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 120.0, 76.0, 76.0, 74.0, 76.0, 105.0, 76.0], "value": 83.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 126.0, 84.0, 85.0, 84.0, 120.0, 84.0, 84.0, 123.0], "value": 96.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 116.0, 116.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 146.0, 149.0, 146.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 62.0, 61.0, 62.0, 75.0, 61.0, 62.0, 62.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 67.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 80.0, 84.0, 78.0, 79.0, 79.0, 79.0, 80.0, 83.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 132.0, 140.0, 128.0, 141.0, 125.0, 135.0, 128.0, 202.0, 130.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 174.0, 167.0, 175.0, 167.0, 169.0, 175.0, 170.0, 164.0], "value": 170.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 67.0, 68.0, 71.0, 65.0, 65.0, 66.0, 70.0, 67.0, 65.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1538.8, "alertThreshold": 2.0}]} 09:55:25 INFO - Return code: 0 09:55:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:55:25 INFO - # TBPL SUCCESS # 09:55:25 INFO - Running post-action listener: _resource_record_post_action 09:55:25 INFO - [mozharness: 2016-07-27 16:55:25.070418Z] Finished run-tests step (success) 09:55:25 INFO - Running post-run listener: _resource_record_post_run 09:55:25 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 551706624; Read time: 16; Write time: 489020 09:55:25 INFO - TinderboxPrint: CPU usage
13.0% 09:55:25 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:55:25 INFO - TinderboxPrint: I/O write bytes / time
551,706,624 / 489,020 09:55:25 INFO - TinderboxPrint: CPU idle
8,329.3 (87.0%) 09:55:25 INFO - TinderboxPrint: CPU user
1,205.4 (12.6%) 09:55:25 INFO - TinderboxPrint: Swap in / out
0 / 0 09:55:25 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 262754304; Read time: 0; Write time: 270196 09:55:25 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 288952320; Read time: 0; Write time: 218824 09:55:25 INFO - Running post-run listener: _upload_blobber_files 09:55:25 INFO - Blob upload gear active. 09:55:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:55:25 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 09:55:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:55:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:55:26 INFO - (blobuploader) - INFO - Open directory for files ... 09:55:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:55:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:55:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:27 INFO - (blobuploader) - INFO - Done attempting. 09:55:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:55:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:55:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:27 INFO - (blobuploader) - INFO - Done attempting. 09:55:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:55:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:55:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:32 INFO - (blobuploader) - INFO - Done attempting. 09:55:32 INFO - (blobuploader) - INFO - Iteration through files over. 09:55:32 INFO - Return code: 0 09:55:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:55:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:55:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/107a9714f7faf58789bf651cd607232ce5141626763172afa85a12d9565bd2eae21620ff713064670add2dfeca33809eb98765c8e857e8520a63dd4b6ae17562", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/024a4604e16b871bd890920fecf20be7cf3fba3f23781e40e7f8b2be8d435cb93f4408cfa0dd4bad5b9fa2790acc672ec0d5c43b5e41623c9c7fa0c018ed7e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e68fbf6531258aa636e3ae91321adf44a403633a2e00dac3e454b3f306c04b9983845c0047fee7c024afa1eaee9b1c9dfc78bba1c50978b1b86292d789dd4b3c"} 09:55:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:55:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:55:32 INFO - Contents: 09:55:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/107a9714f7faf58789bf651cd607232ce5141626763172afa85a12d9565bd2eae21620ff713064670add2dfeca33809eb98765c8e857e8520a63dd4b6ae17562", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/024a4604e16b871bd890920fecf20be7cf3fba3f23781e40e7f8b2be8d435cb93f4408cfa0dd4bad5b9fa2790acc672ec0d5c43b5e41623c9c7fa0c018ed7e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e68fbf6531258aa636e3ae91321adf44a403633a2e00dac3e454b3f306c04b9983845c0047fee7c024afa1eaee9b1c9dfc78bba1c50978b1b86292d789dd4b3c"} 09:55:32 INFO - Running post-run listener: copy_logs_to_upload_dir 09:55:32 INFO - Copying logs to upload dir... 09:55:32 INFO - mkdir: /builds/slave/test/build/upload/logs 09:55:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.596364 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-27 09:55:32.547406) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 09:55:32.551469) ========= 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=4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461 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/107a9714f7faf58789bf651cd607232ce5141626763172afa85a12d9565bd2eae21620ff713064670add2dfeca33809eb98765c8e857e8520a63dd4b6ae17562", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/024a4604e16b871bd890920fecf20be7cf3fba3f23781e40e7f8b2be8d435cb93f4408cfa0dd4bad5b9fa2790acc672ec0d5c43b5e41623c9c7fa0c018ed7e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e68fbf6531258aa636e3ae91321adf44a403633a2e00dac3e454b3f306c04b9983845c0047fee7c024afa1eaee9b1c9dfc78bba1c50978b1b86292d789dd4b3c"} build_url:https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011539 build_url: 'https://queue.taskcluster.net/v1/task/OXN65moXRT25kZYSm-cd3w/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/107a9714f7faf58789bf651cd607232ce5141626763172afa85a12d9565bd2eae21620ff713064670add2dfeca33809eb98765c8e857e8520a63dd4b6ae17562", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/024a4604e16b871bd890920fecf20be7cf3fba3f23781e40e7f8b2be8d435cb93f4408cfa0dd4bad5b9fa2790acc672ec0d5c43b5e41623c9c7fa0c018ed7e6e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/e68fbf6531258aa636e3ae91321adf44a403633a2e00dac3e454b3f306c04b9983845c0047fee7c024afa1eaee9b1c9dfc78bba1c50978b1b86292d789dd4b3c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 09:55:32.577045) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 09:55:32.577378) ========= 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=4ac8d263c7293b36a1be1aed000001be-1469637289.733613-795461 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 09:55:32.662792) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 09:55:32.663327) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 09:55:32.663834) ========= ========= Total master_lag: 0.18 =========