builder: autoland_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-027 starttime: 1470218936.72 results: success (0) buildid: 20160803003003 builduid: 7290ea626db545448230f44d81aa8eeb revision: 6849935c81c669e32c93e88d061635648cc94049 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.723761) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.724350) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.724853) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.742877) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.743227) ========= 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=1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-03 03:08:57-- 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% 37.7M=0s 2016-08-03 03:08:57 (37.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.145400 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.904318) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.904848) ========= 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=1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020380 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.954522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:56.955083) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 6849935c81c669e32c93e88d061635648cc94049 --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 6849935c81c669e32c93e88d061635648cc94049 --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=1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-03 03:08:57,280 truncating revision to first 12 chars 2016-08-03 03:08:57,280 Setting DEBUG logging. 2016-08-03 03:08:57,280 attempt 1/10 2016-08-03 03:08:57,280 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/6849935c81c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-03 03:08:57,768 unpacking tar archive at: autoland-6849935c81c6/testing/mozharness/ program finished with exit code 0 elapsedTime=0.871494 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:57.843976) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:57.844373) ========= script_repo_revision: 6849935c81c669e32c93e88d061635648cc94049 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:57.844830) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:57.845142) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-03 03:08:57.856671) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-03 03:08:57.857147) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] 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=1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:08:58 INFO - MultiFileLogger online at 20160803 03:08:58 in /builds/slave/test 03:08:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 03:08:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:08:58 INFO - {'append_to_log': False, 03:08:58 INFO - 'base_work_dir': '/builds/slave/test', 03:08:58 INFO - 'blob_upload_branch': 'Autoland', 03:08:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:08:58 INFO - 'branch': 'Autoland', 03:08:58 INFO - 'buildbot_json_path': 'buildprops.json', 03:08:58 INFO - 'config_files': ('talos/linux_config.py',), 03:08:58 INFO - 'default_actions': ('clobber', 03:08:58 INFO - 'read-buildbot-config', 03:08:58 INFO - 'download-and-extract', 03:08:58 INFO - 'populate-webroot', 03:08:58 INFO - 'create-virtualenv', 03:08:58 INFO - 'install', 03:08:58 INFO - 'run-tests'), 03:08:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:08:58 INFO - 'download_minidump_stackwalk': True, 03:08:58 INFO - 'download_symbols': 'ondemand', 03:08:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:08:58 INFO - 'tooltool.py': '/tools/tooltool.py', 03:08:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:08:58 INFO - '/tools/misc-python/virtualenv.py')}, 03:08:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:08:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:08:58 INFO - 'installer_path': 'installer.exe', 03:08:58 INFO - 'log_level': 'info', 03:08:58 INFO - 'log_name': 'talos', 03:08:58 INFO - 'log_to_console': True, 03:08:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:08:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:08:58 INFO - 'opt_config_files': (), 03:08:58 INFO - 'pip_index': False, 03:08:58 INFO - 'sps_profile': False, 03:08:58 INFO - 'sps_profile_interval': 0, 03:08:58 INFO - 'suite': 'dromaeojs', 03:08:58 INFO - 'system_bits': '32', 03:08:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:08:58 INFO - 'title': 'talos-linux64-ix-027', 03:08:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:08:58 INFO - 'use_talos_json': True, 03:08:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:08:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:08:58 INFO - 'work_dir': 'build'} 03:08:58 INFO - [mozharness: 2016-08-03 10:08:58.275581Z] Running clobber step. 03:08:58 INFO - Running pre-action listener: _resource_record_pre_action 03:08:58 INFO - Running main action method: clobber 03:08:58 INFO - rmtree: /builds/slave/test/build 03:08:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:08:58 INFO - Running post-action listener: _resource_record_post_action 03:08:58 INFO - [mozharness: 2016-08-03 10:08:58.729027Z] Finished clobber step (success) 03:08:58 INFO - [mozharness: 2016-08-03 10:08:58.729129Z] Running read-buildbot-config step. 03:08:58 INFO - Running pre-action listener: _resource_record_pre_action 03:08:58 INFO - Running main action method: read_buildbot_config 03:08:58 INFO - Using buildbot properties: 03:08:58 INFO - { 03:08:58 INFO - "project": "", 03:08:58 INFO - "product": "firefox", 03:08:58 INFO - "script_repo_revision": "production", 03:08:58 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 03:08:58 INFO - "repository": "", 03:08:58 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland pgo talos dromaeojs", 03:08:58 INFO - "buildid": "20160803003003", 03:08:58 INFO - "pgo_build": "True", 03:08:58 INFO - "basedir": "/builds/slave/test", 03:08:58 INFO - "buildnumber": 78, 03:08:58 INFO - "slavename": "talos-linux64-ix-027", 03:08:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:08:58 INFO - "platform": "ubuntu64_hw", 03:08:58 INFO - "scheduler": "tests-autoland-linux64-pgo-talos", 03:08:58 INFO - "branch": "autoland", 03:08:58 INFO - "repo_path": "integration/autoland", 03:08:58 INFO - "revision": "6849935c81c669e32c93e88d061635648cc94049", 03:08:58 INFO - "stage_platform": "linux64-pgo", 03:08:58 INFO - "builduid": "7290ea626db545448230f44d81aa8eeb", 03:08:58 INFO - "slavebuilddir": "test" 03:08:58 INFO - } 03:08:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 03:08:58 INFO - Running post-action listener: _resource_record_post_action 03:08:58 INFO - [mozharness: 2016-08-03 10:08:58.731553Z] Finished read-buildbot-config step (success) 03:08:58 INFO - [mozharness: 2016-08-03 10:08:58.731660Z] Running download-and-extract step. 03:08:58 INFO - Running pre-action listener: _resource_record_pre_action 03:08:58 INFO - Running main action method: download_and_extract 03:08:58 INFO - mkdir: /builds/slave/test/build/tests 03:08:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:08:58 INFO - https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 03:08:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:08:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:08:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 03:09:01 INFO - Downloaded 1361 bytes. 03:09:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 03:09:01 INFO - Using the following test package requirements: 03:09:01 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 03:09:01 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:09:01 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:09:01 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'jsshell-linux-x86_64.zip'], 03:09:01 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:09:01 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 03:09:01 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:09:01 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:09:01 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:09:01 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 03:09:01 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:09:01 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03:09:01 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')]} 03:09:01 INFO - https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:09:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:09:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:09:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:09:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:09:02 INFO - Downloaded 21810734 bytes. 03:09:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:09:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:09:03 INFO - Return code: 0 03:09:03 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:09:03 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')]} 03:09:03 INFO - https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:09:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:09:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:09:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 03:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:09:04 INFO - Downloaded 21810734 bytes. 03:09:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:09:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:09:05 INFO - Return code: 0 03:09: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')]} 03:09:05 INFO - https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:09:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 03:09:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 03:09:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 03:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:09:07 INFO - Downloaded 14413723 bytes. 03:09:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:09:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:09:07 INFO - Return code: 0 03:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:07 INFO - https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:09:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:09:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:09:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:09:10 INFO - Downloaded 59359074 bytes. 03:09:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:09:10 INFO - mkdir: /builds/slave/test/properties 03:09:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:09:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:09:10 INFO - Contents: 03:09:10 INFO - build_url:https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 03:09:10 INFO - Running post-action listener: _resource_record_post_action 03:09:10 INFO - Running post-action listener: set_extra_try_arguments 03:09:10 INFO - [mozharness: 2016-08-03 10:09:10.757391Z] Finished download-and-extract step (success) 03:09:10 INFO - [mozharness: 2016-08-03 10:09:10.757623Z] Running populate-webroot step. 03:09:10 INFO - Running pre-action listener: _resource_record_pre_action 03:09:10 INFO - Running main action method: populate_webroot 03:09:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:09:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:09:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:09:10 INFO - '--tppagecycles', 03:09:10 INFO - '7'], 03:09:10 INFO - 'tests': ['tsvgm']}}, 03:09:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:09:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:09:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:09:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:09:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:09:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['damp', 'tps']}, 03:09:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'tests': ['damp', 'tps']}, 03:09:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['dromaeo_dom']}, 03:09:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:09:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['basic_compositor_video']}, 03:09:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:09:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other-e10s': {'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['a11yr', 03:09:10 INFO - 'ts_paint', 03:09:10 INFO - 'tpaint', 03:09:10 INFO - 'sessionrestore', 03:09:10 INFO - 'sessionrestore_no_auto_restore', 03:09:10 INFO - 'tabpaint']}, 03:09:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tsvgx', 03:09:10 INFO - 'tsvgr_opacity', 03:09:10 INFO - 'tart', 03:09:10 INFO - 'tscrollx', 03:09:10 INFO - 'cart']}, 03:09:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:09:10 INFO - 'tsvgr_opacity', 03:09:10 INFO - 'tart', 03:09:10 INFO - 'tscrollx', 03:09:10 INFO - 'cart']}, 03:09:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s'], 03:09:10 INFO - 'tests': ['tp5o']}, 03:09:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'tests': ['tp5o']}, 03:09:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--disable-e10s', 03:09:10 INFO - '--xperf_path', 03:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:10 INFO - 'tests': ['tp5n']}, 03:09:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:09:10 INFO - 'talos_options': ['--xperf_path', 03:09:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:09:10 INFO - 'tests': ['tp5n']}}, 03:09:10 INFO - 'talos.zip': {'path': '', 03:09:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:09:10 INFO - Running post-action listener: _resource_record_post_action 03:09:10 INFO - [mozharness: 2016-08-03 10:09:10.777251Z] Finished populate-webroot step (success) 03:09:10 INFO - [mozharness: 2016-08-03 10:09:10.777339Z] Running create-virtualenv step. 03:09:10 INFO - Running pre-action listener: _resource_record_pre_action 03:09:10 INFO - Running main action method: create_virtualenv 03:09:10 INFO - Creating virtualenv /builds/slave/test/build/venv 03:09:10 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 03:09:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:09:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:09:10 INFO - Using real prefix '/usr' 03:09:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:09:11 INFO - Installing distribute.............................................................................................................................................................................................done. 03:09:13 INFO - Installing pip.................done. 03:09:13 INFO - Return code: 0 03:09:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09: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', 'pip>=1.5'] in /builds/slave/test/build 03:09: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 pip>=1.5 03:09:13 INFO - Using env: {'DISPLAY': ':0', 03:09:13 INFO - 'HOME': '/home/cltbld', 03:09:13 INFO - 'LANG': 'en_US.UTF-8', 03:09:13 INFO - 'LANGUAGE': 'en_US:en', 03:09:13 INFO - 'LOGNAME': 'cltbld', 03:09:13 INFO - 'MAIL': '/var/mail/cltbld', 03:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:13 INFO - 'MOZ_NO_REMOTE': '1', 03:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:13 INFO - 'NO_EM_RESTART': '1', 03:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:13 INFO - 'PWD': '/builds/slave/test', 03:09:13 INFO - 'SHELL': '/bin/bash', 03:09:13 INFO - 'SHLVL': '1', 03:09:13 INFO - 'TERM': 'linux', 03:09:13 INFO - 'TMOUT': '86400', 03:09:13 INFO - 'USER': 'cltbld', 03:09:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:13 INFO - '_': '/tools/buildbot/bin/python'} 03:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:13 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 03:09:13 INFO - Cleaning up... 03:09:13 INFO - Return code: 0 03:09:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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', '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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09: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', 'psutil>=3.1.1'] in /builds/slave/test/build 03:09: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 psutil>=3.1.1 03:09:13 INFO - Using env: {'DISPLAY': ':0', 03:09:13 INFO - 'HOME': '/home/cltbld', 03:09:13 INFO - 'LANG': 'en_US.UTF-8', 03:09:13 INFO - 'LANGUAGE': 'en_US:en', 03:09:13 INFO - 'LOGNAME': 'cltbld', 03:09:13 INFO - 'MAIL': '/var/mail/cltbld', 03:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:13 INFO - 'MOZ_NO_REMOTE': '1', 03:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:13 INFO - 'NO_EM_RESTART': '1', 03:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:13 INFO - 'PWD': '/builds/slave/test', 03:09:13 INFO - 'SHELL': '/bin/bash', 03:09:13 INFO - 'SHLVL': '1', 03:09:13 INFO - 'TERM': 'linux', 03:09:13 INFO - 'TMOUT': '86400', 03:09:13 INFO - 'USER': 'cltbld', 03:09:13 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:13 INFO - '_': '/tools/buildbot/bin/python'} 03:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:13 INFO - Downloading/unpacking psutil>=3.1.1 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:09:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:15 INFO - Installing collected packages: psutil 03:09:15 INFO - Running setup.py install for psutil 03:09:16 INFO - building 'psutil._psutil_linux' extension 03:09:16 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 03:09:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:09:16 INFO - building 'psutil._psutil_posix' extension 03:09:16 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:09:16 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:09:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:09:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:09:16 INFO - Successfully installed psutil 03:09:16 INFO - Cleaning up... 03:09:16 INFO - Return code: 0 03:09:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:09: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')]} 03:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09: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')]} 03:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:16 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:16 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 03:09:16 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 03:09:16 INFO - Using env: {'DISPLAY': ':0', 03:09:16 INFO - 'HOME': '/home/cltbld', 03:09:16 INFO - 'LANG': 'en_US.UTF-8', 03:09:16 INFO - 'LANGUAGE': 'en_US:en', 03:09:16 INFO - 'LOGNAME': 'cltbld', 03:09:16 INFO - 'MAIL': '/var/mail/cltbld', 03:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:16 INFO - 'MOZ_NO_REMOTE': '1', 03:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:16 INFO - 'NO_EM_RESTART': '1', 03:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:16 INFO - 'PWD': '/builds/slave/test', 03:09:16 INFO - 'SHELL': '/bin/bash', 03:09:16 INFO - 'SHLVL': '1', 03:09:16 INFO - 'TERM': 'linux', 03:09:16 INFO - 'TMOUT': '86400', 03:09:16 INFO - 'USER': 'cltbld', 03:09:16 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:16 INFO - '_': '/tools/buildbot/bin/python'} 03:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:09:18 INFO - Installing collected packages: mozsystemmonitor 03:09:18 INFO - Running setup.py install for mozsystemmonitor 03:09:18 INFO - Successfully installed mozsystemmonitor 03:09:18 INFO - Cleaning up... 03:09:18 INFO - Return code: 0 03:09:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:09:18 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')]} 03:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:18 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')]} 03:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:18 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:18 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 03:09:18 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 03:09:18 INFO - Using env: {'DISPLAY': ':0', 03:09:18 INFO - 'HOME': '/home/cltbld', 03:09:18 INFO - 'LANG': 'en_US.UTF-8', 03:09:18 INFO - 'LANGUAGE': 'en_US:en', 03:09:18 INFO - 'LOGNAME': 'cltbld', 03:09:18 INFO - 'MAIL': '/var/mail/cltbld', 03:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:18 INFO - 'MOZ_NO_REMOTE': '1', 03:09:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:18 INFO - 'NO_EM_RESTART': '1', 03:09:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:18 INFO - 'PWD': '/builds/slave/test', 03:09:18 INFO - 'SHELL': '/bin/bash', 03:09:18 INFO - 'SHLVL': '1', 03:09:18 INFO - 'TERM': 'linux', 03:09:18 INFO - 'TMOUT': '86400', 03:09:18 INFO - 'USER': 'cltbld', 03:09:18 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:18 INFO - '_': '/tools/buildbot/bin/python'} 03:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:19 INFO - Downloading/unpacking jsonschema==2.5.1 03:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:19 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 03:09:19 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 03:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:09:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:09:20 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 03:09:20 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 03:09:20 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 03:09:20 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 03:09:20 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 03:09:20 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 03:09:21 INFO - Downloading functools32-3.2.3-2.tar.gz 03:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:09:21 INFO - warning: no files found matching '*.txt' 03:09:21 INFO - no previously-included directories found matching 'build' 03:09:21 INFO - no previously-included directories found matching 'dist' 03:09:21 INFO - no previously-included directories found matching '.git*' 03:09:21 INFO - Installing collected packages: jsonschema, functools32 03:09:21 INFO - Running setup.py install for functools32 03:09:21 INFO - warning: no files found matching '*.txt' 03:09:21 INFO - no previously-included directories found matching 'build' 03:09:21 INFO - no previously-included directories found matching 'dist' 03:09:21 INFO - no previously-included directories found matching '.git*' 03:09:21 INFO - Successfully installed jsonschema functools32 03:09:21 INFO - Cleaning up... 03:09:21 INFO - Return code: 0 03:09:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:21 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:21 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 03:09:21 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 03:09:21 INFO - Using env: {'DISPLAY': ':0', 03:09:21 INFO - 'HOME': '/home/cltbld', 03:09:21 INFO - 'LANG': 'en_US.UTF-8', 03:09:21 INFO - 'LANGUAGE': 'en_US:en', 03:09:21 INFO - 'LOGNAME': 'cltbld', 03:09:21 INFO - 'MAIL': '/var/mail/cltbld', 03:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:21 INFO - 'MOZ_NO_REMOTE': '1', 03:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:21 INFO - 'NO_EM_RESTART': '1', 03:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:21 INFO - 'PWD': '/builds/slave/test', 03:09:21 INFO - 'SHELL': '/bin/bash', 03:09:21 INFO - 'SHLVL': '1', 03:09:21 INFO - 'TERM': 'linux', 03:09:21 INFO - 'TMOUT': '86400', 03:09:21 INFO - 'USER': 'cltbld', 03:09:21 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:21 INFO - '_': '/tools/buildbot/bin/python'} 03:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:09:21 INFO - Cleaning up... 03:09:21 INFO - Return code: 0 03:09:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:21 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:21 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 03:09:21 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 03:09:21 INFO - Using env: {'DISPLAY': ':0', 03:09:21 INFO - 'HOME': '/home/cltbld', 03:09:21 INFO - 'LANG': 'en_US.UTF-8', 03:09:21 INFO - 'LANGUAGE': 'en_US:en', 03:09:21 INFO - 'LOGNAME': 'cltbld', 03:09:21 INFO - 'MAIL': '/var/mail/cltbld', 03:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:21 INFO - 'MOZ_NO_REMOTE': '1', 03:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:21 INFO - 'NO_EM_RESTART': '1', 03:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:21 INFO - 'PWD': '/builds/slave/test', 03:09:21 INFO - 'SHELL': '/bin/bash', 03:09:21 INFO - 'SHLVL': '1', 03:09:21 INFO - 'TERM': 'linux', 03:09:21 INFO - 'TMOUT': '86400', 03:09:21 INFO - 'USER': 'cltbld', 03:09:21 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:21 INFO - '_': '/tools/buildbot/bin/python'} 03:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:21 INFO - Downloading/unpacking blobuploader==1.2.4 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09: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 03:09:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:09:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:23 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 03:09:23 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 03:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:09:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 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 03:09:24 INFO - Downloading docopt-0.6.1.tar.gz 03:09:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:09:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:09:24 INFO - Running setup.py install for blobuploader 03:09:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:09:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:09:24 INFO - Running setup.py install for requests 03:09:24 INFO - Running setup.py install for docopt 03:09:25 INFO - Successfully installed blobuploader requests docopt 03:09:25 INFO - Cleaning up... 03:09:25 INFO - Return code: 0 03:09:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:25 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:25 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 03:09:25 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 03:09:25 INFO - Using env: {'DISPLAY': ':0', 03:09:25 INFO - 'HOME': '/home/cltbld', 03:09:25 INFO - 'LANG': 'en_US.UTF-8', 03:09:25 INFO - 'LANGUAGE': 'en_US:en', 03:09:25 INFO - 'LOGNAME': 'cltbld', 03:09:25 INFO - 'MAIL': '/var/mail/cltbld', 03:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:25 INFO - 'MOZ_NO_REMOTE': '1', 03:09:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:25 INFO - 'NO_EM_RESTART': '1', 03:09:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:25 INFO - 'PWD': '/builds/slave/test', 03:09:25 INFO - 'SHELL': '/bin/bash', 03:09:25 INFO - 'SHLVL': '1', 03:09:25 INFO - 'TERM': 'linux', 03:09:25 INFO - 'TMOUT': '86400', 03:09:25 INFO - 'USER': 'cltbld', 03:09:25 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:25 INFO - '_': '/tools/buildbot/bin/python'} 03:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:25 INFO - Running setup.py (path:/tmp/pip-vcExLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:25 INFO - Running setup.py (path:/tmp/pip-q8VYTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:25 INFO - Running setup.py (path:/tmp/pip-uVn2Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:25 INFO - Running setup.py (path:/tmp/pip-t8vUGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:25 INFO - Running setup.py (path:/tmp/pip-oJIw5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:25 INFO - Running setup.py (path:/tmp/pip-O8vrVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:25 INFO - Running setup.py (path:/tmp/pip-A9i_3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:25 INFO - Running setup.py (path:/tmp/pip-eAJwIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:25 INFO - Running setup.py (path:/tmp/pip-Ig_zyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:25 INFO - Running setup.py (path:/tmp/pip-ZRReik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:26 INFO - Running setup.py (path:/tmp/pip-_nJYOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:26 INFO - Running setup.py (path:/tmp/pip-Au1ieP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:26 INFO - Running setup.py (path:/tmp/pip-9oNMYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:26 INFO - Running setup.py (path:/tmp/pip-6Hm2UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:26 INFO - Running setup.py (path:/tmp/pip-oeB875-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:26 INFO - Running setup.py (path:/tmp/pip-BQ1jry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:26 INFO - Running setup.py (path:/tmp/pip-9ISnZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:09:26 INFO - Running setup.py install for manifestparser 03:09:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:09:26 INFO - Running setup.py install for mozcrash 03:09:26 INFO - Running setup.py install for mozdebug 03:09:26 INFO - Running setup.py install for mozdevice 03:09:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:09:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:09:26 INFO - Running setup.py install for mozfile 03:09:27 INFO - Running setup.py install for mozhttpd 03:09:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for mozinfo 03:09:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for mozInstall 03:09:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for mozleak 03:09:27 INFO - Running setup.py install for mozlog 03:09:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for moznetwork 03:09:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for mozprocess 03:09:27 INFO - Running setup.py install for mozprofile 03:09:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:09:27 INFO - Running setup.py install for mozrunner 03:09:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Running setup.py install for mozscreenshot 03:09:28 INFO - Running setup.py install for moztest 03:09:28 INFO - Running setup.py install for mozversion 03:09:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:09:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:09:28 INFO - Cleaning up... 03:09:28 INFO - Return code: 0 03:09:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:28 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')]} 03:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:28 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')]} 03:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:28 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:28 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 03:09:28 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 03:09:28 INFO - Using env: {'DISPLAY': ':0', 03:09:28 INFO - 'HOME': '/home/cltbld', 03:09:28 INFO - 'LANG': 'en_US.UTF-8', 03:09:28 INFO - 'LANGUAGE': 'en_US:en', 03:09:28 INFO - 'LOGNAME': 'cltbld', 03:09:28 INFO - 'MAIL': '/var/mail/cltbld', 03:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:28 INFO - 'MOZ_NO_REMOTE': '1', 03:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:28 INFO - 'NO_EM_RESTART': '1', 03:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:28 INFO - 'PWD': '/builds/slave/test', 03:09:28 INFO - 'SHELL': '/bin/bash', 03:09:28 INFO - 'SHLVL': '1', 03:09:28 INFO - 'TERM': 'linux', 03:09:28 INFO - 'TMOUT': '86400', 03:09:28 INFO - 'USER': 'cltbld', 03:09:28 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:28 INFO - '_': '/tools/buildbot/bin/python'} 03:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:09:28 INFO - Running setup.py (path:/tmp/pip-hwsLxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:09:28 INFO - Running setup.py (path:/tmp/pip-Jpa73u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:09:28 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)) 03:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:09:28 INFO - Running setup.py (path:/tmp/pip-o20Wbn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:09:28 INFO - Running setup.py (path:/tmp/pip-w7AUTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:09:28 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)) 03:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:09:28 INFO - Running setup.py (path:/tmp/pip-GQZW1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:09:28 INFO - Running setup.py (path:/tmp/pip-ivjd_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:09:29 INFO - Running setup.py (path:/tmp/pip-SD3i5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:09:29 INFO - Running setup.py (path:/tmp/pip-Z5rH_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:09:29 INFO - Running setup.py (path:/tmp/pip-5ZV8nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:09:29 INFO - Running setup.py (path:/tmp/pip-yooeMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:09:29 INFO - Running setup.py (path:/tmp/pip-NGHLQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:09:29 INFO - Running setup.py (path:/tmp/pip-RD6ClH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:09:29 INFO - Running setup.py (path:/tmp/pip-XG6Lyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:09:29 INFO - Running setup.py (path:/tmp/pip-1YgOmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:29 INFO - Running setup.py (path:/tmp/pip-RPanzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:09:29 INFO - Running setup.py (path:/tmp/pip-BGb5oF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:09:29 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)) 03:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:09:29 INFO - Running setup.py (path:/tmp/pip-hJvGbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:09:29 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)) 03:09:29 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)) 03:09:29 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)) 03:09:29 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)) 03:09:29 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)) 03:09:29 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)) 03:09:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:29 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 03:09:29 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 03:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:09:31 INFO - Downloading blessings-1.6.tar.gz 03:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:09:31 INFO - Installing collected packages: blessings 03:09:31 INFO - Running setup.py install for blessings 03:09:31 INFO - Successfully installed blessings 03:09:31 INFO - Cleaning up... 03:09:32 INFO - Return code: 0 03:09:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:32 INFO - Reading from file tmpfile_stdout 03:09:32 INFO - Current package versions: 03:09:32 INFO - argparse == 1.2.1 03:09:32 INFO - blessings == 1.6 03:09:32 INFO - blobuploader == 1.2.4 03:09:32 INFO - docopt == 0.6.1 03:09:32 INFO - functools32 == 3.2.3-2 03:09:32 INFO - jsonschema == 2.5.1 03:09:32 INFO - manifestparser == 1.1 03:09:32 INFO - mozInstall == 1.12 03:09:32 INFO - mozcrash == 0.17 03:09:32 INFO - mozdebug == 0.1 03:09:32 INFO - mozdevice == 0.48 03:09:32 INFO - mozfile == 1.2 03:09:32 INFO - mozhttpd == 0.7 03:09:32 INFO - mozinfo == 0.9 03:09:32 INFO - mozleak == 0.1 03:09:32 INFO - mozlog == 3.3 03:09:32 INFO - moznetwork == 0.27 03:09:32 INFO - mozprocess == 0.23 03:09:32 INFO - mozprofile == 0.28 03:09:32 INFO - mozrunner == 6.12 03:09:32 INFO - mozscreenshot == 0.1 03:09:32 INFO - mozsystemmonitor == 0.3 03:09:32 INFO - moztest == 0.7 03:09:32 INFO - mozversion == 1.4 03:09:32 INFO - psutil == 3.1.1 03:09:32 INFO - requests == 1.2.3 03:09:32 INFO - wsgiref == 0.1.2 03:09:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:09:32 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')]} 03:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:32 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')]} 03:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:32 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:32 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 03:09:32 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 03:09:32 INFO - Using env: {'DISPLAY': ':0', 03:09:32 INFO - 'HOME': '/home/cltbld', 03:09:32 INFO - 'LANG': 'en_US.UTF-8', 03:09:32 INFO - 'LANGUAGE': 'en_US:en', 03:09:32 INFO - 'LOGNAME': 'cltbld', 03:09:32 INFO - 'MAIL': '/var/mail/cltbld', 03:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:32 INFO - 'MOZ_NO_REMOTE': '1', 03:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:32 INFO - 'NO_EM_RESTART': '1', 03:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:32 INFO - 'PWD': '/builds/slave/test', 03:09:32 INFO - 'SHELL': '/bin/bash', 03:09:32 INFO - 'SHLVL': '1', 03:09:32 INFO - 'TERM': 'linux', 03:09:32 INFO - 'TMOUT': '86400', 03:09:32 INFO - 'USER': 'cltbld', 03:09:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:32 INFO - '_': '/tools/buildbot/bin/python'} 03:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 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)) 03:09:32 INFO - Cleaning up... 03:09:32 INFO - Return code: 0 03:09:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:09:32 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')]} 03:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:32 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')]} 03:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:09:32 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 0x20a7030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x220a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2368180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, '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': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', '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 03:09:32 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 03:09:32 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 03:09:32 INFO - Using env: {'DISPLAY': ':0', 03:09:32 INFO - 'HOME': '/home/cltbld', 03:09:32 INFO - 'LANG': 'en_US.UTF-8', 03:09:32 INFO - 'LANGUAGE': 'en_US:en', 03:09:32 INFO - 'LOGNAME': 'cltbld', 03:09:32 INFO - 'MAIL': '/var/mail/cltbld', 03:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:32 INFO - 'MOZ_NO_REMOTE': '1', 03:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:32 INFO - 'NO_EM_RESTART': '1', 03:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:32 INFO - 'PWD': '/builds/slave/test', 03:09:32 INFO - 'SHELL': '/bin/bash', 03:09:32 INFO - 'SHLVL': '1', 03:09:32 INFO - 'TERM': 'linux', 03:09:32 INFO - 'TMOUT': '86400', 03:09:32 INFO - 'USER': 'cltbld', 03:09:32 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:32 INFO - '_': '/tools/buildbot/bin/python'} 03:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:09:32 INFO - Cleaning up... 03:09:32 INFO - Return code: 0 03:09:32 INFO - Running post-action listener: _resource_record_post_action 03:09:32 INFO - Running post-action listener: _start_resource_monitoring 03:09:32 INFO - Starting resource monitoring. 03:09:32 INFO - [mozharness: 2016-08-03 10:09:32.676815Z] Finished create-virtualenv step (success) 03:09:32 INFO - [mozharness: 2016-08-03 10:09:32.677289Z] Running install step. 03:09:32 INFO - Running pre-action listener: _resource_record_pre_action 03:09:32 INFO - Running main action method: install 03:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:09:32 INFO - Reading from file tmpfile_stdout 03:09:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:09:32 INFO - Reading from file tmpfile_stdout 03:09:32 INFO - Output received: 03:09:32 INFO - Usage: mozinstall [options] installer 03:09:32 INFO - Options: 03:09:32 INFO - -h, --help show this help message and exit 03:09:32 INFO - -d DEST, --destination=DEST 03:09:32 INFO - Directory to install application into. [default: 03:09:32 INFO - "/builds/slave/test"] 03:09:32 INFO - --app=APP Application being installed. [default: firefox] 03:09:32 INFO - mkdir: /builds/slave/test/build/application 03:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:09:50 INFO - Reading from file tmpfile_stdout 03:09:50 INFO - Output received: 03:09:50 INFO - /builds/slave/test/build/application/firefox/firefox 03:09:50 INFO - Running post-action listener: _resource_record_post_action 03:09:50 INFO - [mozharness: 2016-08-03 10:09:50.017253Z] Finished install step (success) 03:09:50 INFO - [mozharness: 2016-08-03 10:09:50.017527Z] Running run-tests step. 03:09:50 INFO - Running pre-action listener: _resource_record_pre_action 03:09:50 INFO - Running main action method: run_tests 03:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:09:50 INFO - Python 2.7.3 03:09:50 INFO - Return code: 0 03:09:50 INFO - grabbing minidump binary from tooltool 03:09:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:09:50 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 0x23663b0>, '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 0x23672b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2367740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:09:50 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 03:09:50 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 03:09:50 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 03:09:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:09:50 INFO - Return code: 0 03:09:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:09:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:09:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:09:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:09:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:09:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 03:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-027 --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 03:09:50 INFO - Using env: {'DISPLAY': ':0', 03:09:50 INFO - 'HOME': '/home/cltbld', 03:09:50 INFO - 'LANG': 'en_US.UTF-8', 03:09:50 INFO - 'LANGUAGE': 'en_US:en', 03:09:50 INFO - 'LOGNAME': 'cltbld', 03:09:50 INFO - 'MAIL': '/var/mail/cltbld', 03:09:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:09:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:09:50 INFO - 'MOZ_NO_REMOTE': '1', 03:09:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:09:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:09:50 INFO - 'NO_EM_RESTART': '1', 03:09:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:09:50 INFO - 'PWD': '/builds/slave/test', 03:09:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:09:50 INFO - 'SHELL': '/bin/bash', 03:09:50 INFO - 'SHLVL': '1', 03:09:50 INFO - 'TERM': 'linux', 03:09:50 INFO - 'TMOUT': '86400', 03:09:50 INFO - 'USER': 'cltbld', 03:09:50 INFO - 'XDG_SESSION_COOKIE': '1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668', 03:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:09:50 INFO - '_': '/tools/buildbot/bin/python'} 03:09:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-027', '--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 03:09:50 INFO - mozversion application_buildid: 20160803003003 03:09:50 INFO - mozversion application_changeset: 6849935c81c669e32c93e88d061635648cc94049 03:09:50 INFO - mozversion application_display_name: Nightly 03:09:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:09:50 INFO - mozversion application_name: Firefox 03:09:50 INFO - mozversion application_remotingname: firefox 03:09:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:09:50 INFO - mozversion application_vendor: Mozilla 03:09:50 INFO - mozversion application_version: 51.0a1 03:09:50 INFO - mozversion platform_buildid: 20160803003003 03:09:50 INFO - mozversion platform_changeset: 6849935c81c669e32c93e88d061635648cc94049 03:09:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:09:50 INFO - mozversion platform_version: 51.0a1 03:09:50 INFO - using testdate: 1470218990 03:09:50 INFO - actual date: 1470218990 03:09:50 INFO - starting webserver on 'localhost:41857' 03:09:50 INFO - SUITE-START | Running 2 tests 03:09:50 INFO - TEST-START | dromaeo_css 03:09:50 INFO - operating with platform_type : linux_ 03:09:50 INFO - Initialising browser for dromaeo_css test... 03:09:50 INFO - TEST-INFO | started process 29084 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptplNky/profile http://localhost:41857/getInfo.html) 03:09:54 INFO - PROCESS | 29084 | __metrics Screen width/height:1600/1200 03:09:54 INFO - PROCESS | 29084 | colorDepth:24 03:09:54 INFO - PROCESS | 29084 | Browser inner width/height: 1024/697 03:09:54 INFO - PROCESS | 29084 | __metrics 03:09:55 INFO - PROCESS | 29084 | 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" 03:09:55 INFO - PROCESS | 29084 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:09:56 INFO - TEST-INFO | 29084: exit 0 03:09:56 INFO - Browser initialized. 03:09:56 INFO - Running cycle 1/1 for dromaeo_css test... 03:09:56 INFO - TEST-INFO | started process 29166 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptplNky/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:10:07 INFO - PROCESS | 29166 | RSS: Main: 156049408 03:10:07 INFO - PROCESS | 29166 | 03:10:58 INFO - PROCESS | 29166 | 1470219058625 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] 03:13:07 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:41857/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41857/tests/dromaeo/cssquery-ext.html) 03:13:08 INFO - PROCESS | 29166 | RSS: Main: 254033920 03:13:08 INFO - PROCESS | 29166 | 03:16:11 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:41857/tests/dromaeo/cssquery-ext.html (next: http://localhost:41857/tests/dromaeo/cssquery-jquery.html) 03:16:11 INFO - PROCESS | 29166 | RSS: Main: 245903360 03:16:11 INFO - PROCESS | 29166 | 03:19:14 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:41857/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41857/tests/dromaeo/cssquery-mootools.html) 03:19:14 INFO - PROCESS | 29166 | RSS: Main: 232509440 03:19:14 INFO - PROCESS | 29166 | 03:22:16 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:41857/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41857/tests/dromaeo/cssquery-prototype.html) 03:22:17 INFO - PROCESS | 29166 | RSS: Main: 224325632 03:22:17 INFO - PROCESS | 29166 | 03:25:19 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:41857/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41857/tests/dromaeo/cssquery-yui.html) 03:25:20 INFO - PROCESS | 29166 | RSS: Main: 182456320 03:25:20 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | Cycle 1(1): loaded http://localhost:41857/tests/dromaeo/cssquery-yui.html (next: http://localhost:41857/tests/dromaeo/cssquery-dojo.html) 03:28:23 INFO - PROCESS | 29166 | RSS: Main: 275189760 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | __start_tp_report 03:28:23 INFO - PROCESS | 29166 | _x_x_mozilla_page_load 03:28:23 INFO - PROCESS | 29166 | _x_x_mozilla_page_load_details 03:28:23 INFO - PROCESS | 29166 | |i|pagename|runs| 03:28:23 INFO - PROCESS | 29166 | |0;dojo.html;3013.7890347675666;3048.207466058824;3067.7699172562066;3070.907872763817;3077.3999070181308;5192.247124166994;5206.609504287175;5260.079486089933;5349.983503134402;5353.036453438384;5309.097453432916;5311.49540793624;5336.733163341827;5394.838154855361;5417.864553386167;4694.600958918488;4808.495108013661;4862.246351815456;4873.463918968929;5023.171176755831;4927.704337739747;4936.33359496467;4965.826196083138;4982.526659967315;4986.775595098211;4697.271922851964;4747.216709242971;4819.277108433728;4890.73100979446;4929.21132618781;4779.952200477986;4790.089882922233;4798.328234047236;4847.345608342864;4922.483139270361;4796;4819.42166939966;4822.493638168012;4846;4856.15017371962;5111.182210846284;5235.921461178085;5283.392409872876;5341.599380046473;5365.03429382711;5753.626014309057;5756.712164391805;5763.740631671585;5771.740271687742;5820.767169313222;5824.737886795062;5883.588148829584;5885.911711324334;5894.145348924382;5895.970520147414;5196.142636464979;5242.1088414969545;5242.947570524322;5261.947380526222;5465.508104270635;5803.970980145156;5823.621464604787;5867.354590994987;5869.178315035899;5875.030619947661;5587.469190426903;5595.328560572757;5632.633878797947;5640.238567793377;5642.943570564242;5520.089185284463;5698.287714035746;5706.857328566736;5755.740991655385;5761.798337058183;5751.539876809845;5765.1928729977835;5766.106253530709;5770.480656740913;5803.912941305884;5714.657120572779;5762.654240745569;5764.452377024176;5778.27771528559;5778.913316300259;4173.499180098408;4269.316909294498;4270.722403043792;4321.286987647066;4449.57729015743;3836.827342769582;3867.555231148454;3913.96086039136;3920.2747491714126;3943.960560394359;5455.208994695747;5457.69982650958;5460.754266058037;5468.671879687232;5507.449255074461;5537.667739935617;5778.393268706809;5782.768689252467;5784.1034639630925;5828.475437210672;5346.652467589594;5386.13821788512;5400;5477.4248703886315;5484.862878428071;4914.164592019294;4916.311716359713;4916.631252656065;4918.262260660929;4921.975390123026;5962.135490353788;5962.25471816023;5967.104934259809;5972;5975.492083172979;5471.753771080232;5720.08478643415;5758.913616295675;5760.855978600569;5831.912521312099;6009.278886533644;6010.248718910137;6013.7293821779;6017.06735456005;6020.488258498077;5698.20225168485;5698.715064246689;5699.173619825019;5699.202111704281;5710.114932185518;5474.507294343528;5664.0654292043;5691.430856914276;5703.7433315500075;5799.101139323243;5550.417206193291;5583.832485025456;5600.523955463831;5601;5604.047311956896;5600.971995139998;5609.49514543693;5614.663120212637;5616.382197958303;5618.971905140448;5297.152455607084;5453.7818487260065;5455.372632147195;5498.340199176125;5592.720363981866;5583.41374155724;5591.608587398843;5638.407967163389;5641.9435805641415;5647.491725744702;5471.097268950579;5489.670619762827;5490.450954904637;5495.285612870462;5496.422875598165 03:28:23 INFO - PROCESS | 29166 | |1;ext.html;554.5556258208883;573.2118337286234;590.3181824992134;590.7400743672787;606.1302031584677;32374.83812580937;32947.34105317892;35321.64678353216;35347.4697879532;43601;42881.35677964825;46871.296930545985;47086.29370559444;47118.29322560164;47193.29210061843;8969.282457403424;9126.543672816366;9131.908680913206;9137;9138.817223655524;8977.596008179615;9056.366054376196;9067.95466022669;9071;9071.863922041142;9117.544122793834;9154.72535823923;9167.541622918861;9168.31237657175;9172.31207659425;9061.13919177677;9098.909010909938;9107.908920910839;9107.954460227722;9109;8919.152680495343;8989.101089891024;8996.145366190201;8998.14517620825;9001.909980900173;43862.78068609668;44063.55936440659;44184.779076104416;44208;44223.33664995027;3688.354537955847;3753.774773513571;3899.6490315871706;3983.7410568312966;4059.8961839369304;33118.3376332472;33918.32163356744;34882.65117348819;34889.82555087234;35573;4391.604755572014;4449.688521803475;4460.821567137311;4468.575485328888;4655.208614535537;347144;347442;348132;348229;348379;61052.694736526035;61295.38704612897;61373.38626613766;61395.38604613986;61450.69274653688;54164;60614.696926515084;62379.37620623827;62790.68604657039;68469.65765171242;13623.523176688768;13665.18008919468;13716.314184290746;13726.725465490634;13785.448582056806;12007.27956322606;12136.87863121375;12177.26936383802;12192;12198.878011219951;13755.243461609707;17450.301987920593;17942.192601332972;18037.18832652534;18338.358157464692;5774.422557744192;5837.402355951561;5846.970765146147;5860.384659610765;5958.225430693982;927.9953600231955;999.4952548962873;1071.0628200324716;1100.6532942123206;1142.3602782441692;27467.31331716723;29387.265318366783;29861.850690746407;31192.376152476823;32028.359432811678;1048.5858086056119;1075.9085477734325;1080.2492267873752;1105.6848798092506;1147.5868687272575;16006.199690015686;16096.505041718317;16157.676846463004;16226.188690565425;16450.917745411196;496243;501381;502574;503499;505424;60274.397256027754;60316.396836031076;60467.69766151229;60594;60621.39378606246;1103.8454616353717;1313.8554758976504;1358.3140514040347;1375.312343828106;1381.799639052352;27384.315392115357;28534.57198141988;28624;29122.12633620995;29196;52097.479025209264;52721;52813;52887.20669190042;52905.7354713224;383896;384177;384791;385621;388500.0574997107;28385.29036774097;29633.555496668;29705.25736856596;30769.846150769103;30783.076507704805;46994.53005469902;47711;47838.28242576295;48112.27831582459;49384;39864.2027159461;39924.60075399209;41019.58980410277;41139.794301028305;42531;39758.2048359037;40063.59936400599;40963.59036409598;41044.58955410407;41428;41641.167176656905;41872.371914421914;42381.36427953521;42787.57212427836;43379;36253.27493450169;39058.60941390663;39356;40041;40175.3973690389;28806.09946283173;29231.561526576694;29324;29534.409311813215;29544.89778573305 03:28:23 INFO - PROCESS | 29166 | |2;jquery.html;350.18581797321235;377.1514093290097;382.63076131533063;384.6307544757033;405.5113588126308;15336.46322378717;18182.090895455225;18566.535836604107;20430.182792688276;21014;18575.164117614706;19191;19516.902415487937;19993.400197994084;20030.39908802738;5089.821856235023;5221.347331583552;5226.216067589873;5229.947700523003;5771.192033115346;5953.345132035494;6127.203463549732;6130.81607551772;6135.447809697126;6135.631862088267;3874.2832575973534;5757.078867381219;5992.131140984555;6035.7585696572305;6103.633781973096;6075.33171351151;6075.875962946847;6077.7568897244055;6162.5378096642935;6635.568688035262;5925.822225333204;5932.0508718604815;5937.198478205473;5937.821865344046;5938.910916336215;14455.927720361437;14466.638334041629;14626.488072917396;14841.257937103102;20784.75291482516;2077.210659949225;2199.8570092943905;2201.2405720026563;2252.155441709359;2442.8884857389844;10272.845907311395;10804.297720648212;10847.783044339149;11477.253978491372;12461.501539938392;1983.3355825798376;2009.2465325502938;2044.9386518404472;2049.7847725988704;2081.323569839793;84096;86044.56977715071;86357.56821215854;86680;86740.56629716874;9381.718548443558;9974.501274936225;10007.09936105754;11244.269122507174;11429.142814288962;13202.801957970638;13268.469261229635;13330.200187988557;13348.599542013755;13349.132306400053;7470.589117598584;7497.625118744041;7632.885506717404;7667.776344859928;7738.690452381954;7408.666610002563;7545.547267163988;7671.808204794925;7733.613319334011;7776.455648104578;3848.3265428549894;4057.3102572562734;4108.239975604522;4238.597333253336;4254.29804082323;1883.8116188381327;1906.961860762777;1911.8183772541586;1949.5710943592387;2002.389271272276;881.316979341003;988.3476905242505;988.4217732626494;1039.0129377091794;1040.4485622619873;19432;19792.802071979382;20505.2823151189;20600.690989635168;20629.484262893548;966.3415794481385;967.9661211857551;981.1611072533025;993.7267251505893;1015.3298822776932;10856.837147442795;10916.727081823017;11396.14528910335;11567.074634029426;11773.234739741889;467282;469187;482329;488219;496264;65207.67396162989;83518;97155;100889.49555252177;102503;987.511181964932;1003.9849402259118;1031.7781676939344;1078.6763970808884;1081.313366012572;18115.456536303933;18409.171587278335;18912.10878912153;19110.734068566075;19145.425637230906;43871;43948.92357534942;44585.331220031076;44979;45643.77178114221;61098.38901611105;62024;62616.37383626105;64082.67958660177;64582.354176459514;11787.351695656822;12349.444275007467;12859.16415432994;12953.740925181632;12998.350082495648;14076.718465630835;14271;14465;14547.636309092357;14729.92635036818;12745.681357966125;12842.422091006114;12842.550510732453;12906.870931290567;12942.288174150512;12552.497900083894;12557;12570.748585028432;12727.80908286358;12871.099023068295;12390.31853248044;12880.871191287968;12886.48454061827;13123.54067607629;13220.603381898558;12946.67633309137;13029.543965961146;13164.868351316747;13301.135426197266;13493;12564.748705026032;12702.174358666656;12766.361681916052;12779.233246004898;13265.071444998757 03:28:23 INFO - PROCESS | 29166 | |3;mootools.html;1246.302070840329;1444.8844092472602;1495.6559991202023;1595.4256467671637;1747.1700942052519;7923.801904952372;8226.136255693149;8365.539895305756;8866.601002954867;8867.91132088679;8597.1402859714;8683.869741953877;8699.260562852165;8712.346574006942;8718.128187181279;2281.8288628352857;2305.0664480885266;2320.385097949045;2322.7677232276724;2322.941926451835;2240.9887950560305;2271.602469567819;2307.457747429353;2311.144876395736;2318.014843691433;2267.8866056697098;2282.429392651837;2286.325533967484;2310.653401989698;2311.6648086027517;2251.6960210371553;2291.9083236670594;2293.1973809166657;2297.4026753044245;2299.4136495193784;2243.7083179186766;2253.3803204119;2258;2260.2993072147688;2262.1064679451524;8653.826923461496;8675.479471231747;8705.085965973545;8726.69456569017;8740.562971851446;5001.149804533238;5173.32746742921;5182.144946083929;5196.32447781786;5205.791768329263;3389.7288216942584;3554.591222009476;3584.2652256287397;3609.8014609196484;3627.873024444158;1179.9764004719857;1233.105998151333;1236.2150034727922;1260.3887114749339;1272.7327261275143;12166.270023798601;12348.753024939451;12354.938225308906;12416.317102559356;12450.937745311307;4145.564715704868;4315.115401342718;4451.1765323415275;4459.1304695584695;4481.484629267611;9667.226621870233;9667.661631842851;9669.758256043655;9671.854922176173;9696.321257512047;5034.043531728959;5039.571636410873;5042.647014708935;5045.167547354718;5046.470120637354;3988.3419235826345;4633.4671512775785;4665.790039448233;4761.166795810788;4786.688865223749;3623.8912832615065;3728.105254738843;3731.533558305212;3761.6458075092946;3845.250176215611;1144.9656510304703;1147.3173461790223;1151.988480115205;1169.497116240008;1169.8888605582456;857.6869442653511;944.1974321826392;1012.1750773119884;1026.5534492495788;1036.9792604147874;5998.580099393088;6058.242719660043;6061.242344706911;6231.594946328474;6328.493720502349;488.8582311129804;491.1012846490913;498.46913037614826;501.6237821633775;502.0160485448489;4058.7564746115327;4193.433886425293;4212.831486740496;4221.155768846182;4347.673924455678;9745.31782775199;10069.899301006895;10331.793364132825;10437.530311136017;10440;10050.396976181453;10068.949655251678;10071.14395276395;10088.243381746399;10110.747231319276;7705.807354816062;7785;7789.493682910593;7789.922100779033;7793.610319484003;5850.882982340415;5875.588708790344;5892.94107058924;5925.318588362221;6021.157038014769;9248.953755231181;9329.253659707072;9355.25157987373;9400.200982916254;9489.952550237205;9319.906800931903;9498.810023799624;9560.187384072431;9572.282078843975;9581.760455988657;6251.374862513894;6813.0461735358085;6876.553024053421;7052;7196.136463624399;8332.458390204618;8478.703245386381;8489.490630562186;8490.32077433816;8492.108328625405;7164.534305270141;7219.061522002106;7238.746643867439;7450.515716478411;7477.252274772479;6997.545159564612;7007.404370628554;7040.964795175992;7073.964630177022;7167.964160179375;6327.620342779263;6466.4503517201565;6513.54405191632;6609.306022867722;6625.105610742488;5000.324956131021;5127.2052831811125;5147.279380886604;5279.683219006872;5369.758360873848;5084.872878178076;5227.0591293566;5231.607629427732;5295.099833028471;5585.497305242548 03:28:23 INFO - PROCESS | 29166 | |4;prototype.html;311.62293624714107;320.6937374807059;321.7618961968144;332.2723236112913;332.41494968854806;5283.260343551901;5304.867378315539;5309.309789727335;5310.256564081032;5319;4986.501349865011;5061.9622977289655;5069.366329208849;5092.643514953955;5267.736613169345;3963.4253033310297;4017.5536806749546;4093.16090201508;4134.958650413502;4171.916561668765;4141.7514949103;4142.834286628531;4144.171165766844;4158.397032430295;4161.292580261348;3897.2010737798823;4020.8190631421658;4109.938350924738;4128.8761337159785;4140.378943158536;4053.8783836484954;4057.371107478316;4083.673306135502;4090.3046482098116;4091.6931230157707;3588.372034893909;3593.389123848952;3598.874039408608;3599.2441587266703;3609.945850812214;4851.660383773137;5123.846284611468;5131.640785145041;5131.794728210867;5136.897262054776;4616.192166370872;4646.581807637329;4654.790534425959;4657.510961349043;4660.836870709509;5423.213634023084;5465.207544906006;5467.152591348347;5469.206964990094;5486.094794358966;3844.884983354824;3939.9015024624327;3948.723589348747;3951.8539623509146;3954.5847685992844;5604.411536788619;5636.943630563683;5670.886582268332;5675.801346952837;5680.233168522238;4011.5587285398583;4014.558398576154;4018.578049304839;4020.9195816083516;4032.4152997815154;4052.6352628263007;4065.4918135233097;4072.7781665500233;4079.8572049978106;4081.142959978438;4516.16450956574;4603.8849028773875;4612.308153776893;4620.699654522446;4648.188485441973;5435.483629055233;5436.565074794007;5445.87610831776;5446.482584154578;5454.699991500505;1321.4383886848052;1383.5295999360267;1393.3520912775607;1396.211140705498;1397.70775927479;922.579227988903;923.8060007398387;958.9520523973773;961.5384615384514;965.5027660754716;5202.739863006835;5214.243934629533;5246.291750613695;5267.498762852569;5282.81510147143;5388.703621300866;5497.22390192958;5521.365043020024;5526.226328314039;5529.557635389239;5144.405234377355;5174.197999310113;5191.662541934762;5211.843644690665;5240.842774716764;2938.3682508260827;2940.4413161499247;2941.57347184657;2942.6321709786275;2970.420767950272;5562.7774889004795;5612.494875461228;5721.771129154788;5735;5774.364819869811;5726.770929162787;5743.645105506722;5766.596338256281;5794.246747922743;5797.536197104221;5822.359540450547;5851.970740146272;5866.765329386777;5874.324452687824;5885.411458854166;5517.558595312285;5610.5231055360755;5637.774489020558;5641.774329026793;5648.491635752802;5543.281582709212;5568.972155139199;5579.581531385081;5579.609427340048;5581.804636837691;5592.189132575835;5773.797917072882;5845.473907348359;5865.354810970704;5867.706614669335;3917.666998305062;3948.684105271629;3959.3071212538266;3965.0483883867087;3967.7978721808518;5624.74688639004;5630.845676636198;5646.0076159635455;5651.88696226065;5668.971655141698;5275.7362131894015;5502.294288770494;5508.091164957737;5516.482967184056;5565.30433695788;5533.750981205775;5536.2249285100925;5537.473939975695;5549.250851135045;5559.6664200148125;5239.973800130975;5270.288511050959;5438.428964958623;5513.61404701667;5514.393416724238;5503.459031471194;5521.309836270466;5530.364008139116;5530.419305972815;5537.169424586345;5401.675899446046;5412.864678383072;5432.29380180574;5434.701091440009;5436.266104075899 03:28:23 INFO - PROCESS | 29166 | |5;yui.html;763.1033535595674;768.2039015627093;774.2451110167586;815.5636734347125;833.9207775261348;15297.388104475822;16588.834111658878;16642.25109870056;16706.49880503585;16724.247408866617;14803.629909252275;15373.385064597403;15435.151066691327;15518.68962620747;15538.611534711637;817.1664901800175;827.6854795177824;835.4068611285994;835.8328334333128;837.5602808525512;677.3294438505868;808.8139727862596;840.9789755256106;841.5792103948025;845.1464021338431;881.6209030117047;883.0198479687542;883.5670521444478;883.779055236191;886.6719313853883;876.2551830943711;876.2989608313355;876.3602570123813;877.4209022045483;879.6393478673713;512.8487096306599;531.3039917707815;617.3425302053299;639.3925770518026;639.6354078175442;15226.162561059138;15881.761773573406;15886.602834929103;15910.363585456567;15918.124503152321;14386.92806535971;14688.339024743913;14929.253537323091;15019.624509387244;15040;13374.197548147142;13445.731085378335;13461.528846490424;13587.796183057262;13729.862701372958;4614.8154073837;4717.339572459859;4818.68678535894;4829.879253018667;4850.733209673466;80590;80833.59583202105;81231.18768812355;82239;82518;12082.802706819451;12089.395530223454;12140.575079872338;12275.38623068843;12817.551385701643;22801.543969120525;22806.429839254153;22868.885655571616;22997.195098171484;23003.539929201655;8623.137686231326;8723.302135829119;8909.064548222474;9069.727908162766;9075.13786190311;4860.829870954571;4904.411470623548;4918.898980131035;4986.6010719143105;5066.873328166752;3501.8949431517094;3622.909427264287;3644.781313121221;3648.7810731356203;3650.799206043693;634.9650769207645;639.1371648274792;648.4714957309777;654.028767280588;659.5383231737803;555.5111501878401;598.9101634754821;637.6238019568477;638.1703785079378;669.2671524680467;9333.113354231336;9586.616535338508;9642.662506812369;9687.225021998223;9924.702258932244;636.2301615045847;653.859420224654;669.3875104279628;705.340506626306;712.4870093532649;4332.1985432695055;4553.908921821545;4589.311603259538;4638.118757436144;4714.0807542529565;95816.52091739497;98240.50879745555;99380;113764.43117784358;129252;94584;96836.51581742187;97316;98951;100984;54456.727716361165;69857;84329;88240.11759882448;90614.54692726626;10377.740556486147;10456.26806123564;10463.895361046292;10572.154227661922;10627.734306642396;42478.78760606177;48594.514054859;49307.26039109344;50775;52486.737566311924;72701;74449.25550744424;75274.24725752672;77513.51189196612;78407;12574.245545267313;13371.130876492996;13401.731965360834;13630;13844.684754948265;15021.924890375916;15438.768418473508;15519.22403879824;15564.766528502309;15801.209939502749;13865.792013120014;13897.791533127214;14080.084794488326;14260;14684.045537040058;13566;13684.247366394944;13947.860521394656;14009.859901401263;14880.62798430048;14090.411325448165;14283.143011419348;14543.85456145425;14788.260586970393;15113.168775717002;9885.752856178653;10391.948040259751;10482.947585262025;10546.894531054591;10853.457327133454;11191.66425007251;11220.15848811326;11242;11277.379744114152;11289.548418063185 03:28:23 INFO - PROCESS | 29166 | __end_tp_report 03:28:23 INFO - PROCESS | 29166 | __start_cc_report 03:28:23 INFO - PROCESS | 29166 | _x_x_mozilla_cycle_collect,957 03:28:23 INFO - PROCESS | 29166 | __end_cc_report 03:28:23 INFO - PROCESS | 29166 | __startTimestamp1470220103757__endTimestamp 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | ------- Summary: start ------- 03:28:23 INFO - PROCESS | 29166 | Number of tests: 6 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | [#0] dojo.html Cycles:165 Average:5315.83 Median:5495.29 stddev:625.07 (11.4%) stddev-sans-first:600.34 03:28:23 INFO - PROCESS | 29166 | Values: 3013.8 3048.2 3067.8 3070.9 3077.4 5192.2 5206.6 5260.1 5350.0 5353.0 5309.1 5311.5 5336.7 5394.8 5417.9 4694.6 4808.5 4862.2 4873.5 5023.2 4927.7 4936.3 4965.8 4982.5 4986.8 4697.3 4747.2 4819.3 4890.7 4929.2 4780.0 4790.1 4798.3 4847.3 4922.5 4796.0 4819.4 4822.5 4846.0 4856.2 5111.2 5235.9 5283.4 5341.6 5365.0 5753.6 5756.7 5763.7 5771.7 5820.8 5824.7 5883.6 5885.9 5894.1 5896.0 5196.1 5242.1 5242.9 5261.9 5465.5 5804.0 5823.6 5867.4 5869.2 5875.0 5587.5 5595.3 5632.6 5640.2 5642.9 5520.1 5698.3 5706.9 5755.7 5761.8 5751.5 5765.2 5766.1 5770.5 5803.9 5714.7 5762.7 5764.5 5778.3 5778.9 4173.5 4269.3 4270.7 4321.3 4449.6 3836.8 3867.6 3914.0 3920.3 3944.0 5455.2 5457.7 5460.8 5468.7 5507.4 5537.7 5778.4 5782.8 5784.1 5828.5 5346.7 5386.1 5400.0 5477.4 5484.9 4914.2 4916.3 4916.6 4918.3 4922.0 5962.1 5962.3 5967.1 5972.0 5975.5 5471.8 5720.1 5758.9 5760.9 5831.9 6009.3 6010.2 6013.7 6017.1 6020.5 5698.2 5698.7 5699.2 5699.2 5710.1 5474.5 5664.1 5691.4 5703.7 5799.1 5550.4 5583.8 5600.5 5601.0 5604.0 5601.0 5609.5 5614.7 5616.4 5619.0 5297.2 5453.8 5455.4 5498.3 5592.7 5583.4 5591.6 5638.4 5641.9 5647.5 5471.1 5489.7 5490.5 5495.3 5496.4 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | [#1] ext.html Cycles:180 Average:57871.49 Median:41534.58 stddev:110257.40 (265.5%) stddev-sans-first:110482.72 03:28:23 INFO - PROCESS | 29166 | Values: 554.6 573.2 590.3 590.7 606.1 32374.8 32947.3 35321.6 35347.5 43601.0 42881.4 46871.3 47086.3 47118.3 47193.3 8969.3 9126.5 9131.9 9137.0 9138.8 8977.6 9056.4 9068.0 9071.0 9071.9 9117.5 9154.7 9167.5 9168.3 9172.3 9061.1 9098.9 9107.9 9108.0 9109.0 8919.2 8989.1 8996.1 8998.1 9001.9 43862.8 44063.6 44184.8 44208.0 44223.3 3688.4 3753.8 3899.6 3983.7 4059.9 33118.3 33918.3 34882.7 34889.8 35573.0 4391.6 4449.7 4460.8 4468.6 4655.2 347144.0 347442.0 348132.0 348229.0 348379.0 61052.7 61295.4 61373.4 61395.4 61450.7 54164.0 60614.7 62379.4 62790.7 68469.7 13623.5 13665.2 13716.3 13726.7 13785.4 12007.3 12136.9 12177.3 12192.0 12198.9 13755.2 17450.3 17942.2 18037.2 18338.4 5774.4 5837.4 5847.0 5860.4 5958.2 928.0 999.5 1071.1 1100.7 1142.4 27467.3 29387.3 29861.9 31192.4 32028.4 1048.6 1075.9 1080.2 1105.7 1147.6 16006.2 16096.5 16157.7 16226.2 16450.9 496243.0 501381.0 502574.0 503499.0 505424.0 60274.4 60316.4 60467.7 60594.0 60621.4 1103.8 1313.9 1358.3 1375.3 1381.8 27384.3 28534.6 28624.0 29122.1 29196.0 52097.5 52721.0 52813.0 52887.2 52905.7 383896.0 384177.0 384791.0 385621.0 388500.1 28385.3 29633.6 29705.3 30769.8 30783.1 46994.5 47711.0 47838.3 48112.3 49384.0 39864.2 39924.6 41019.6 41139.8 42531.0 39758.2 40063.6 40963.6 41044.6 41428.0 41641.2 41872.4 42381.4 42787.6 43379.0 36253.3 39058.6 39356.0 40041.0 40175.4 28806.1 29231.6 29324.0 29534.4 29544.9 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | [#2] jquery.html Cycles:180 Average:29237.03 Median:11277.53 stddev:79417.42 (704.2%) stddev-sans-first:79610.59 03:28:23 INFO - PROCESS | 29166 | Values: 350.2 377.2 382.6 384.6 405.5 15336.5 18182.1 18566.5 20430.2 21014.0 18575.2 19191.0 19516.9 19993.4 20030.4 5089.8 5221.3 5226.2 5229.9 5771.2 5953.3 6127.2 6130.8 6135.4 6135.6 3874.3 5757.1 5992.1 6035.8 6103.6 6075.3 6075.9 6077.8 6162.5 6635.6 5925.8 5932.1 5937.2 5937.8 5938.9 14455.9 14466.6 14626.5 14841.3 20784.8 2077.2 2199.9 2201.2 2252.2 2442.9 10272.8 10804.3 10847.8 11477.3 12461.5 1983.3 2009.2 2044.9 2049.8 2081.3 84096.0 86044.6 86357.6 86680.0 86740.6 9381.7 9974.5 10007.1 11244.3 11429.1 13202.8 13268.5 13330.2 13348.6 13349.1 7470.6 7497.6 7632.9 7667.8 7738.7 7408.7 7545.5 7671.8 7733.6 7776.5 3848.3 4057.3 4108.2 4238.6 4254.3 1883.8 1907.0 1911.8 1949.6 2002.4 881.3 988.3 988.4 1039.0 1040.4 19432.0 19792.8 20505.3 20600.7 20629.5 966.3 968.0 981.2 993.7 1015.3 10856.8 10916.7 11396.1 11567.1 11773.2 467282.0 469187.0 482329.0 488219.0 496264.0 65207.7 83518.0 97155.0 100889.5 102503.0 987.5 1004.0 1031.8 1078.7 1081.3 18115.5 18409.2 18912.1 19110.7 19145.4 43871.0 43948.9 44585.3 44979.0 45643.8 61098.4 62024.0 62616.4 64082.7 64582.4 11787.4 12349.4 12859.2 12953.7 12998.4 14076.7 14271.0 14465.0 14547.6 14729.9 12745.7 12842.4 12842.6 12906.9 12942.3 12552.5 12557.0 12570.7 12727.8 12871.1 12390.3 12880.9 12886.5 13123.5 13220.6 12946.7 13029.5 13164.9 13301.1 13493.0 12564.7 12702.2 12766.4 12779.2 13265.1 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | [#3] mootools.html Cycles:180 Average:5580.69 Median:5041.11 stddev:3136.29 (62.2%) stddev-sans-first:3128.17 03:28:23 INFO - PROCESS | 29166 | Values: 1246.3 1444.9 1495.7 1595.4 1747.2 7923.8 8226.1 8365.5 8866.6 8867.9 8597.1 8683.9 8699.3 8712.3 8718.1 2281.8 2305.1 2320.4 2322.8 2322.9 2241.0 2271.6 2307.5 2311.1 2318.0 2267.9 2282.4 2286.3 2310.7 2311.7 2251.7 2291.9 2293.2 2297.4 2299.4 2243.7 2253.4 2258.0 2260.3 2262.1 8653.8 8675.5 8705.1 8726.7 8740.6 5001.1 5173.3 5182.1 5196.3 5205.8 3389.7 3554.6 3584.3 3609.8 3627.9 1180.0 1233.1 1236.2 1260.4 1272.7 12166.3 12348.8 12354.9 12416.3 12450.9 4145.6 4315.1 4451.2 4459.1 4481.5 9667.2 9667.7 9669.8 9671.9 9696.3 5034.0 5039.6 5042.6 5045.2 5046.5 3988.3 4633.5 4665.8 4761.2 4786.7 3623.9 3728.1 3731.5 3761.6 3845.3 1145.0 1147.3 1152.0 1169.5 1169.9 857.7 944.2 1012.2 1026.6 1037.0 5998.6 6058.2 6061.2 6231.6 6328.5 488.9 491.1 498.5 501.6 502.0 4058.8 4193.4 4212.8 4221.2 4347.7 9745.3 10069.9 10331.8 10437.5 10440.0 10050.4 10068.9 10071.1 10088.2 10110.7 7705.8 7785.0 7789.5 7789.9 7793.6 5850.9 5875.6 5892.9 5925.3 6021.2 9249.0 9329.3 9355.3 9400.2 9490.0 9319.9 9498.8 9560.2 9572.3 9581.8 6251.4 6813.0 6876.6 7052.0 7196.1 8332.5 8478.7 8489.5 8490.3 8492.1 7164.5 7219.1 7238.7 7450.5 7477.3 6997.5 7007.4 7041.0 7074.0 7168.0 6327.6 6466.5 6513.5 6609.3 6625.1 5000.3 5127.2 5147.3 5279.7 5369.8 5084.9 5227.1 5231.6 5295.1 5585.5 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | [#4] prototype.html Cycles:180 Average:4611.60 Median:5273.01 stddev:1356.50 (25.7%) stddev-sans-first:1321.35 03:28:23 INFO - PROCESS | 29166 | Values: 311.6 320.7 321.8 332.3 332.4 5283.3 5304.9 5309.3 5310.3 5319.0 4986.5 5062.0 5069.4 5092.6 5267.7 3963.4 4017.6 4093.2 4135.0 4171.9 4141.8 4142.8 4144.2 4158.4 4161.3 3897.2 4020.8 4109.9 4128.9 4140.4 4053.9 4057.4 4083.7 4090.3 4091.7 3588.4 3593.4 3598.9 3599.2 3609.9 4851.7 5123.8 5131.6 5131.8 5136.9 4616.2 4646.6 4654.8 4657.5 4660.8 5423.2 5465.2 5467.2 5469.2 5486.1 3844.9 3939.9 3948.7 3951.9 3954.6 5604.4 5636.9 5670.9 5675.8 5680.2 4011.6 4014.6 4018.6 4020.9 4032.4 4052.6 4065.5 4072.8 4079.9 4081.1 4516.2 4603.9 4612.3 4620.7 4648.2 5435.5 5436.6 5445.9 5446.5 5454.7 1321.4 1383.5 1393.4 1396.2 1397.7 922.6 923.8 959.0 961.5 965.5 5202.7 5214.2 5246.3 5267.5 5282.8 5388.7 5497.2 5521.4 5526.2 5529.6 5144.4 5174.2 5191.7 5211.8 5240.8 2938.4 2940.4 2941.6 2942.6 2970.4 5562.8 5612.5 5721.8 5735.0 5774.4 5726.8 5743.6 5766.6 5794.2 5797.5 5822.4 5852.0 5866.8 5874.3 5885.4 5517.6 5610.5 5637.8 5641.8 5648.5 5543.3 5569.0 5579.6 5579.6 5581.8 5592.2 5773.8 5845.5 5865.4 5867.7 3917.7 3948.7 3959.3 3965.0 3967.8 5624.7 5630.8 5646.0 5651.9 5669.0 5275.7 5502.3 5508.1 5516.5 5565.3 5533.8 5536.2 5537.5 5549.3 5559.7 5240.0 5270.3 5438.4 5513.6 5514.4 5503.5 5521.3 5530.4 5530.4 5537.2 5401.7 5412.9 5432.3 5434.7 5436.3 03:28:23 INFO - PROCESS | 29166 | 03:28:23 INFO - PROCESS | 29166 | [#5] yui.html Cycles:180 Average:20729.91 Median:4824.28 stddev:29028.02 (601.7%) stddev-sans-first:29070.74 03:28:23 INFO - PROCESS | 29166 | Values: 763.1 768.2 774.2 815.6 833.9 15297.4 16588.8 16642.3 16706.5 16724.2 14803.6 15373.4 15435.2 15518.7 15538.6 817.2 827.7 835.4 835.8 837.6 677.3 808.8 841.0 841.6 845.1 881.6 883.0 883.6 883.8 886.7 876.3 876.3 876.4 877.4 879.6 512.8 531.3 617.3 639.4 639.6 15226.2 15881.8 15886.6 15910.4 15918.1 14386.9 14688.3 14929.3 15019.6 15040.0 13374.2 13445.7 13461.5 13587.8 13729.9 4614.8 4717.3 4818.7 4829.9 4850.7 80590.0 80833.6 81231.2 82239.0 82518.0 12082.8 12089.4 12140.6 12275.4 12817.6 22801.5 22806.4 22868.9 22997.2 23003.5 8623.1 8723.3 8909.1 9069.7 9075.1 4860.8 4904.4 4918.9 4986.6 5066.9 3501.9 3622.9 3644.8 3648.8 3650.8 635.0 639.1 648.5 654.0 659.5 555.5 598.9 637.6 638.2 669.3 9333.1 9586.6 9642.7 9687.2 9924.7 636.2 653.9 669.4 705.3 712.5 4332.2 4553.9 4589.3 4638.1 4714.1 95816.5 98240.5 99380.0 113764.4 129252.0 94584.0 96836.5 97316.0 98951.0 100984.0 54456.7 69857.0 84329.0 88240.1 90614.5 10377.7 10456.3 10463.9 10572.2 10627.7 42478.8 48594.5 49307.3 50775.0 52486.7 72701.0 74449.3 75274.2 77513.5 78407.0 12574.2 13371.1 13401.7 13630.0 13844.7 15021.9 15438.8 15519.2 15564.8 15801.2 13865.8 13897.8 14080.1 14260.0 14684.0 13566.0 13684.2 13947.9 14009.9 14880.6 14090.4 14283.1 14543.9 14788.3 15113.2 9885.8 10391.9 10482.9 10546.9 10853.5 11191.7 11220.2 11242.0 11277.4 11289.5 03:28:23 INFO - PROCESS | 29166 | -------- Summary: end -------- 03:28:23 INFO - PROCESS | 29166 | 03:28:24 INFO - TEST-INFO | 29166: exit 0 03:28:24 INFO - TEST-OK | dromaeo_css | took 1114321ms 03:28:24 INFO - TEST-START | kraken 03:28:24 INFO - operating with platform_type : linux_ 03:28:24 INFO - Initialising browser for kraken test... 03:28:24 INFO - TEST-INFO | started process 29361 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpivgqMg/profile http://localhost:41857/getInfo.html) 03:28:28 INFO - PROCESS | 29361 | __metrics Screen width/height:1600/1200 03:28:28 INFO - PROCESS | 29361 | colorDepth:24 03:28:28 INFO - PROCESS | 29361 | Browser inner width/height: 1024/697 03:28:28 INFO - PROCESS | 29361 | __metrics 03:28:29 INFO - PROCESS | 29361 | 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" 03:28:29 INFO - PROCESS | 29361 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:28:30 INFO - TEST-INFO | 29361: exit 0 03:28:30 INFO - Browser initialized. 03:28:30 INFO - Running cycle 1/1 for kraken test... 03:28:30 INFO - TEST-INFO | started process 29443 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpivgqMg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:28:41 INFO - PROCESS | 29443 | RSS: Main: 155705344 03:28:41 INFO - PROCESS | 29443 | 03:28:44 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41857/tests/kraken/driver.html?testName=audio-beat-detection) 03:28:45 INFO - PROCESS | 29443 | RSS: Main: 212160512 03:28:45 INFO - PROCESS | 29443 | 03:28:47 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41857/tests/kraken/driver.html?testName=audio-dft) 03:28:48 INFO - PROCESS | 29443 | RSS: Main: 190767104 03:28:48 INFO - PROCESS | 29443 | 03:28:51 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41857/tests/kraken/driver.html?testName=audio-fft) 03:28:51 INFO - PROCESS | 29443 | RSS: Main: 267841536 03:28:51 INFO - PROCESS | 29443 | 03:28:54 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41857/tests/kraken/driver.html?testName=audio-oscillator) 03:28:54 INFO - PROCESS | 29443 | RSS: Main: 179150848 03:28:54 INFO - PROCESS | 29443 | 03:28:56 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41857/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:28:57 INFO - PROCESS | 29443 | RSS: Main: 183431168 03:28:57 INFO - PROCESS | 29443 | 03:29:00 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41857/tests/kraken/driver.html?testName=imaging-darkroom) 03:29:01 INFO - PROCESS | 29443 | RSS: Main: 218824704 03:29:01 INFO - PROCESS | 29443 | 03:29:04 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41857/tests/kraken/driver.html?testName=imaging-desaturate) 03:29:05 INFO - PROCESS | 29443 | RSS: Main: 219254784 03:29:05 INFO - PROCESS | 29443 | 03:29:07 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41857/tests/kraken/driver.html?testName=json-parse-financial) 03:29:08 INFO - PROCESS | 29443 | RSS: Main: 218648576 03:29:08 INFO - PROCESS | 29443 | 03:29:09 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41857/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:29:10 INFO - PROCESS | 29443 | RSS: Main: 183128064 03:29:10 INFO - PROCESS | 29443 | 03:29:12 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:29:12 INFO - PROCESS | 29443 | RSS: Main: 188887040 03:29:12 INFO - PROCESS | 29443 | 03:29:14 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:29:15 INFO - PROCESS | 29443 | RSS: Main: 193798144 03:29:15 INFO - PROCESS | 29443 | 03:29:18 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:29:18 INFO - PROCESS | 29443 | RSS: Main: 188583936 03:29:18 INFO - PROCESS | 29443 | 03:29:21 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:29:21 INFO - PROCESS | 29443 | RSS: Main: 192532480 03:29:21 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | Cycle 1(1): loaded http://localhost:41857/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41857/tests/kraken/driver.html?testName=ai-astar) 03:29:23 INFO - PROCESS | 29443 | RSS: Main: 243261440 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | __start_tp_report 03:29:23 INFO - PROCESS | 29443 | _x_x_mozilla_page_load 03:29:23 INFO - PROCESS | 29443 | _x_x_mozilla_page_load_details 03:29:23 INFO - PROCESS | 29443 | |i|pagename|runs| 03:29:23 INFO - PROCESS | 29443 | |0;ai-astar;104;112;111;109;118;113;111;113;111;111 03:29:23 INFO - PROCESS | 29443 | |1;audio-beat-detection;141;121;121;182;120;120;144;118;157;124 03:29:23 INFO - PROCESS | 29443 | |2;audio-dft;163;163;162;161;162;161;163;162;160;163 03:29:23 INFO - PROCESS | 29443 | |3;audio-fft;75;76;75;123;75;76;74;74;104;76 03:29:23 INFO - PROCESS | 29443 | |4;audio-oscillator;82;84;123;84;84;84;122;84;83;139 03:29:23 INFO - PROCESS | 29443 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;117;116;116 03:29:23 INFO - PROCESS | 29443 | |6;imaging-darkroom;146;146;146;146;146;145;147;145;146;146 03:29:23 INFO - PROCESS | 29443 | |7;imaging-desaturate;96;95;95;95;95;96;95;95;95;95 03:29:23 INFO - PROCESS | 29443 | |8;json-parse-financial;50;52;50;49;50;50;50;50;49;51 03:29:23 INFO - PROCESS | 29443 | |9;json-stringify-tinderbox;59;60;59;59;60;59;60;60;60;59 03:29:23 INFO - PROCESS | 29443 | |10;stanford-crypto-aes;77;76;76;75;80;75;76;75;75;75 03:29:23 INFO - PROCESS | 29443 | |11;stanford-crypto-ccm;122;125;124;128;121;136;126;119;167;120 03:29:23 INFO - PROCESS | 29443 | |12;stanford-crypto-pbkdf2;161;163;162;157;161;160;157;158;157;160 03:29:23 INFO - PROCESS | 29443 | |13;stanford-crypto-sha256-iterative;62;62;64;62;63;62;62;64;62;63 03:29:23 INFO - PROCESS | 29443 | __end_tp_report 03:29:23 INFO - PROCESS | 29443 | __start_cc_report 03:29:23 INFO - PROCESS | 29443 | _x_x_mozilla_cycle_collect,1710 03:29:23 INFO - PROCESS | 29443 | __end_cc_report 03:29:23 INFO - PROCESS | 29443 | __startTimestamp1470220163861__endTimestamp 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | ------- Summary: start ------- 03:29:23 INFO - PROCESS | 29443 | Number of tests: 14 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#0] ai-astar Cycles:10 Average:111.30 Median:111.50 stddev:3.50 (3.1%) stddev-sans-first:2.52 03:29:23 INFO - PROCESS | 29443 | Values: 104.0 112.0 111.0 109.0 118.0 113.0 111.0 113.0 111.0 111.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#1] audio-beat-detection Cycles:10 Average:134.80 Median:132.50 stddev:21.24 (16.0%) stddev-sans-first:22.41 03:29:23 INFO - PROCESS | 29443 | Values: 141.0 121.0 121.0 182.0 120.0 120.0 144.0 118.0 157.0 124.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.50 stddev:1.05 (0.6%) stddev-sans-first:1.05 03:29:23 INFO - PROCESS | 29443 | Values: 163.0 163.0 162.0 161.0 162.0 161.0 163.0 162.0 160.0 163.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:16.80 (22.4%) stddev-sans-first:17.59 03:29:23 INFO - PROCESS | 29443 | Values: 75.0 76.0 75.0 123.0 75.0 76.0 74.0 74.0 104.0 76.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:84.00 stddev:21.94 (26.1%) stddev-sans-first:22.59 03:29:23 INFO - PROCESS | 29443 | Values: 82.0 84.0 123.0 84.0 84.0 84.0 122.0 84.0 83.0 139.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 03:29:23 INFO - PROCESS | 29443 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 03:29:23 INFO - PROCESS | 29443 | Values: 146.0 146.0 146.0 146.0 146.0 145.0 147.0 145.0 146.0 146.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 03:29:23 INFO - PROCESS | 29443 | Values: 96.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.88 (1.8%) stddev-sans-first:0.93 03:29:23 INFO - PROCESS | 29443 | Values: 50.0 52.0 50.0 49.0 50.0 50.0 50.0 50.0 49.0 51.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 03:29:23 INFO - PROCESS | 29443 | Values: 59.0 60.0 59.0 59.0 60.0 59.0 60.0 60.0 60.0 59.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#10] stanford-crypto-aes Cycles:10 Average:76.00 Median:76.00 stddev:1.56 (2.1%) stddev-sans-first:1.62 03:29:23 INFO - PROCESS | 29443 | Values: 77.0 76.0 76.0 75.0 80.0 75.0 76.0 75.0 75.0 75.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#11] stanford-crypto-ccm Cycles:10 Average:128.80 Median:125.50 stddev:14.29 (11.4%) stddev-sans-first:14.94 03:29:23 INFO - PROCESS | 29443 | Values: 122.0 125.0 124.0 128.0 121.0 136.0 126.0 119.0 167.0 120.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.60 Median:160.50 stddev:2.22 (1.4%) stddev-sans-first:2.30 03:29:23 INFO - PROCESS | 29443 | Values: 161.0 163.0 162.0 157.0 161.0 160.0 157.0 158.0 157.0 160.0 03:29:23 INFO - PROCESS | 29443 | 03:29:23 INFO - PROCESS | 29443 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.60 Median:62.50 stddev:0.84 (1.3%) stddev-sans-first:0.87 03:29:23 INFO - PROCESS | 29443 | Values: 62.0 62.0 64.0 62.0 63.0 62.0 62.0 64.0 62.0 63.0 03:29:23 INFO - PROCESS | 29443 | -------- Summary: end -------- 03:29:23 INFO - PROCESS | 29443 | 03:29:24 INFO - TEST-INFO | 29443: exit 0 03:29:24 INFO - TEST-OK | kraken | took 60301ms 03:29:24 INFO - SUITE-END | took 1174s 03:29:25 INFO - Completed test suite (00:19:34) 03:29:25 INFO - javascript benchmark 03:29:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3013.7890347675666, 3048.207466058824, 3067.7699172562066, 3070.907872763817, 3077.3999070181308, 5192.247124166994, 5206.609504287175, 5260.079486089933, 5349.983503134402, 5353.036453438384, 5309.097453432916, 5311.49540793624, 5336.733163341827, 5394.838154855361, 5417.864553386167, 4694.600958918488, 4808.495108013661, 4862.246351815456, 4873.463918968929, 5023.171176755831, 4927.704337739747, 4936.33359496467, 4965.826196083138, 4982.526659967315, 4986.775595098211, 4697.271922851964, 4747.216709242971, 4819.277108433728, 4890.73100979446, 4929.21132618781, 4779.952200477986, 4790.089882922233, 4798.328234047236, 4847.345608342864, 4922.483139270361, 4796.0, 4819.42166939966, 4822.493638168012, 4846.0, 4856.15017371962, 5111.182210846284, 5235.921461178085, 5283.392409872876, 5341.599380046473, 5365.03429382711, 5753.626014309057, 5756.712164391805, 5763.740631671585, 5771.740271687742, 5820.767169313222, 5824.737886795062, 5883.588148829584, 5885.911711324334, 5894.145348924382, 5895.970520147414, 5196.142636464979, 5242.1088414969545, 5242.947570524322, 5261.947380526222, 5465.508104270635, 5803.970980145156, 5823.621464604787, 5867.354590994987, 5869.178315035899, 5875.030619947661, 5587.469190426903, 5595.328560572757, 5632.633878797947, 5640.238567793377, 5642.943570564242, 5520.089185284463, 5698.287714035746, 5706.857328566736, 5755.740991655385, 5761.798337058183, 5751.539876809845, 5765.1928729977835, 5766.106253530709, 5770.480656740913, 5803.912941305884, 5714.657120572779, 5762.654240745569, 5764.452377024176, 5778.27771528559, 5778.913316300259, 4173.499180098408, 4269.316909294498, 4270.722403043792, 4321.286987647066, 4449.57729015743, 3836.827342769582, 3867.555231148454, 3913.96086039136, 3920.2747491714126, 3943.960560394359, 5455.208994695747, 5457.69982650958, 5460.754266058037, 5468.671879687232, 5507.449255074461, 5537.667739935617, 5778.393268706809, 5782.768689252467, 5784.1034639630925, 5828.475437210672, 5346.652467589594, 5386.13821788512, 5400.0, 5477.4248703886315, 5484.862878428071, 4914.164592019294, 4916.311716359713, 4916.631252656065, 4918.262260660929, 4921.975390123026, 5962.135490353788, 5962.25471816023, 5967.104934259809, 5972.0, 5975.492083172979, 5471.753771080232, 5720.08478643415, 5758.913616295675, 5760.855978600569, 5831.912521312099, 6009.278886533644, 6010.248718910137, 6013.7293821779, 6017.06735456005, 6020.488258498077, 5698.20225168485, 5698.715064246689, 5699.173619825019, 5699.202111704281, 5710.114932185518, 5474.507294343528, 5664.0654292043, 5691.430856914276, 5703.7433315500075, 5799.101139323243, 5550.417206193291, 5583.832485025456, 5600.523955463831, 5601.0, 5604.047311956896, 5600.971995139998, 5609.49514543693, 5614.663120212637, 5616.382197958303, 5618.971905140448, 5297.152455607084, 5453.7818487260065, 5455.372632147195, 5498.340199176125, 5592.720363981866, 5583.41374155724, 5591.608587398843, 5638.407967163389, 5641.9435805641415, 5647.491725744702, 5471.097268950579, 5489.670619762827, 5490.450954904637, 5495.285612870462, 5496.422875598165], "value": 5272.290158474998, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [554.5556258208883, 573.2118337286234, 590.3181824992134, 590.7400743672787, 606.1302031584677, 32374.83812580937, 32947.34105317892, 35321.64678353216, 35347.4697879532, 43601.0, 42881.35677964825, 46871.296930545985, 47086.29370559444, 47118.29322560164, 47193.29210061843, 8969.282457403424, 9126.543672816366, 9131.908680913206, 9137.0, 9138.817223655524, 8977.596008179615, 9056.366054376196, 9067.95466022669, 9071.0, 9071.863922041142, 9117.544122793834, 9154.72535823923, 9167.541622918861, 9168.31237657175, 9172.31207659425, 9061.13919177677, 9098.909010909938, 9107.908920910839, 9107.954460227722, 9109.0, 8919.152680495343, 8989.101089891024, 8996.145366190201, 8998.14517620825, 9001.909980900173, 43862.78068609668, 44063.55936440659, 44184.779076104416, 44208.0, 44223.33664995027, 3688.354537955847, 3753.774773513571, 3899.6490315871706, 3983.7410568312966, 4059.8961839369304, 33118.3376332472, 33918.32163356744, 34882.65117348819, 34889.82555087234, 35573.0, 4391.604755572014, 4449.688521803475, 4460.821567137311, 4468.575485328888, 4655.208614535537, 347144.0, 347442.0, 348132.0, 348229.0, 348379.0, 61052.694736526035, 61295.38704612897, 61373.38626613766, 61395.38604613986, 61450.69274653688, 54164.0, 60614.696926515084, 62379.37620623827, 62790.68604657039, 68469.65765171242, 13623.523176688768, 13665.18008919468, 13716.314184290746, 13726.725465490634, 13785.448582056806, 12007.27956322606, 12136.87863121375, 12177.26936383802, 12192.0, 12198.878011219951, 13755.243461609707, 17450.301987920593, 17942.192601332972, 18037.18832652534, 18338.358157464692, 5774.422557744192, 5837.402355951561, 5846.970765146147, 5860.384659610765, 5958.225430693982, 927.9953600231955, 999.4952548962873, 1071.0628200324716, 1100.6532942123206, 1142.3602782441692, 27467.31331716723, 29387.265318366783, 29861.850690746407, 31192.376152476823, 32028.359432811678, 1048.5858086056119, 1075.9085477734325, 1080.2492267873752, 1105.6848798092506, 1147.5868687272575, 16006.199690015686, 16096.505041718317, 16157.676846463004, 16226.188690565425, 16450.917745411196, 496243.0, 501381.0, 502574.0, 503499.0, 505424.0, 60274.397256027754, 60316.396836031076, 60467.69766151229, 60594.0, 60621.39378606246, 1103.8454616353717, 1313.8554758976504, 1358.3140514040347, 1375.312343828106, 1381.799639052352, 27384.315392115357, 28534.57198141988, 28624.0, 29122.12633620995, 29196.0, 52097.479025209264, 52721.0, 52813.0, 52887.20669190042, 52905.7354713224, 383896.0, 384177.0, 384791.0, 385621.0, 388500.0574997107, 28385.29036774097, 29633.555496668, 29705.25736856596, 30769.846150769103, 30783.076507704805, 46994.53005469902, 47711.0, 47838.28242576295, 48112.27831582459, 49384.0, 39864.2027159461, 39924.60075399209, 41019.58980410277, 41139.794301028305, 42531.0, 39758.2048359037, 40063.59936400599, 40963.59036409598, 41044.58955410407, 41428.0, 41641.167176656905, 41872.371914421914, 42381.36427953521, 42787.57212427836, 43379.0, 36253.27493450169, 39058.60941390663, 39356.0, 40041.0, 40175.3973690389, 28806.09946283173, 29231.561526576694, 29324.0, 29534.409311813215, 29544.89778573305], "value": 19837.012556478858, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.18581797321235, 377.1514093290097, 382.63076131533063, 384.6307544757033, 405.5113588126308, 15336.46322378717, 18182.090895455225, 18566.535836604107, 20430.182792688276, 21014.0, 18575.164117614706, 19191.0, 19516.902415487937, 19993.400197994084, 20030.39908802738, 5089.821856235023, 5221.347331583552, 5226.216067589873, 5229.947700523003, 5771.192033115346, 5953.345132035494, 6127.203463549732, 6130.81607551772, 6135.447809697126, 6135.631862088267, 3874.2832575973534, 5757.078867381219, 5992.131140984555, 6035.7585696572305, 6103.633781973096, 6075.33171351151, 6075.875962946847, 6077.7568897244055, 6162.5378096642935, 6635.568688035262, 5925.822225333204, 5932.0508718604815, 5937.198478205473, 5937.821865344046, 5938.910916336215, 14455.927720361437, 14466.638334041629, 14626.488072917396, 14841.257937103102, 20784.75291482516, 2077.210659949225, 2199.8570092943905, 2201.2405720026563, 2252.155441709359, 2442.8884857389844, 10272.845907311395, 10804.297720648212, 10847.783044339149, 11477.253978491372, 12461.501539938392, 1983.3355825798376, 2009.2465325502938, 2044.9386518404472, 2049.7847725988704, 2081.323569839793, 84096.0, 86044.56977715071, 86357.56821215854, 86680.0, 86740.56629716874, 9381.718548443558, 9974.501274936225, 10007.09936105754, 11244.269122507174, 11429.142814288962, 13202.801957970638, 13268.469261229635, 13330.200187988557, 13348.599542013755, 13349.132306400053, 7470.589117598584, 7497.625118744041, 7632.885506717404, 7667.776344859928, 7738.690452381954, 7408.666610002563, 7545.547267163988, 7671.808204794925, 7733.613319334011, 7776.455648104578, 3848.3265428549894, 4057.3102572562734, 4108.239975604522, 4238.597333253336, 4254.29804082323, 1883.8116188381327, 1906.961860762777, 1911.8183772541586, 1949.5710943592387, 2002.389271272276, 881.316979341003, 988.3476905242505, 988.4217732626494, 1039.0129377091794, 1040.4485622619873, 19432.0, 19792.802071979382, 20505.2823151189, 20600.690989635168, 20629.484262893548, 966.3415794481385, 967.9661211857551, 981.1611072533025, 993.7267251505893, 1015.3298822776932, 10856.837147442795, 10916.727081823017, 11396.14528910335, 11567.074634029426, 11773.234739741889, 467282.0, 469187.0, 482329.0, 488219.0, 496264.0, 65207.67396162989, 83518.0, 97155.0, 100889.49555252177, 102503.0, 987.511181964932, 1003.9849402259118, 1031.7781676939344, 1078.6763970808884, 1081.313366012572, 18115.456536303933, 18409.171587278335, 18912.10878912153, 19110.734068566075, 19145.425637230906, 43871.0, 43948.92357534942, 44585.331220031076, 44979.0, 45643.77178114221, 61098.38901611105, 62024.0, 62616.37383626105, 64082.67958660177, 64582.354176459514, 11787.351695656822, 12349.444275007467, 12859.16415432994, 12953.740925181632, 12998.350082495648, 14076.718465630835, 14271.0, 14465.0, 14547.636309092357, 14729.92635036818, 12745.681357966125, 12842.422091006114, 12842.550510732453, 12906.870931290567, 12942.288174150512, 12552.497900083894, 12557.0, 12570.748585028432, 12727.80908286358, 12871.099023068295, 12390.31853248044, 12880.871191287968, 12886.48454061827, 13123.54067607629, 13220.603381898558, 12946.67633309137, 13029.543965961146, 13164.868351316747, 13301.135426197266, 13493.0, 12564.748705026032, 12702.174358666656, 12766.361681916052, 12779.233246004898, 13265.071444998757], "value": 9460.977609199117, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1246.302070840329, 1444.8844092472602, 1495.6559991202023, 1595.4256467671637, 1747.1700942052519, 7923.801904952372, 8226.136255693149, 8365.539895305756, 8866.601002954867, 8867.91132088679, 8597.1402859714, 8683.869741953877, 8699.260562852165, 8712.346574006942, 8718.128187181279, 2281.8288628352857, 2305.0664480885266, 2320.385097949045, 2322.7677232276724, 2322.941926451835, 2240.9887950560305, 2271.602469567819, 2307.457747429353, 2311.144876395736, 2318.014843691433, 2267.8866056697098, 2282.429392651837, 2286.325533967484, 2310.653401989698, 2311.6648086027517, 2251.6960210371553, 2291.9083236670594, 2293.1973809166657, 2297.4026753044245, 2299.4136495193784, 2243.7083179186766, 2253.3803204119, 2258.0, 2260.2993072147688, 2262.1064679451524, 8653.826923461496, 8675.479471231747, 8705.085965973545, 8726.69456569017, 8740.562971851446, 5001.149804533238, 5173.32746742921, 5182.144946083929, 5196.32447781786, 5205.791768329263, 3389.7288216942584, 3554.591222009476, 3584.2652256287397, 3609.8014609196484, 3627.873024444158, 1179.9764004719857, 1233.105998151333, 1236.2150034727922, 1260.3887114749339, 1272.7327261275143, 12166.270023798601, 12348.753024939451, 12354.938225308906, 12416.317102559356, 12450.937745311307, 4145.564715704868, 4315.115401342718, 4451.1765323415275, 4459.1304695584695, 4481.484629267611, 9667.226621870233, 9667.661631842851, 9669.758256043655, 9671.854922176173, 9696.321257512047, 5034.043531728959, 5039.571636410873, 5042.647014708935, 5045.167547354718, 5046.470120637354, 3988.3419235826345, 4633.4671512775785, 4665.790039448233, 4761.166795810788, 4786.688865223749, 3623.8912832615065, 3728.105254738843, 3731.533558305212, 3761.6458075092946, 3845.250176215611, 1144.9656510304703, 1147.3173461790223, 1151.988480115205, 1169.497116240008, 1169.8888605582456, 857.6869442653511, 944.1974321826392, 1012.1750773119884, 1026.5534492495788, 1036.9792604147874, 5998.580099393088, 6058.242719660043, 6061.242344706911, 6231.594946328474, 6328.493720502349, 488.8582311129804, 491.1012846490913, 498.46913037614826, 501.6237821633775, 502.0160485448489, 4058.7564746115327, 4193.433886425293, 4212.831486740496, 4221.155768846182, 4347.673924455678, 9745.31782775199, 10069.899301006895, 10331.793364132825, 10437.530311136017, 10440.0, 10050.396976181453, 10068.949655251678, 10071.14395276395, 10088.243381746399, 10110.747231319276, 7705.807354816062, 7785.0, 7789.493682910593, 7789.922100779033, 7793.610319484003, 5850.882982340415, 5875.588708790344, 5892.94107058924, 5925.318588362221, 6021.157038014769, 9248.953755231181, 9329.253659707072, 9355.25157987373, 9400.200982916254, 9489.952550237205, 9319.906800931903, 9498.810023799624, 9560.187384072431, 9572.282078843975, 9581.760455988657, 6251.374862513894, 6813.0461735358085, 6876.553024053421, 7052.0, 7196.136463624399, 8332.458390204618, 8478.703245386381, 8489.490630562186, 8490.32077433816, 8492.108328625405, 7164.534305270141, 7219.061522002106, 7238.746643867439, 7450.515716478411, 7477.252274772479, 6997.545159564612, 7007.404370628554, 7040.964795175992, 7073.964630177022, 7167.964160179375, 6327.620342779263, 6466.4503517201565, 6513.54405191632, 6609.306022867722, 6625.105610742488, 5000.324956131021, 5127.2052831811125, 5147.279380886604, 5279.683219006872, 5369.758360873848, 5084.872878178076, 5227.0591293566, 5231.607629427732, 5295.099833028471, 5585.497305242548], "value": 4437.796645069688, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.62293624714107, 320.6937374807059, 321.7618961968144, 332.2723236112913, 332.41494968854806, 5283.260343551901, 5304.867378315539, 5309.309789727335, 5310.256564081032, 5319.0, 4986.501349865011, 5061.9622977289655, 5069.366329208849, 5092.643514953955, 5267.736613169345, 3963.4253033310297, 4017.5536806749546, 4093.16090201508, 4134.958650413502, 4171.916561668765, 4141.7514949103, 4142.834286628531, 4144.171165766844, 4158.397032430295, 4161.292580261348, 3897.2010737798823, 4020.8190631421658, 4109.938350924738, 4128.8761337159785, 4140.378943158536, 4053.8783836484954, 4057.371107478316, 4083.673306135502, 4090.3046482098116, 4091.6931230157707, 3588.372034893909, 3593.389123848952, 3598.874039408608, 3599.2441587266703, 3609.945850812214, 4851.660383773137, 5123.846284611468, 5131.640785145041, 5131.794728210867, 5136.897262054776, 4616.192166370872, 4646.581807637329, 4654.790534425959, 4657.510961349043, 4660.836870709509, 5423.213634023084, 5465.207544906006, 5467.152591348347, 5469.206964990094, 5486.094794358966, 3844.884983354824, 3939.9015024624327, 3948.723589348747, 3951.8539623509146, 3954.5847685992844, 5604.411536788619, 5636.943630563683, 5670.886582268332, 5675.801346952837, 5680.233168522238, 4011.5587285398583, 4014.558398576154, 4018.578049304839, 4020.9195816083516, 4032.4152997815154, 4052.6352628263007, 4065.4918135233097, 4072.7781665500233, 4079.8572049978106, 4081.142959978438, 4516.16450956574, 4603.8849028773875, 4612.308153776893, 4620.699654522446, 4648.188485441973, 5435.483629055233, 5436.565074794007, 5445.87610831776, 5446.482584154578, 5454.699991500505, 1321.4383886848052, 1383.5295999360267, 1393.3520912775607, 1396.211140705498, 1397.70775927479, 922.579227988903, 923.8060007398387, 958.9520523973773, 961.5384615384514, 965.5027660754716, 5202.739863006835, 5214.243934629533, 5246.291750613695, 5267.498762852569, 5282.81510147143, 5388.703621300866, 5497.22390192958, 5521.365043020024, 5526.226328314039, 5529.557635389239, 5144.405234377355, 5174.197999310113, 5191.662541934762, 5211.843644690665, 5240.842774716764, 2938.3682508260827, 2940.4413161499247, 2941.57347184657, 2942.6321709786275, 2970.420767950272, 5562.7774889004795, 5612.494875461228, 5721.771129154788, 5735.0, 5774.364819869811, 5726.770929162787, 5743.645105506722, 5766.596338256281, 5794.246747922743, 5797.536197104221, 5822.359540450547, 5851.970740146272, 5866.765329386777, 5874.324452687824, 5885.411458854166, 5517.558595312285, 5610.5231055360755, 5637.774489020558, 5641.774329026793, 5648.491635752802, 5543.281582709212, 5568.972155139199, 5579.581531385081, 5579.609427340048, 5581.804636837691, 5592.189132575835, 5773.797917072882, 5845.473907348359, 5865.354810970704, 5867.706614669335, 3917.666998305062, 3948.684105271629, 3959.3071212538266, 3965.0483883867087, 3967.7978721808518, 5624.74688639004, 5630.845676636198, 5646.0076159635455, 5651.88696226065, 5668.971655141698, 5275.7362131894015, 5502.294288770494, 5508.091164957737, 5516.482967184056, 5565.30433695788, 5533.750981205775, 5536.2249285100925, 5537.473939975695, 5549.250851135045, 5559.6664200148125, 5239.973800130975, 5270.288511050959, 5438.428964958623, 5513.61404701667, 5514.393416724238, 5503.459031471194, 5521.309836270466, 5530.364008139116, 5530.419305972815, 5537.169424586345, 5401.675899446046, 5412.864678383072, 5432.29380180574, 5434.701091440009, 5436.266104075899], "value": 4177.989003381992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.1033535595674, 768.2039015627093, 774.2451110167586, 815.5636734347125, 833.9207775261348, 15297.388104475822, 16588.834111658878, 16642.25109870056, 16706.49880503585, 16724.247408866617, 14803.629909252275, 15373.385064597403, 15435.151066691327, 15518.68962620747, 15538.611534711637, 817.1664901800175, 827.6854795177824, 835.4068611285994, 835.8328334333128, 837.5602808525512, 677.3294438505868, 808.8139727862596, 840.9789755256106, 841.5792103948025, 845.1464021338431, 881.6209030117047, 883.0198479687542, 883.5670521444478, 883.779055236191, 886.6719313853883, 876.2551830943711, 876.2989608313355, 876.3602570123813, 877.4209022045483, 879.6393478673713, 512.8487096306599, 531.3039917707815, 617.3425302053299, 639.3925770518026, 639.6354078175442, 15226.162561059138, 15881.761773573406, 15886.602834929103, 15910.363585456567, 15918.124503152321, 14386.92806535971, 14688.339024743913, 14929.253537323091, 15019.624509387244, 15040.0, 13374.197548147142, 13445.731085378335, 13461.528846490424, 13587.796183057262, 13729.862701372958, 4614.8154073837, 4717.339572459859, 4818.68678535894, 4829.879253018667, 4850.733209673466, 80590.0, 80833.59583202105, 81231.18768812355, 82239.0, 82518.0, 12082.802706819451, 12089.395530223454, 12140.575079872338, 12275.38623068843, 12817.551385701643, 22801.543969120525, 22806.429839254153, 22868.885655571616, 22997.195098171484, 23003.539929201655, 8623.137686231326, 8723.302135829119, 8909.064548222474, 9069.727908162766, 9075.13786190311, 4860.829870954571, 4904.411470623548, 4918.898980131035, 4986.6010719143105, 5066.873328166752, 3501.8949431517094, 3622.909427264287, 3644.781313121221, 3648.7810731356203, 3650.799206043693, 634.9650769207645, 639.1371648274792, 648.4714957309777, 654.028767280588, 659.5383231737803, 555.5111501878401, 598.9101634754821, 637.6238019568477, 638.1703785079378, 669.2671524680467, 9333.113354231336, 9586.616535338508, 9642.662506812369, 9687.225021998223, 9924.702258932244, 636.2301615045847, 653.859420224654, 669.3875104279628, 705.340506626306, 712.4870093532649, 4332.1985432695055, 4553.908921821545, 4589.311603259538, 4638.118757436144, 4714.0807542529565, 95816.52091739497, 98240.50879745555, 99380.0, 113764.43117784358, 129252.0, 94584.0, 96836.51581742187, 97316.0, 98951.0, 100984.0, 54456.727716361165, 69857.0, 84329.0, 88240.11759882448, 90614.54692726626, 10377.740556486147, 10456.26806123564, 10463.895361046292, 10572.154227661922, 10627.734306642396, 42478.78760606177, 48594.514054859, 49307.26039109344, 50775.0, 52486.737566311924, 72701.0, 74449.25550744424, 75274.24725752672, 77513.51189196612, 78407.0, 12574.245545267313, 13371.130876492996, 13401.731965360834, 13630.0, 13844.684754948265, 15021.924890375916, 15438.768418473508, 15519.22403879824, 15564.766528502309, 15801.209939502749, 13865.792013120014, 13897.791533127214, 14080.084794488326, 14260.0, 14684.045537040058, 13566.0, 13684.247366394944, 13947.860521394656, 14009.859901401263, 14880.62798430048, 14090.411325448165, 14283.143011419348, 14543.85456145425, 14788.260586970393, 15113.168775717002, 9885.752856178653, 10391.948040259751, 10482.947585262025, 10546.894531054591, 10853.457327133454, 11191.66425007251, 11220.15848811326, 11242.0, 11277.379744114152, 11289.548418063185], "value": 7714.828836712335, "unit": "score"}], "name": "dromaeo_css", "value": 7219.1596608563505, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 111.0, 109.0, 118.0, 113.0, 111.0, 113.0, 111.0, 111.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 121.0, 121.0, 182.0, 120.0, 120.0, 144.0, 118.0, 157.0, 124.0], "value": 134.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 162.0, 161.0, 162.0, 161.0, 163.0, 162.0, 160.0, 163.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 123.0, 75.0, 76.0, 74.0, 74.0, 104.0, 76.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 123.0, 84.0, 84.0, 84.0, 122.0, 84.0, 83.0, 139.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 50.0, 49.0, 50.0, 50.0, 50.0, 50.0, 49.0, 51.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 76.0, 75.0, 80.0, 75.0, 76.0, 75.0, 75.0, 75.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 125.0, 124.0, 128.0, 121.0, 136.0, 126.0, 119.0, 167.0, 120.0], "value": 128.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 157.0, 161.0, 160.0, 157.0, 158.0, 157.0, 160.0], "value": 159.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 62.0, 63.0, 62.0, 62.0, 64.0, 62.0, 63.0], "value": 62.6, "unit": "score"}], "name": "kraken", "value": 1482.1, "alertThreshold": 2.0}]} 03:29:25 INFO - Return code: 0 03:29:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:29:25 INFO - # TBPL SUCCESS # 03:29:25 INFO - Running post-action listener: _resource_record_post_action 03:29:25 INFO - [mozharness: 2016-08-03 10:29:25.155766Z] Finished run-tests step (success) 03:29:25 INFO - Running post-run listener: _resource_record_post_run 03:29:25 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 8192; Write bytes: 548794368; Read time: 260; Write time: 526112 03:29:25 INFO - TinderboxPrint: CPU usage
13.0% 03:29:25 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 260 03:29:25 INFO - TinderboxPrint: I/O write bytes / time
548,794,368 / 526,112 03:29:25 INFO - TinderboxPrint: CPU idle
8,276.6 (87.0%) 03:29:25 INFO - TinderboxPrint: CPU user
1,192.8 (12.5%) 03:29:25 INFO - TinderboxPrint: Swap in / out
0 / 0 03:29:25 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254689280; Read time: 0; Write time: 253596 03:29:25 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294105088; Read time: 240; Write time: 272516 03:29:25 INFO - Running post-run listener: _upload_blobber_files 03:29:25 INFO - Blob upload gear active. 03:29:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:29: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 03:29: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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:29: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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:29:26 INFO - (blobuploader) - INFO - Open directory for files ... 03:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:29:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:30 INFO - (blobuploader) - INFO - Done attempting. 03:29:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:29:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:29:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:31 INFO - (blobuploader) - INFO - Done attempting. 03:29:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:29:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:29:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:29:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:29:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:29:32 INFO - (blobuploader) - INFO - Done attempting. 03:29:32 INFO - (blobuploader) - INFO - Iteration through files over. 03:29:32 INFO - Return code: 0 03:29:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:29:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:29:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdc144e42920ba097dd0a2b24911d64a910eeb88ab85e14fee8ee121738656486707771fc394a482cf10ff4dea969437a220c6ed7e2aa33d14d11f87c2ca1402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4061a96c52bbcb986205e0667807ebeab9164e9f9f910a5aedd24df7e7e0d6415632304bb5231750ec269435e756c99b6d8ac53cc7e841d53701d42e8994cc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1046a120a2261c6460dddead01d2f7c9d408aa35d22ca7839775ba8938f95524307d519f8727f63b863e01fe746241cb25001533263dde307b6716851e2282ec"} 03:29:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:29:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:29:32 INFO - Contents: 03:29:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdc144e42920ba097dd0a2b24911d64a910eeb88ab85e14fee8ee121738656486707771fc394a482cf10ff4dea969437a220c6ed7e2aa33d14d11f87c2ca1402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4061a96c52bbcb986205e0667807ebeab9164e9f9f910a5aedd24df7e7e0d6415632304bb5231750ec269435e756c99b6d8ac53cc7e841d53701d42e8994cc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1046a120a2261c6460dddead01d2f7c9d408aa35d22ca7839775ba8938f95524307d519f8727f63b863e01fe746241cb25001533263dde307b6716851e2282ec"} 03:29:32 INFO - Running post-run listener: copy_logs_to_upload_dir 03:29:32 INFO - Copying logs to upload dir... 03:29:32 INFO - mkdir: /builds/slave/test/build/upload/logs 03:29:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.149124 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-08-03 03:29:32.025520) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 03:29:32.028724) ========= 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=1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668 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/sha512/cdc144e42920ba097dd0a2b24911d64a910eeb88ab85e14fee8ee121738656486707771fc394a482cf10ff4dea969437a220c6ed7e2aa33d14d11f87c2ca1402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4061a96c52bbcb986205e0667807ebeab9164e9f9f910a5aedd24df7e7e0d6415632304bb5231750ec269435e756c99b6d8ac53cc7e841d53701d42e8994cc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1046a120a2261c6460dddead01d2f7c9d408aa35d22ca7839775ba8938f95524307d519f8727f63b863e01fe746241cb25001533263dde307b6716851e2282ec"} build_url:https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011165 build_url: 'https://queue.taskcluster.net/v1/task/BU07S-FLRO2zFpJBQHwaPw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cdc144e42920ba097dd0a2b24911d64a910eeb88ab85e14fee8ee121738656486707771fc394a482cf10ff4dea969437a220c6ed7e2aa33d14d11f87c2ca1402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/e4061a96c52bbcb986205e0667807ebeab9164e9f9f910a5aedd24df7e7e0d6415632304bb5231750ec269435e756c99b6d8ac53cc7e841d53701d42e8994cc5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/1046a120a2261c6460dddead01d2f7c9d408aa35d22ca7839775ba8938f95524307d519f8727f63b863e01fe746241cb25001533263dde307b6716851e2282ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-03 03:29:32.056162) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:29:32.056751) ========= 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=1f5564757af27664ca02d71f000001b0-1470217767.687772-758046668 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004416 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-03 03:29:32.141573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 03:29:32.141980) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-03 03:29:32.142480) ========= ========= Total master_lag: 0.18 =========