builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-017 starttime: 1469680857.37 results: success (0) buildid: 20160727201023 builduid: c079b69ded0d48ca9fe2fa963638c899 revision: 4d52a4c41a7754ef4c14f4f02b3273a5469de156 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.372617) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.373113) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.373811) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.416535) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.416803) ========= 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=0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-27 21:40: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.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 22.9M=0.001s 2016-07-27 21:40:57 (22.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083966 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.515696) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.515990) ========= 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=0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015451 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.567091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:57.567494) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 4d52a4c41a7754ef4c14f4f02b3273a5469de156 --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 4d52a4c41a7754ef4c14f4f02b3273a5469de156 --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=0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-27 21:40:57,847 truncating revision to first 12 chars 2016-07-27 21:40:57,847 Setting DEBUG logging. 2016-07-27 21:40:57,847 attempt 1/10 2016-07-27 21:40:57,847 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/4d52a4c41a77?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-27 21:40:58,145 unpacking tar archive at: autoland-4d52a4c41a77/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623735 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:58.206527) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:58.206842) ========= script_repo_revision: 4d52a4c41a7754ef4c14f4f02b3273a5469de156 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:58.207262) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:58.207573) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-27 21:40:58.217953) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-27 21:40:58.218269) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:40:58 INFO - MultiFileLogger online at 20160727 21:40:58 in /builds/slave/test 21:40:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland-Non-PGO 21:40:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:40:58 INFO - {'append_to_log': False, 21:40:58 INFO - 'base_work_dir': '/builds/slave/test', 21:40:58 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 21:40:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:40:58 INFO - 'branch': 'Autoland-Non-PGO', 21:40:58 INFO - 'buildbot_json_path': 'buildprops.json', 21:40:58 INFO - 'config_files': ('talos/linux_config.py',), 21:40:58 INFO - 'default_actions': ('clobber', 21:40:58 INFO - 'read-buildbot-config', 21:40:58 INFO - 'download-and-extract', 21:40:58 INFO - 'populate-webroot', 21:40:58 INFO - 'create-virtualenv', 21:40:58 INFO - 'install', 21:40:58 INFO - 'run-tests'), 21:40:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:40:58 INFO - 'download_minidump_stackwalk': True, 21:40:58 INFO - 'download_symbols': 'ondemand', 21:40:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:40:58 INFO - 'tooltool.py': '/tools/tooltool.py', 21:40:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:40:58 INFO - '/tools/misc-python/virtualenv.py')}, 21:40:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:40:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:40:58 INFO - 'installer_path': 'installer.exe', 21:40:58 INFO - 'log_level': 'info', 21:40:58 INFO - 'log_name': 'talos', 21:40:58 INFO - 'log_to_console': True, 21:40:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:40:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:40:58 INFO - 'opt_config_files': (), 21:40:58 INFO - 'pip_index': False, 21:40:58 INFO - 'sps_profile': False, 21:40:58 INFO - 'sps_profile_interval': 0, 21:40:58 INFO - 'suite': 'dromaeojs', 21:40:58 INFO - 'system_bits': '32', 21:40:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:40:58 INFO - 'title': 'talos-linux64-ix-017', 21:40:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:40:58 INFO - 'use_talos_json': True, 21:40:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:40:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:40:58 INFO - 'work_dir': 'build'} 21:40:58 INFO - [mozharness: 2016-07-28 04:40:58.593149Z] Running clobber step. 21:40:58 INFO - Running pre-action listener: _resource_record_pre_action 21:40:58 INFO - Running main action method: clobber 21:40:58 INFO - rmtree: /builds/slave/test/build 21:40:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:40:59 INFO - Running post-action listener: _resource_record_post_action 21:40:59 INFO - [mozharness: 2016-07-28 04:40:59.064052Z] Finished clobber step (success) 21:40:59 INFO - [mozharness: 2016-07-28 04:40:59.064153Z] Running read-buildbot-config step. 21:40:59 INFO - Running pre-action listener: _resource_record_pre_action 21:40:59 INFO - Running main action method: read_buildbot_config 21:40:59 INFO - Using buildbot properties: 21:40:59 INFO - { 21:40:59 INFO - "project": "", 21:40:59 INFO - "product": "firefox", 21:40:59 INFO - "script_repo_revision": "production", 21:40:59 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 21:40:59 INFO - "repository": "", 21:40:59 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 21:40:59 INFO - "buildid": "20160727201023", 21:40:59 INFO - "pgo_build": "False", 21:40:59 INFO - "basedir": "/builds/slave/test", 21:40:59 INFO - "buildnumber": 139, 21:40:59 INFO - "slavename": "talos-linux64-ix-017", 21:40:59 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 21:40:59 INFO - "platform": "ubuntu64_hw", 21:40:59 INFO - "scheduler": "tests-autoland-linux64-talos", 21:40:59 INFO - "branch": "autoland", 21:40:59 INFO - "repo_path": "integration/autoland", 21:40:59 INFO - "revision": "4d52a4c41a7754ef4c14f4f02b3273a5469de156", 21:40:59 INFO - "stage_platform": "linux64", 21:40:59 INFO - "builduid": "c079b69ded0d48ca9fe2fa963638c899", 21:40:59 INFO - "slavebuilddir": "test" 21:40:59 INFO - } 21:40:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 21:40:59 INFO - Running post-action listener: _resource_record_post_action 21:40:59 INFO - [mozharness: 2016-07-28 04:40:59.066599Z] Finished read-buildbot-config step (success) 21:40:59 INFO - [mozharness: 2016-07-28 04:40:59.066689Z] Running download-and-extract step. 21:40:59 INFO - Running pre-action listener: _resource_record_pre_action 21:40:59 INFO - Running main action method: download_and_extract 21:40:59 INFO - mkdir: /builds/slave/test/build/tests 21:40:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:40:59 INFO - https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:40:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:40:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:40:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:40:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:41:00 INFO - Downloaded 1361 bytes. 21:41:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 21:41:00 INFO - Using the following test package requirements: 21:41:00 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:41:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 21:41:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 21:41:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'jsshell-linux-x86_64.zip'], 21:41:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 21:41:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 21:41:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 21:41:00 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 21:41:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 21:41:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 21:41:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 21:41:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:41:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:00 INFO - https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:41:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:41:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:41:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:41:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:41:01 INFO - Downloaded 22746771 bytes. 21:41:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:41:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:41:03 INFO - Return code: 0 21:41:03 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:41: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')]} 21:41:03 INFO - https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:41:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:41:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:41:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 21:41:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:41:05 INFO - Downloaded 22746771 bytes. 21:41:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:41:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:41:06 INFO - Return code: 0 21:41:06 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')]} 21:41:06 INFO - https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:41:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:41:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:41:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 21:41:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:41:07 INFO - Downloaded 14413725 bytes. 21:41:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:41:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:41:07 INFO - Return code: 0 21:41: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')]} 21:41:07 INFO - https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:41:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:41:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:41:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:41:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:41:11 INFO - Downloaded 57154478 bytes. 21:41:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:41:11 INFO - mkdir: /builds/slave/test/properties 21:41:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:41:11 INFO - Writing to file /builds/slave/test/properties/build_url 21:41:11 INFO - Contents: 21:41:11 INFO - build_url:https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 21:41:12 INFO - Running post-action listener: _resource_record_post_action 21:41:12 INFO - Running post-action listener: set_extra_try_arguments 21:41:12 INFO - [mozharness: 2016-07-28 04:41:12.498714Z] Finished download-and-extract step (success) 21:41:12 INFO - [mozharness: 2016-07-28 04:41:12.498948Z] Running populate-webroot step. 21:41:12 INFO - Running pre-action listener: _resource_record_pre_action 21:41:12 INFO - Running main action method: populate_webroot 21:41:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:41:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:41:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:41:12 INFO - '--tppagecycles', 21:41:12 INFO - '7'], 21:41:12 INFO - 'tests': ['tsvgm']}}, 21:41:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:41:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:41:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:41:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:41:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:41:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:41:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['damp', 'tps']}, 21:41:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'tests': ['damp', 'tps']}, 21:41:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['dromaeo_dom']}, 21:41:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:41:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['basic_compositor_video']}, 21:41:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:41:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['a11yr', 21:41:12 INFO - 'ts_paint', 21:41:12 INFO - 'tpaint', 21:41:12 INFO - 'sessionrestore', 21:41:12 INFO - 'sessionrestore_no_auto_restore', 21:41:12 INFO - 'tabpaint']}, 21:41:12 INFO - 'other-e10s': {'tests': ['a11yr', 21:41:12 INFO - 'ts_paint', 21:41:12 INFO - 'tpaint', 21:41:12 INFO - 'sessionrestore', 21:41:12 INFO - 'sessionrestore_no_auto_restore', 21:41:12 INFO - 'tabpaint']}, 21:41:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:41:12 INFO - 'ts_paint', 21:41:12 INFO - 'tpaint', 21:41:12 INFO - 'sessionrestore', 21:41:12 INFO - 'sessionrestore_no_auto_restore', 21:41:12 INFO - 'tabpaint']}, 21:41:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:41:12 INFO - 'ts_paint', 21:41:12 INFO - 'tpaint', 21:41:12 INFO - 'sessionrestore', 21:41:12 INFO - 'sessionrestore_no_auto_restore', 21:41:12 INFO - 'tabpaint']}, 21:41:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['a11yr', 21:41:12 INFO - 'ts_paint', 21:41:12 INFO - 'tpaint', 21:41:12 INFO - 'sessionrestore', 21:41:12 INFO - 'sessionrestore_no_auto_restore', 21:41:12 INFO - 'tabpaint']}, 21:41:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['a11yr', 21:41:12 INFO - 'ts_paint', 21:41:12 INFO - 'tpaint', 21:41:12 INFO - 'sessionrestore', 21:41:12 INFO - 'sessionrestore_no_auto_restore', 21:41:12 INFO - 'tabpaint']}, 21:41:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['tsvgx', 21:41:12 INFO - 'tsvgr_opacity', 21:41:12 INFO - 'tart', 21:41:12 INFO - 'tscrollx', 21:41:12 INFO - 'cart']}, 21:41:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:41:12 INFO - 'tsvgr_opacity', 21:41:12 INFO - 'tart', 21:41:12 INFO - 'tscrollx', 21:41:12 INFO - 'cart']}, 21:41:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'talos_options': ['--disable-e10s'], 21:41:12 INFO - 'tests': ['tp5o']}, 21:41:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'tests': ['tp5o']}, 21:41:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'talos_options': ['--disable-e10s', 21:41:12 INFO - '--xperf_path', 21:41:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:41:12 INFO - 'tests': ['tp5n']}, 21:41:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:41:12 INFO - 'talos_options': ['--xperf_path', 21:41:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:41:12 INFO - 'tests': ['tp5n']}}, 21:41:12 INFO - 'talos.zip': {'path': '', 21:41:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:41:12 INFO - Running post-action listener: _resource_record_post_action 21:41:12 INFO - [mozharness: 2016-07-28 04:41:12.521469Z] Finished populate-webroot step (success) 21:41:12 INFO - [mozharness: 2016-07-28 04:41:12.521558Z] Running create-virtualenv step. 21:41:12 INFO - Running pre-action listener: _resource_record_pre_action 21:41:12 INFO - Running main action method: create_virtualenv 21:41:12 INFO - Creating virtualenv /builds/slave/test/build/venv 21:41:12 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 21:41:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:41:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:41:12 INFO - Using real prefix '/usr' 21:41:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:41:13 INFO - Installing distribute.............................................................................................................................................................................................done. 21:41:15 INFO - Installing pip.................done. 21:41:15 INFO - Return code: 0 21:41:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:41:15 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')]} 21:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:15 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')]} 21:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:15 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:15 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 21:41:15 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 21:41:15 INFO - Using env: {'DISPLAY': ':0', 21:41:15 INFO - 'HOME': '/home/cltbld', 21:41:15 INFO - 'LANG': 'en_US.UTF-8', 21:41:15 INFO - 'LANGUAGE': 'en_US:en', 21:41:15 INFO - 'LOGNAME': 'cltbld', 21:41:15 INFO - 'MAIL': '/var/mail/cltbld', 21:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:15 INFO - 'MOZ_NO_REMOTE': '1', 21:41:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:15 INFO - 'NO_EM_RESTART': '1', 21:41:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:15 INFO - 'PWD': '/builds/slave/test', 21:41:15 INFO - 'SHELL': '/bin/bash', 21:41:15 INFO - 'SHLVL': '1', 21:41:15 INFO - 'TERM': 'linux', 21:41:15 INFO - 'TMOUT': '86400', 21:41:15 INFO - 'USER': 'cltbld', 21:41:15 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:15 INFO - '_': '/tools/buildbot/bin/python'} 21:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:15 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 21:41:15 INFO - Cleaning up... 21:41:15 INFO - Return code: 0 21:41:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:41:15 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')]} 21:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:15 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')]} 21:41:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:15 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:15 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 21:41:15 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 21:41:15 INFO - Using env: {'DISPLAY': ':0', 21:41:15 INFO - 'HOME': '/home/cltbld', 21:41:15 INFO - 'LANG': 'en_US.UTF-8', 21:41:15 INFO - 'LANGUAGE': 'en_US:en', 21:41:15 INFO - 'LOGNAME': 'cltbld', 21:41:15 INFO - 'MAIL': '/var/mail/cltbld', 21:41:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:15 INFO - 'MOZ_NO_REMOTE': '1', 21:41:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:15 INFO - 'NO_EM_RESTART': '1', 21:41:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:15 INFO - 'PWD': '/builds/slave/test', 21:41:15 INFO - 'SHELL': '/bin/bash', 21:41:15 INFO - 'SHLVL': '1', 21:41:15 INFO - 'TERM': 'linux', 21:41:15 INFO - 'TMOUT': '86400', 21:41:15 INFO - 'USER': 'cltbld', 21:41:15 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:15 INFO - '_': '/tools/buildbot/bin/python'} 21:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:15 INFO - Downloading/unpacking psutil>=3.1.1 21:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:41:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:41:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:41:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:41:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:41:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:41:17 INFO - Installing collected packages: psutil 21:41:17 INFO - Running setup.py install for psutil 21:41:17 INFO - building 'psutil._psutil_linux' extension 21:41:17 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 21:41:17 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 21:41:17 INFO - building 'psutil._psutil_posix' extension 21:41:17 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 21:41:17 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 21:41:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:41:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:41:18 INFO - Successfully installed psutil 21:41:18 INFO - Cleaning up... 21:41:18 INFO - Return code: 0 21:41:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:41: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')]} 21:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41: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')]} 21:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 21:41: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 mozsystemmonitor==0.3 21:41:18 INFO - Using env: {'DISPLAY': ':0', 21:41:18 INFO - 'HOME': '/home/cltbld', 21:41:18 INFO - 'LANG': 'en_US.UTF-8', 21:41:18 INFO - 'LANGUAGE': 'en_US:en', 21:41:18 INFO - 'LOGNAME': 'cltbld', 21:41:18 INFO - 'MAIL': '/var/mail/cltbld', 21:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:18 INFO - 'MOZ_NO_REMOTE': '1', 21:41:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:18 INFO - 'NO_EM_RESTART': '1', 21:41:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:18 INFO - 'PWD': '/builds/slave/test', 21:41:18 INFO - 'SHELL': '/bin/bash', 21:41:18 INFO - 'SHLVL': '1', 21:41:18 INFO - 'TERM': 'linux', 21:41:18 INFO - 'TMOUT': '86400', 21:41:18 INFO - 'USER': 'cltbld', 21:41:18 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:18 INFO - '_': '/tools/buildbot/bin/python'} 21:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:18 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 21:41:18 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 21:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:41:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:41:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:41:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:41:20 INFO - Installing collected packages: mozsystemmonitor 21:41:20 INFO - Running setup.py install for mozsystemmonitor 21:41:20 INFO - Successfully installed mozsystemmonitor 21:41:20 INFO - Cleaning up... 21:41:20 INFO - Return code: 0 21:41:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:20 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:20 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 21:41:20 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 21:41:20 INFO - Using env: {'DISPLAY': ':0', 21:41:20 INFO - 'HOME': '/home/cltbld', 21:41:20 INFO - 'LANG': 'en_US.UTF-8', 21:41:20 INFO - 'LANGUAGE': 'en_US:en', 21:41:20 INFO - 'LOGNAME': 'cltbld', 21:41:20 INFO - 'MAIL': '/var/mail/cltbld', 21:41:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:20 INFO - 'MOZ_NO_REMOTE': '1', 21:41:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:20 INFO - 'NO_EM_RESTART': '1', 21:41:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:20 INFO - 'PWD': '/builds/slave/test', 21:41:20 INFO - 'SHELL': '/bin/bash', 21:41:20 INFO - 'SHLVL': '1', 21:41:20 INFO - 'TERM': 'linux', 21:41:20 INFO - 'TMOUT': '86400', 21:41:20 INFO - 'USER': 'cltbld', 21:41:20 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:20 INFO - '_': '/tools/buildbot/bin/python'} 21:41:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:20 INFO - Downloading/unpacking jsonschema==2.5.1 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:41:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:41:22 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 21:41:22 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 21:41:22 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 21:41:22 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 21:41:22 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 21:41:22 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 21:41:22 INFO - Downloading functools32-3.2.3-2.tar.gz 21:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:41:22 INFO - warning: no files found matching '*.txt' 21:41:22 INFO - no previously-included directories found matching 'build' 21:41:22 INFO - no previously-included directories found matching 'dist' 21:41:22 INFO - no previously-included directories found matching '.git*' 21:41:22 INFO - Installing collected packages: jsonschema, functools32 21:41:22 INFO - Running setup.py install for functools32 21:41:22 INFO - warning: no files found matching '*.txt' 21:41:22 INFO - no previously-included directories found matching 'build' 21:41:22 INFO - no previously-included directories found matching 'dist' 21:41:22 INFO - no previously-included directories found matching '.git*' 21:41:22 INFO - Successfully installed jsonschema functools32 21:41:22 INFO - Cleaning up... 21:41:23 INFO - Return code: 0 21:41:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:41:23 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')]} 21:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:23 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')]} 21:41:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:23 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:23 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 21:41:23 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 21:41:23 INFO - Using env: {'DISPLAY': ':0', 21:41:23 INFO - 'HOME': '/home/cltbld', 21:41:23 INFO - 'LANG': 'en_US.UTF-8', 21:41:23 INFO - 'LANGUAGE': 'en_US:en', 21:41:23 INFO - 'LOGNAME': 'cltbld', 21:41:23 INFO - 'MAIL': '/var/mail/cltbld', 21:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:23 INFO - 'MOZ_NO_REMOTE': '1', 21:41:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:23 INFO - 'NO_EM_RESTART': '1', 21:41:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:23 INFO - 'PWD': '/builds/slave/test', 21:41:23 INFO - 'SHELL': '/bin/bash', 21:41:23 INFO - 'SHLVL': '1', 21:41:23 INFO - 'TERM': 'linux', 21:41:23 INFO - 'TMOUT': '86400', 21:41:23 INFO - 'USER': 'cltbld', 21:41:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:23 INFO - '_': '/tools/buildbot/bin/python'} 21:41:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:41:23 INFO - Cleaning up... 21:41:23 INFO - Return code: 0 21:41:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:41:23 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')]} 21:41:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:23 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')]} 21:41:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:23 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:23 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 21:41:23 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 21:41:23 INFO - Using env: {'DISPLAY': ':0', 21:41:23 INFO - 'HOME': '/home/cltbld', 21:41:23 INFO - 'LANG': 'en_US.UTF-8', 21:41:23 INFO - 'LANGUAGE': 'en_US:en', 21:41:23 INFO - 'LOGNAME': 'cltbld', 21:41:23 INFO - 'MAIL': '/var/mail/cltbld', 21:41:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:23 INFO - 'MOZ_NO_REMOTE': '1', 21:41:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:23 INFO - 'NO_EM_RESTART': '1', 21:41:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:23 INFO - 'PWD': '/builds/slave/test', 21:41:23 INFO - 'SHELL': '/bin/bash', 21:41:23 INFO - 'SHLVL': '1', 21:41:23 INFO - 'TERM': 'linux', 21:41:23 INFO - 'TMOUT': '86400', 21:41:23 INFO - 'USER': 'cltbld', 21:41:23 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:23 INFO - '_': '/tools/buildbot/bin/python'} 21:41:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:23 INFO - Downloading/unpacking blobuploader==1.2.4 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41: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 21:41:25 INFO - Downloading blobuploader-1.2.4.tar.gz 21:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:41:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:41:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 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 21:41:25 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 21:41:26 INFO - Downloading docopt-0.6.1.tar.gz 21:41:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:41:26 INFO - Installing collected packages: blobuploader, requests, docopt 21:41:26 INFO - Running setup.py install for blobuploader 21:41:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:41:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:41:26 INFO - Running setup.py install for requests 21:41:26 INFO - Running setup.py install for docopt 21:41:26 INFO - Successfully installed blobuploader requests docopt 21:41:26 INFO - Cleaning up... 21:41:26 INFO - Return code: 0 21:41:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:26 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')]} 21:41:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:26 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')]} 21:41:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:26 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:26 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 21:41:26 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 21:41:26 INFO - Using env: {'DISPLAY': ':0', 21:41:26 INFO - 'HOME': '/home/cltbld', 21:41:26 INFO - 'LANG': 'en_US.UTF-8', 21:41:26 INFO - 'LANGUAGE': 'en_US:en', 21:41:26 INFO - 'LOGNAME': 'cltbld', 21:41:26 INFO - 'MAIL': '/var/mail/cltbld', 21:41:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:26 INFO - 'MOZ_NO_REMOTE': '1', 21:41:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:26 INFO - 'NO_EM_RESTART': '1', 21:41:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:26 INFO - 'PWD': '/builds/slave/test', 21:41:26 INFO - 'SHELL': '/bin/bash', 21:41:26 INFO - 'SHLVL': '1', 21:41:26 INFO - 'TERM': 'linux', 21:41:26 INFO - 'TMOUT': '86400', 21:41:26 INFO - 'USER': 'cltbld', 21:41:26 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:26 INFO - '_': '/tools/buildbot/bin/python'} 21:41:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:41:26 INFO - Running setup.py (path:/tmp/pip-fDfWMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:41:27 INFO - Running setup.py (path:/tmp/pip-RmiW_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:41:27 INFO - Running setup.py (path:/tmp/pip-QLRmkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:41:27 INFO - Running setup.py (path:/tmp/pip-Z7R_c4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:41:27 INFO - Running setup.py (path:/tmp/pip-ZVOjWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:41:27 INFO - Running setup.py (path:/tmp/pip-VNv1Y2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:41:27 INFO - Running setup.py (path:/tmp/pip-sHelbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:41:27 INFO - Running setup.py (path:/tmp/pip-6uFOI5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:41:27 INFO - Running setup.py (path:/tmp/pip-dHuyBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:41:27 INFO - Running setup.py (path:/tmp/pip-FIV3Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:41:27 INFO - Running setup.py (path:/tmp/pip-2r2zjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:41:27 INFO - Running setup.py (path:/tmp/pip-2phTdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:41:27 INFO - Running setup.py (path:/tmp/pip-hHczuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:41:27 INFO - Running setup.py (path:/tmp/pip-B9mTJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:41:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:27 INFO - Running setup.py (path:/tmp/pip-h0Qgzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:41:28 INFO - Running setup.py (path:/tmp/pip-khrD_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:41:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:41:28 INFO - Running setup.py (path:/tmp/pip-P4gqE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:41:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:41:28 INFO - Running setup.py install for manifestparser 21:41:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Running setup.py install for mozcrash 21:41:28 INFO - Running setup.py install for mozdebug 21:41:28 INFO - Running setup.py install for mozdevice 21:41:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Running setup.py install for mozfile 21:41:28 INFO - Running setup.py install for mozhttpd 21:41:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Running setup.py install for mozinfo 21:41:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Running setup.py install for mozInstall 21:41:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:41:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Running setup.py install for mozleak 21:41:29 INFO - Running setup.py install for mozlog 21:41:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Running setup.py install for moznetwork 21:41:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Running setup.py install for mozprocess 21:41:29 INFO - Running setup.py install for mozprofile 21:41:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Running setup.py install for mozrunner 21:41:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Running setup.py install for mozscreenshot 21:41:29 INFO - Running setup.py install for moztest 21:41:29 INFO - Running setup.py install for mozversion 21:41:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:41:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:41:29 INFO - Cleaning up... 21:41:29 INFO - Return code: 0 21:41:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:30 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:30 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 21:41:30 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 21:41:30 INFO - Using env: {'DISPLAY': ':0', 21:41:30 INFO - 'HOME': '/home/cltbld', 21:41:30 INFO - 'LANG': 'en_US.UTF-8', 21:41:30 INFO - 'LANGUAGE': 'en_US:en', 21:41:30 INFO - 'LOGNAME': 'cltbld', 21:41:30 INFO - 'MAIL': '/var/mail/cltbld', 21:41:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:30 INFO - 'MOZ_NO_REMOTE': '1', 21:41:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:30 INFO - 'NO_EM_RESTART': '1', 21:41:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:30 INFO - 'PWD': '/builds/slave/test', 21:41:30 INFO - 'SHELL': '/bin/bash', 21:41:30 INFO - 'SHLVL': '1', 21:41:30 INFO - 'TERM': 'linux', 21:41:30 INFO - 'TMOUT': '86400', 21:41:30 INFO - 'USER': 'cltbld', 21:41:30 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:30 INFO - '_': '/tools/buildbot/bin/python'} 21:41:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:41:30 INFO - Running setup.py (path:/tmp/pip-C17EPT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:41:30 INFO - Running setup.py (path:/tmp/pip-iXjKmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:41:30 INFO - Running setup.py (path:/tmp/pip-2X9Vij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:41:30 INFO - Running setup.py (path:/tmp/pip-U0GzVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:41:30 INFO - Running setup.py (path:/tmp/pip-FxYqY2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:41:30 INFO - Running setup.py (path:/tmp/pip-HMUJwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:41:30 INFO - Running setup.py (path:/tmp/pip-MKTUbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:41:30 INFO - Running setup.py (path:/tmp/pip-KAWDHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:41:30 INFO - Running setup.py (path:/tmp/pip-srwDB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:41:30 INFO - Running setup.py (path:/tmp/pip-qiFdW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:41:30 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)) 21:41:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:41:30 INFO - Running setup.py (path:/tmp/pip-7IMQeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:41:31 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)) 21:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:41:31 INFO - Running setup.py (path:/tmp/pip-BwdeU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:41:31 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)) 21:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:41:31 INFO - Running setup.py (path:/tmp/pip-D2cTDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:41:31 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)) 21:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:41:31 INFO - Running setup.py (path:/tmp/pip-wfNN1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:41:31 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)) 21:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:31 INFO - Running setup.py (path:/tmp/pip-y6nFEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:41:31 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)) 21:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:41:31 INFO - Running setup.py (path:/tmp/pip-x0guSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:41:31 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)) 21:41:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:41:31 INFO - Running setup.py (path:/tmp/pip-pauuT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:41:31 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)) 21:41:31 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)) 21:41:31 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)) 21:41:31 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)) 21:41:31 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)) 21:41:31 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)) 21:41:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:41:31 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 21:41:31 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 21:41:31 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 21:41:31 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 21:41:31 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 21:41:31 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 21:41:33 INFO - Downloading blessings-1.6.tar.gz 21:41:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:41:33 INFO - Installing collected packages: blessings 21:41:33 INFO - Running setup.py install for blessings 21:41:33 INFO - Successfully installed blessings 21:41:33 INFO - Cleaning up... 21:41:33 INFO - Return code: 0 21:41:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:41:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:41:33 INFO - Reading from file tmpfile_stdout 21:41:33 INFO - Current package versions: 21:41:33 INFO - argparse == 1.2.1 21:41:33 INFO - blessings == 1.6 21:41:33 INFO - blobuploader == 1.2.4 21:41:33 INFO - docopt == 0.6.1 21:41:33 INFO - functools32 == 3.2.3-2 21:41:33 INFO - jsonschema == 2.5.1 21:41:33 INFO - manifestparser == 1.1 21:41:33 INFO - mozInstall == 1.12 21:41:33 INFO - mozcrash == 0.17 21:41:33 INFO - mozdebug == 0.1 21:41:33 INFO - mozdevice == 0.48 21:41:33 INFO - mozfile == 1.2 21:41:33 INFO - mozhttpd == 0.7 21:41:33 INFO - mozinfo == 0.9 21:41:33 INFO - mozleak == 0.1 21:41:33 INFO - mozlog == 3.3 21:41:33 INFO - moznetwork == 0.27 21:41:33 INFO - mozprocess == 0.23 21:41:33 INFO - mozprofile == 0.28 21:41:33 INFO - mozrunner == 6.12 21:41:33 INFO - mozscreenshot == 0.1 21:41:33 INFO - mozsystemmonitor == 0.3 21:41:33 INFO - moztest == 0.7 21:41:33 INFO - mozversion == 1.4 21:41:33 INFO - psutil == 3.1.1 21:41:33 INFO - requests == 1.2.3 21:41:33 INFO - wsgiref == 0.1.2 21:41:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:41:33 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')]} 21:41:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:33 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')]} 21:41:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:33 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:33 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 21:41:33 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 21:41:33 INFO - Using env: {'DISPLAY': ':0', 21:41:33 INFO - 'HOME': '/home/cltbld', 21:41:33 INFO - 'LANG': 'en_US.UTF-8', 21:41:33 INFO - 'LANGUAGE': 'en_US:en', 21:41:33 INFO - 'LOGNAME': 'cltbld', 21:41:33 INFO - 'MAIL': '/var/mail/cltbld', 21:41:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:33 INFO - 'MOZ_NO_REMOTE': '1', 21:41:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:33 INFO - 'NO_EM_RESTART': '1', 21:41:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:33 INFO - 'PWD': '/builds/slave/test', 21:41:33 INFO - 'SHELL': '/bin/bash', 21:41:33 INFO - 'SHLVL': '1', 21:41:33 INFO - 'TERM': 'linux', 21:41:33 INFO - 'TMOUT': '86400', 21:41:33 INFO - 'USER': 'cltbld', 21:41:33 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:33 INFO - '_': '/tools/buildbot/bin/python'} 21:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 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)) 21:41:34 INFO - Cleaning up... 21:41:34 INFO - Return code: 0 21:41:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:41:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:41:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:41:34 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 0x25fd030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27608a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28be180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, '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': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', '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 21:41:34 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 21:41:34 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 21:41:34 INFO - Using env: {'DISPLAY': ':0', 21:41:34 INFO - 'HOME': '/home/cltbld', 21:41:34 INFO - 'LANG': 'en_US.UTF-8', 21:41:34 INFO - 'LANGUAGE': 'en_US:en', 21:41:34 INFO - 'LOGNAME': 'cltbld', 21:41:34 INFO - 'MAIL': '/var/mail/cltbld', 21:41:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:34 INFO - 'MOZ_NO_REMOTE': '1', 21:41:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:34 INFO - 'NO_EM_RESTART': '1', 21:41:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:34 INFO - 'PWD': '/builds/slave/test', 21:41:34 INFO - 'SHELL': '/bin/bash', 21:41:34 INFO - 'SHLVL': '1', 21:41:34 INFO - 'TERM': 'linux', 21:41:34 INFO - 'TMOUT': '86400', 21:41:34 INFO - 'USER': 'cltbld', 21:41:34 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:34 INFO - '_': '/tools/buildbot/bin/python'} 21:41:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:41:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:41:34 INFO - Cleaning up... 21:41:34 INFO - Return code: 0 21:41:34 INFO - Running post-action listener: _resource_record_post_action 21:41:34 INFO - Running post-action listener: _start_resource_monitoring 21:41:34 INFO - Starting resource monitoring. 21:41:34 INFO - [mozharness: 2016-07-28 04:41:34.318889Z] Finished create-virtualenv step (success) 21:41:34 INFO - [mozharness: 2016-07-28 04:41:34.319375Z] Running install step. 21:41:34 INFO - Running pre-action listener: _resource_record_pre_action 21:41:34 INFO - Running main action method: install 21:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:41:34 INFO - Reading from file tmpfile_stdout 21:41:34 INFO - Detecting whether we're running mozinstall >=1.0... 21:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:41:34 INFO - Reading from file tmpfile_stdout 21:41:34 INFO - Output received: 21:41:34 INFO - Usage: mozinstall [options] installer 21:41:34 INFO - Options: 21:41:34 INFO - -h, --help show this help message and exit 21:41:34 INFO - -d DEST, --destination=DEST 21:41:34 INFO - Directory to install application into. [default: 21:41:34 INFO - "/builds/slave/test"] 21:41:34 INFO - --app=APP Application being installed. [default: firefox] 21:41:34 INFO - mkdir: /builds/slave/test/build/application 21:41:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:41:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:41:51 INFO - Reading from file tmpfile_stdout 21:41:51 INFO - Output received: 21:41:51 INFO - /builds/slave/test/build/application/firefox/firefox 21:41:51 INFO - Running post-action listener: _resource_record_post_action 21:41:51 INFO - [mozharness: 2016-07-28 04:41:51.020715Z] Finished install step (success) 21:41:51 INFO - [mozharness: 2016-07-28 04:41:51.020993Z] Running run-tests step. 21:41:51 INFO - Running pre-action listener: _resource_record_pre_action 21:41:51 INFO - Running main action method: run_tests 21:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:41:51 INFO - Python 2.7.3 21:41:51 INFO - Return code: 0 21:41:51 INFO - grabbing minidump binary from tooltool 21:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:41:51 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 0x28bc3b0>, '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 0x28bd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x28bd740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:41:51 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 21:41:51 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 21:41:51 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 21:41:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:41:51 INFO - Return code: 0 21:41:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:41:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:41:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:41:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:41:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:41:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 21:41:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --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 21:41:51 INFO - Using env: {'DISPLAY': ':0', 21:41:51 INFO - 'HOME': '/home/cltbld', 21:41:51 INFO - 'LANG': 'en_US.UTF-8', 21:41:51 INFO - 'LANGUAGE': 'en_US:en', 21:41:51 INFO - 'LOGNAME': 'cltbld', 21:41:51 INFO - 'MAIL': '/var/mail/cltbld', 21:41:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:41:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:41:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:41:51 INFO - 'MOZ_NO_REMOTE': '1', 21:41:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:41:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:41:51 INFO - 'NO_EM_RESTART': '1', 21:41:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:41:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:41:51 INFO - 'PWD': '/builds/slave/test', 21:41:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:41:51 INFO - 'SHELL': '/bin/bash', 21:41:51 INFO - 'SHLVL': '1', 21:41:51 INFO - 'TERM': 'linux', 21:41:51 INFO - 'TMOUT': '86400', 21:41:51 INFO - 'USER': 'cltbld', 21:41:51 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303', 21:41:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:41:51 INFO - '_': '/tools/buildbot/bin/python'} 21:41:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--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 21:41:51 INFO - mozversion application_buildid: 20160727201023 21:41:51 INFO - mozversion application_changeset: 4d52a4c41a7754ef4c14f4f02b3273a5469de156 21:41:51 INFO - mozversion application_display_name: Nightly 21:41:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:41:51 INFO - mozversion application_name: Firefox 21:41:51 INFO - mozversion application_remotingname: firefox 21:41:51 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 21:41:51 INFO - mozversion application_vendor: Mozilla 21:41:51 INFO - mozversion application_version: 50.0a1 21:41:51 INFO - mozversion platform_buildid: 20160727201023 21:41:51 INFO - mozversion platform_changeset: 4d52a4c41a7754ef4c14f4f02b3273a5469de156 21:41:51 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 21:41:51 INFO - mozversion platform_version: 50.0a1 21:41:51 INFO - using testdate: 1469680911 21:41:51 INFO - actual date: 1469680911 21:41:51 INFO - starting webserver on 'localhost:38225' 21:41:51 INFO - SUITE-START | Running 2 tests 21:41:51 INFO - TEST-START | dromaeo_css 21:41:51 INFO - operating with platform_type : linux_ 21:41:51 INFO - Initialising browser for dromaeo_css test... 21:41:51 INFO - TEST-INFO | started process 7553 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1mt1IN/profile http://localhost:38225/getInfo.html) 21:41:54 INFO - PROCESS | 7553 | JavaScript error: , line 0: uncaught exception: undefined 21:41:54 INFO - PROCESS | 7553 | __metrics Screen width/height:1600/1200 21:41:54 INFO - PROCESS | 7553 | colorDepth:24 21:41:54 INFO - PROCESS | 7553 | Browser inner width/height: 1024/697 21:41:54 INFO - PROCESS | 7553 | __metrics 21:41:55 INFO - PROCESS | 7553 | 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" 21:41:55 INFO - PROCESS | 7553 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:41:56 INFO - TEST-INFO | 7553: exit 0 21:41:56 INFO - Browser initialized. 21:41:56 INFO - Running cycle 1/1 for dromaeo_css test... 21:41:56 INFO - TEST-INFO | started process 7631 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1mt1IN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:42:08 INFO - PROCESS | 7631 | RSS: Main: 159678464 21:42:08 INFO - PROCESS | 7631 | 21:42:59 INFO - PROCESS | 7631 | 1469680979617 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] 21:45:12 INFO - PROCESS | 7631 | Cycle 1(1): loaded http://localhost:38225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38225/tests/dromaeo/cssquery-ext.html) 21:45:13 INFO - PROCESS | 7631 | RSS: Main: 252624896 21:45:13 INFO - PROCESS | 7631 | 21:48:15 INFO - PROCESS | 7631 | Cycle 1(1): loaded http://localhost:38225/tests/dromaeo/cssquery-ext.html (next: http://localhost:38225/tests/dromaeo/cssquery-jquery.html) 21:48:16 INFO - PROCESS | 7631 | RSS: Main: 243945472 21:48:16 INFO - PROCESS | 7631 | 21:51:18 INFO - PROCESS | 7631 | Cycle 1(1): loaded http://localhost:38225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38225/tests/dromaeo/cssquery-mootools.html) 21:51:19 INFO - PROCESS | 7631 | RSS: Main: 220397568 21:51:19 INFO - PROCESS | 7631 | 21:54:21 INFO - PROCESS | 7631 | Cycle 1(1): loaded http://localhost:38225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38225/tests/dromaeo/cssquery-prototype.html) 21:54:22 INFO - PROCESS | 7631 | RSS: Main: 185171968 21:54:22 INFO - PROCESS | 7631 | 21:57:25 INFO - PROCESS | 7631 | Cycle 1(1): loaded http://localhost:38225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38225/tests/dromaeo/cssquery-yui.html) 21:57:25 INFO - PROCESS | 7631 | RSS: Main: 186798080 21:57:25 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | Cycle 1(1): loaded http://localhost:38225/tests/dromaeo/cssquery-yui.html (next: http://localhost:38225/tests/dromaeo/cssquery-dojo.html) 22:00:28 INFO - PROCESS | 7631 | RSS: Main: 278257664 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | __start_tp_report 22:00:28 INFO - PROCESS | 7631 | _x_x_mozilla_page_load 22:00:28 INFO - PROCESS | 7631 | _x_x_mozilla_page_load_details 22:00:28 INFO - PROCESS | 7631 | |i|pagename|runs| 22:00:28 INFO - PROCESS | 7631 | |0;dojo.html;2577;2582.8966841326346;2619.502294564032;2627.855467949263;2629.6844378674564;4581.45022597288;4590.931136032962;4599.770011499424;4622.8613141605765;4645.651576131787;4623.5838774510285;4641.791119399627;4655.394798676176;4696.577308042276;4717.386739723831;4211.726237794549;4249.213895429355;4283.935740963888;4328.264195086843;4455.84404545841;4350.73895566267;4404.845830395937;4416.3375493675885;4422.5577442255835;4435.756033418161;4181.0592616661315;4221.472315960505;4223.746575205498;4259.041715613993;4335.024619460628;4259.084296876186;4260.233158031553;4266.488021437416;4295.205387003414;4362.280223763075;4305.461817272841;4310.181065597527;4320.675949303783;4352.499462561815;4358.389825424443;4534.614557762594;4537.591616754475;4705.1765940960195;4712.552307530779;4754.881127971794;4876.146674332013;4986.775595098229;4988.02733466974;5025.824096156617;5035.194368900995;5008.874778130539;5026.271190677331;5028;5036.899262014739;5037.017781532602;4544.6818722689095;4674.5325467452985;4696.483386827446;4701.55335243148;4736.550027747358;5202.71385073817;5226.3205783248695;5263.868403289948;5275.57795376369;5278.5513231375;4948.356713627206;4996.90006199874;5042.067217564762;5046.318746969112;5046.369203849519;4880.389951256093;4964.156093464047;4990.675606085593;5036.143855544567;5148.176291793295;4942.75286235694;4978.751062446863;4981.501849814989;5007.724575148329;5008.323876276656;4943.950560494421;4953.6532442729385;4963.702177869267;4975.950240497621;5012.022655582199;4120.855770048034;4157.064660451374;4210.59999300066;4272.060146767706;4319;3699.685526730258;3719.479272901796;3753.7184711146774;3787.3750831113107;3810.504634397566;4294.119705460374;4294.162638285567;4298.226319262562;4299;4339.566043395698;4831.710097394097;4887.682300650446;5029.044481548494;5030.572401345853;5066.82266120684;4269.701120921506;4301.956980430155;4305.461817272841;4307.655387568987;4357.825686972549;4531.954680453219;4538.319252112144;4546.158960592301;4548.772561371984;4589.173948689268;4577.3591697161755;4740.573348398591;4742.620590352833;4746.97626511879;4775.808967641325;4912.287718280901;4927.778249978688;4956.653034287565;4963.10664080469;5028.4971502850885;4655.906881862276;4658.510856360033;4677.695949763254;4689.226277664147;4731.787069581808;4802.8799280018275;4910.9017819642695;4911.68074075184;4923.876903077452;4966;4899.167141585867;4953.603711702983;5040.873978150576;5043.218301163325;5053.140966035709;4919;4930.334404855299;4931.679440836334;4936.2102063669645;4980.477049909708;4703.3885594872445;4898.510148985216;4901.852944411673;4905.313256144072;4969;4771.236602143779;4779.904401912012;4805.231163013901;4853.0779151961015;4871.756412179306;4902.485239049848;4903.387076615423;4904.209963363418;4906.730129842893;4962.280469331999;4599.862004139881;4801.51984801517;4808.2547205183255;4808.903821923472;4843.782029808596 22:00:28 INFO - PROCESS | 7631 | |1;ext.html;551.4575919685152;583.7898356591628;589.7788329376484;593.92575928009;598.6946657204826;39021.60978390215;40554.594454055485;40611;40736.185276294455;44636.3304550432;40941.59058409415;41417;41435.79282103585;41502.37746433806;41517.7924110379;7268.963655181743;7690.461667683265;7844.333231675315;7846.725364612211;9879.802403951917;9701.611935522571;10125.594976200942;10131.189504839633;10131.34146280493;10133.645322413717;10134.74663133424;10181.898181018169;10186;10186.236032297607;10192.643257485952;10069.64756233532;10111.191104711605;10114.140298074672;10123.89876101237;10130.898691013106;9958.90041099587;10015.098641122335;10028.147607453375;10029.949850250776;10039.69880903574;38891.8055409724;39469;39494.65558788531;39515.20969580593;39519.40720889189;2985.8656360463615;3010.488217003093;3096.086654436924;3126.609173853268;3297.241634424096;27670.169894902985;27982.580261295894;28051;28185.74514038872;28815;3125.4217969675456;3185.378851124032;3189.1867573768773;3194.169515925853;3269.0683155300626;264077.9999999981;267850;268378;268596;270785;50236.497635023916;50563.49436505661;50625.74687126541;50637.74681126644;50692.23961640579;32768.344633107205;33513.66486335154;33775;35613;35655.28689426249;14041.578752637437;14209.431622734975;14217.50238741639;14234.359453824603;14238.644033899236;9370.67202647904;10315.84526232107;10548.367097974146;10556.788864222672;10558.208134389952;18005.459836204936;18043;18077.27690892376;18209.817901821076;18219.271229150952;6595.637239951849;6722.596644201363;6875.621840798704;6935.167779866449;6957.65211739411;741.8664640364786;771.9498232614862;883.7437143228392;960.279790157382;966.9806603867884;23885.164019259242;25926.481470370487;25937.35156621062;25999.220023399328;26067.608985865227;929.7164364868645;940.4498368454394;948.5257371314343;1018.4347687033768;1022.0239671114068;12906.35468226585;13604.523841665494;13626.795598066037;13715.931420343035;13890.722185556233;394095;397868;397877;398051;400531;49402.50597493979;49406;49776.25335619968;49988.250176247384;50081.24878126831;1075.5858994287255;1114.8104822180087;1172.1033409441955;1178.1458442629025;1211.709189794443;24692.38269043288;28251.576226357032;28567.571486428136;28754.137375878756;29189.85405072961;40541.39187912125;41022.79488602538;41057.589424105376;41070.38394424146;41123.17753644851;312351;313713;315160;316344;318572;24654.260372188863;25792.742072579033;25901;26962.460750785267;27024.72975270301;38807.805960970014;38858.445662173195;39496.210075798896;39705.60294397019;40635.1872962545;33475.665243347255;33563.49654755229;33851.3229735399;33912;34159.31681366408;33172.50241246335;33740.49389259212;34057.318853622295;34292.14269643279;34776.30447391089;33368.33263334768;33625.663743363235;34044.829775850965;34049.31901362009;35849.283014339046;28905.421891562473;29267.268318292212;33959.660403396636;34255.31489370248;34279.485807713405;25077.623835642844;25372.238832835046;25428.618570721825;25687.100951466608;25729.742702573483 22:00:28 INFO - PROCESS | 7631 | |2;jquery.html;341.8615460738402;353.3004650791433;363.7399259529437;366.5582972518115;382.55814534212993;12805.423755930982;13691.178529288238;14213.431462741479;16959.83040169598;16963.49109526713;16013.519594412157;16190.19049047546;16776.916115419404;16812.15939203038;17578.033208173547;4558.339040839076;4689.648276379268;4698.64760142989;4702;4723.244280915054;3549.8580056797696;5733.827985160452;5997.040473524237;6076.118962750398;6151.261848578154;5224.1119009768245;6096.39036096389;6106.938930610681;6110.450059494645;6113.266408031042;5501.587380946425;5635.943640563582;5637.492625663669;5649.576281778842;5654;5290.841274761763;5384.6769193848495;5425.755840987165;5489.615726899118;5496.917546236809;12285.508579656804;13337.066405351628;14535.200563968978;14652.120872747562;14656.974011819177;1801.639672065592;1836.2379612460945;1850.69463538516;2108.156737305075;2189.4526368407896;8889.777755556099;8963.775905602346;9032.954835225783;9456.432614043179;9613.038696130401;1628.145223757525;1685.9157042147845;1713.6058706497452;1734.7397890316427;1814.2289526950992;65042.3495765041;68357;69024.3097569023;69762.65118674425;69995.30004699939;9685.903140968641;9936.850947235796;9938.503074846229;9957.452340121363;10275.640352587623;13608.183508989294;13640.931795340959;13652.112612680343;13667.453301867814;13668.863311366958;6716.126903502513;6829.863402731918;6922.376986071277;7002.253794220068;7025.121859767529;6311.2426508819235;6399.232092148972;6440.903386449207;6560.967195163994;6570.934290657128;4270.316749320094;4274.187904298174;4283.593058659422;4318.956810431919;4380.430544029319;1645.6379596488753;1673.648533807914;1695.5845817774725;1702.8637708983251;1715.785526809149;733.7835338575027;821.117298903681;894.4901406198404;900.3247564326755;948.9430634161972;15426;15434.228288585526;15447.150406727738;15793.21033948298;16565.58586035359;817.7138001699358;832.1803024021334;851.114840565817;875.3216257400566;896.852019416789;8670.91329086701;8927.687530936386;9290.489023103793;9311.860322095174;9458.432494050241;399258;406043;406541;410635;411188;77275;79029;80754.59622701966;81175;81397;862.9520109777609;882.849915514364;895.7984453497911;897.3090720145582;899.2311573604604;14268.71462570722;14366.209858457885;14647.26763661834;14758.778618320519;15239.619009524851;37549.43675844919;38641;38889.80555097319;39040.41439378355;39400.40899386569;51099.2335114981;52357.7382113087;52596.73701631468;52883.20675189798;53425.73287133539;7311.744088956861;7537.886931696139;7673.654685539276;7702.961485192538;7982.361411087215;6496.285408605144;8688;10972.177086718688;11895.524179032742;11945.522179112739;9200.35597508168;9595.656608075014;9599.568019439002;9771.511424428609;9882.061204185879;9929.503524823585;10452.216083793593;10471.895281047093;10508.579656813641;10601.310914790514;9627.229821614113;9856.950715246665;10041.899581004096;10157.746056348651;10371.170306375623;7462.477626566306;7668.463207575416;7720.420968427278;7809.297163255334;8047.678092876219;9275.72172834816;9474.526273686426;9731.951340243253;9748;10050.045245701647 22:00:28 INFO - PROCESS | 7631 | |3;mootools.html;1252.8684488128752;1325.9403326850295;1360.938757755901;1365.4742923974268;1501.5270189890184;6237.46981506572;6845.041694162815;7159.82100447489;7164.140303163627;7364.153122390927;7208.495405321616;7214.96392518038;7220.783376498714;7220.855582888352;7223.89164162538;2126.6916297136904;2149.720536330275;2151.9569608607817;2154.2244791874914;2156.094440335063;2054.5479994401285;2105.4841563816885;2155.8490905636613;2156.051337411534;2158.967615485769;2099.9265025724103;2117.6188286108495;2126.1070350452846;2127.7233959585233;2140.86084404514;2097.4861159016064;2104;2104.6527322991687;2106.2838634864142;2106.73665791776;2036.0837623069679;2089.3105275259127;2093.267356425254;2093.769685334612;2094.2984100326407;7166.247544007856;7177.7487787927175;7286.74496392629;7288.198298187195;7291.234420385889;4086.4891888513935;4237;4260.8721738347895;4262.147570485884;4269.466316710411;2723.196656986194;2844.587534807462;2866.65600127986;2889.5376739721755;2890.3496713239565;878.3587980774007;884.7345796261096;887.0508555845248;891.919727224553;897.1253028297423;9429.858552121723;9480.715578532656;9499.810003799885;9539.713808585753;9541.522923853849;3645.4167333226555;3839.424086387064;3957.3866050762176;3977.6618987386396;3978.144698889753;7574.091109066947;7583.165851756303;7586.317231449196;7593.962030189814;7596.316331530189;4232.957670423318;4238.088810905609;4239.427677263591;4244.702870799076;4250.638695710899;3337.983310083434;3749.3063783200196;3869.5550011748446;3936;3937.409388591677;3209.245827230599;3209.5506629071947;3341.7159541438764;3347.0126312737802;3347.6652334766327;948.6347756113946;951.7144856543009;953.68051702679;956.4548207521646;965.9855102173473;685.9245482996866;705.9435245180373;734.9669264883094;764.7399884039453;815.759350991459;4615.361606452923;4868.732219727878;4942.777575009133;4970.428400733889;5129.692218466904;396.5083296712056;408.5036680433228;411.22073670394434;412.61420571765495;412.9009037830899;3520.9471857922154;3534.752567320263;3539.7876127432437;3544.2557063017057;3577.177249232639;8408.957955210184;8826.691065812794;8869.246114080375;8876.689315873913;8881.777955551162;8826.55867206637;8831.161039701217;8835.469871807583;8849.60176792046;8851.380403371702;6532.150820393414;6700;6805.795826125224;6808.08090907731;6953.687084081127;4830.855074347775;5035.0685123252315;5065.974670126626;5103.132467480612;5110.948890511196;7833.804154896173;7943.4836735612;7976.84046319082;8124.593770311579;8156.755297341089;7954.5624990623755;8223.95888020556;8229.506229626242;8235.052968908412;8241.299489543457;6007.969960150346;6532;6674.065630811788;6694.364035416627;6694.430973367318;6616.735330586723;6843.555168914005;6916.100906882073;7068.3285087916565;7070.186928503288;6122.5408094392205;6201.751929922752;6310.6213627182515;6404;6421.518386120966;6185.072239164161;6228.844278893064;6237.1891654084675;6239.7504099837315;6271.905921411274;5775.162601422686;5802.796902108406;5822.417758224144;5829.09649004405;5844.824655260349;4232.32282834745;4299.785010749388;4364.607185353333;4465.687401881837;4487.7209995151225;4275.401443797933;4350.58669426404;4418.5802348776815;4530.048689775184;4537.047220083819 22:00:28 INFO - PROCESS | 7631 | |4;prototype.html;301.3385618567245;308.1525804038893;308.34476736934016;315.2811589575767;316.5868541553272;4793.928091078632;4821.541953514419;4834.323194752737;4860.9756951215195;4865.221564549673;4766.761661916899;4768.380110585619;4798.448178459478;4799.7360145192;4807.1587472192405;3612.855485780572;3656.945145822815;3728.459373390857;3729.776213427203;3792.924141517168;3785.71607129465;3792.355299599075;3793.696504279651;3794.392897136459;3796.9430458543143;3747.381682022463;3752.9812350938346;3753.9249215015684;3753.9812300938456;3757.8538545743536;3608.5489313835637;3624.9456258156015;3738.9904725724173;3741.9438708419398;3743.7192210584044;3338.132085657722;3340.131565792887;3340.248444100058;3340.5323254744353;3361.46216605342;4635.675502714811;4668.346431499593;4671.850948563665;4691.202495575726;4718.1127548980685;4209.7474151551005;4300.9139817203795;4320.654347652212;4331.805068771912;4335.523092459858;4985.501449855022;4990.65065445419;4995.725235112068;5000.499950005007;5016.949830501721;3668.761530500509;3673.338799016176;3675;3675.3016926784094;3675.558932928039;5080.212567052113;5231.3199284092825;5236.947630523684;5243.981067289083;5244;3706.184639379332;3736.682381997506;3742.802303262984;3751.3435148848844;3762.698984081267;3795.4686343911876;3797.7911214883447;3805.6194380561724;3806.1816709407613;3806.657400833938;4269.2184753076135;4278.850240241577;4288.699791014599;4290.678199135077;4298;4791.9055917149235;4991.700497970133;4993.625478089158;4995.401471529149;5006;1290.993545032269;1302.6678197059878;1306.1640550047978;1306.477409036393;1308.5681725030718;849.0066622052213;860.6385318166385;864.2178828160525;865.6321063547967;901.2294488212484;4298.8280468780895;4347.260965635849;4348.28253338202;4362.236608593484;4389.846355377546;5110.488951104934;5116.641835071509;5117.488251174853;5121.539061484485;5123.154679477844;4243.915121697611;4340.717853339523;4349.826006959686;4367.410399596078;4384;2705.499482595726;2711.295063283521;2716.619673245747;2729.7816174706372;2731.658542682165;4816.277558366203;4818.855434336975;4821.7589120543835;4837.758112094381;4865.464798872121;5075.87310317245;5140.588752899759;5216.591520289577;5259.447757985433;5260.421353651095;4794.520547945178;4819.903601928012;4830.758462076882;4830.903381932341;4836.371271734652;5127.589792816491;5130.050940576006;5131.512506311894;5141.562967147835;5142.37738753231;4741.596964257938;5029.396472423333;5036.6726162799305;5041.521055499722;5057.342545469065;4785.832495862628;4791.520847915181;4797.664163508521;4799.1601469743355;4808.711477311373;3691.593924668338;3704.68510176638;3708.796016219134;3711.5546134462957;3728.70170386363;4890.413150421972;5055.797768089235;5066.670666406671;5067.189249720027;5072.746362681925;4913.975430122827;4924.532169444041;4930.556249937523;4956.653034287565;4968.4286307075145;4916.360873086476;4919.286703427912;4943.802247910044;4946.985867897;4948.727819969936;4734.905301894012;4880.829170978998;4890.364252647133;4897.4857639947295;4923.556879880828;4885.9022819544125;4982.551570358685;5009.423916249678;5073.695578265316;5082.491750824888;4984.675996060245;4986.825461108844;4990.476000019946;4998.925231075336;5004.823866391323 22:00:28 INFO - PROCESS | 7631 | |5;yui.html;564.5398999815151;591.4174538079991;620.8075496596056;624.5128799536358;711.9074520312358;11599.65201043969;12123.636290911276;12232.877671223307;12323.63029109126;12457.128001039919;12821.935890320536;12946.611601651943;12957.676058098552;12960.54638087667;12970.286634235114;623.9126522286883;754.7358424551418;775.2673723331454;777.4363586399843;777.933875620573;756.9545827250355;779.8440311937635;780.325018858686;784.8783438567032;785.6189747972257;815.8816971539123;817.5217497763815;817.648411183191;818.7871153500073;819.1726356380069;645.141961191614;810.0036954545941;811.6063709101081;811.6915572082601;812.1229072601606;573.7246121861488;589.3605438099667;591.8579540910193;594.0613830148355;594.3313772006493;10653.733656658567;10657.946710266398;10682.679519614425;10731.624393146203;10767.4616269187;11998.280103193749;12046.518139274418;12568.371581420892;12819.423125959354;13000.219986800823;10734.677959661145;10819.459027048617;10845.83731244032;10875.836862446991;10975.396353200571;3933.6853051755793;3957;3965.147493288957;4052.5136983561865;4054.9594504054876;61797;61838.38161618371;61938.38061619416;62715;62876.99999999954;9493.810123797624;9526.476043817518;9666;9901.653442129491;9955.701328960144;18804.529886752942;18805.15376808047;18839.152238149316;18853;18883.62232755337;6260.373962603703;6260.624362538262;6339.049142628643;6383.96808015957;6465.709043093072;3524.2775231077567;3533.5759708835103;3546.8935931922083;3552.969638804769;3607.657272559155;2501.4621856300982;2554.3358726731185;2560.065576064723;2565.7434256574193;2565.9230223093336;477.91158635652516;484.1309848821374;486.57424753340825;491.9335889654922;498.5787009976564;465.13484918052393;475.3772557949098;475.6147520508399;497.03327028928385;544.0614939229876;6943.756968506179;6956.8956465653055;7241.696494631017;7274.16347120088;7279.563226206445;406.9776162311042;419.96640268778424;460.7028466638999;489.1879480063078;527.5710411098804;3345.6654334566347;3522.330757156132;3656.5246517952496;3686.4286035664513;3700.9629903701157;80075.19924800794;96223;96772;98967;99242.50378748204;83256.58371708223;83362.58318708367;85272.14727852766;86176;86512.13487865166;72042.91935620969;72427.63786181035;73169.26830731731;77504.99999999886;78449.21550784534;6861.622610756257;6952.756653517305;6994.125734283215;7012.824679383056;7310;45474.54525454703;46638;47888;48095.75952120217;48818.26772598485;59428;63123.248321880776;65158.348416515226;67980;68426.65786671035;8613.3109351253;8960.551972401485;9008.819823603622;9116.179543841086;9180.357374983687;10494.108000820015;10520;10601.310914790822;10942.124630029419;11123;9792;9969;10097.242706796873;10174.440405777754;10220.335678180894;9880.567317738743;9977.95231500682;10011.699649010541;10143.442110683687;10206.081452669296;11191.944040279746;11356.205065645325;11440.141989350666;11937.761244775229;11995.880041199476;9502.429854208493;9663.855042174513;10068.144207742424;10069.295149339476;10069.647562335284;9431.245500360092;9493.762655933657;9656.71029869105;10034.448105354275;10152.898471015194 22:00:28 INFO - PROCESS | 7631 | __end_tp_report 22:00:28 INFO - PROCESS | 7631 | __start_cc_report 22:00:28 INFO - PROCESS | 7631 | _x_x_mozilla_cycle_collect,1013 22:00:28 INFO - PROCESS | 7631 | __end_cc_report 22:00:28 INFO - PROCESS | 7631 | __startTimestamp1469682028539__endTimestamp 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | ------- Summary: start ------- 22:00:28 INFO - PROCESS | 7631 | Number of tests: 6 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | [#0] dojo.html Cycles:165 Average:4626.98 Median:4740.57 stddev:487.09 (10.3%) stddev-sans-first:461.28 22:00:28 INFO - PROCESS | 7631 | Values: 2577.0 2582.9 2619.5 2627.9 2629.7 4581.5 4590.9 4599.8 4622.9 4645.7 4623.6 4641.8 4655.4 4696.6 4717.4 4211.7 4249.2 4283.9 4328.3 4455.8 4350.7 4404.8 4416.3 4422.6 4435.8 4181.1 4221.5 4223.7 4259.0 4335.0 4259.1 4260.2 4266.5 4295.2 4362.3 4305.5 4310.2 4320.7 4352.5 4358.4 4534.6 4537.6 4705.2 4712.6 4754.9 4876.1 4986.8 4988.0 5025.8 5035.2 5008.9 5026.3 5028.0 5036.9 5037.0 4544.7 4674.5 4696.5 4701.6 4736.6 5202.7 5226.3 5263.9 5275.6 5278.6 4948.4 4996.9 5042.1 5046.3 5046.4 4880.4 4964.2 4990.7 5036.1 5148.2 4942.8 4978.8 4981.5 5007.7 5008.3 4944.0 4953.7 4963.7 4976.0 5012.0 4120.9 4157.1 4210.6 4272.1 4319.0 3699.7 3719.5 3753.7 3787.4 3810.5 4294.1 4294.2 4298.2 4299.0 4339.6 4831.7 4887.7 5029.0 5030.6 5066.8 4269.7 4302.0 4305.5 4307.7 4357.8 4532.0 4538.3 4546.2 4548.8 4589.2 4577.4 4740.6 4742.6 4747.0 4775.8 4912.3 4927.8 4956.7 4963.1 5028.5 4655.9 4658.5 4677.7 4689.2 4731.8 4802.9 4910.9 4911.7 4923.9 4966.0 4899.2 4953.6 5040.9 5043.2 5053.1 4919.0 4930.3 4931.7 4936.2 4980.5 4703.4 4898.5 4901.9 4905.3 4969.0 4771.2 4779.9 4805.2 4853.1 4871.8 4902.5 4903.4 4904.2 4906.7 4962.3 4599.9 4801.5 4808.3 4808.9 4843.8 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | [#1] ext.html Cycles:180 Average:47394.70 Median:18032.79 stddev:87210.47 (483.6%) stddev-sans-first:87384.20 22:00:28 INFO - PROCESS | 7631 | Values: 551.5 583.8 589.8 593.9 598.7 39021.6 40554.6 40611.0 40736.2 44636.3 40941.6 41417.0 41435.8 41502.4 41517.8 7269.0 7690.5 7844.3 7846.7 9879.8 9701.6 10125.6 10131.2 10131.3 10133.6 10134.7 10181.9 10186.0 10186.2 10192.6 10069.6 10111.2 10114.1 10123.9 10130.9 9958.9 10015.1 10028.1 10029.9 10039.7 38891.8 39469.0 39494.7 39515.2 39519.4 2985.9 3010.5 3096.1 3126.6 3297.2 27670.2 27982.6 28051.0 28185.7 28815.0 3125.4 3185.4 3189.2 3194.2 3269.1 264078.0 267850.0 268378.0 268596.0 270785.0 50236.5 50563.5 50625.7 50637.7 50692.2 32768.3 33513.7 33775.0 35613.0 35655.3 14041.6 14209.4 14217.5 14234.4 14238.6 9370.7 10315.8 10548.4 10556.8 10558.2 18005.5 18043.0 18077.3 18209.8 18219.3 6595.6 6722.6 6875.6 6935.2 6957.7 741.9 771.9 883.7 960.3 967.0 23885.2 25926.5 25937.4 25999.2 26067.6 929.7 940.4 948.5 1018.4 1022.0 12906.4 13604.5 13626.8 13715.9 13890.7 394095.0 397868.0 397877.0 398051.0 400531.0 49402.5 49406.0 49776.3 49988.3 50081.2 1075.6 1114.8 1172.1 1178.1 1211.7 24692.4 28251.6 28567.6 28754.1 29189.9 40541.4 41022.8 41057.6 41070.4 41123.2 312351.0 313713.0 315160.0 316344.0 318572.0 24654.3 25792.7 25901.0 26962.5 27024.7 38807.8 38858.4 39496.2 39705.6 40635.2 33475.7 33563.5 33851.3 33912.0 34159.3 33172.5 33740.5 34057.3 34292.1 34776.3 33368.3 33625.7 34044.8 34049.3 35849.3 28905.4 29267.3 33959.7 34255.3 34279.5 25077.6 25372.2 25428.6 25687.1 25729.7 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | [#2] jquery.html Cycles:180 Average:24507.26 Median:5405.22 stddev:67198.16 (1243.2%) stddev-sans-first:67362.18 22:00:28 INFO - PROCESS | 7631 | Values: 341.9 353.3 363.7 366.6 382.6 12805.4 13691.2 14213.4 16959.8 16963.5 16013.5 16190.2 16776.9 16812.2 17578.0 4558.3 4689.6 4698.6 4702.0 4723.2 3549.9 5733.8 5997.0 6076.1 6151.3 5224.1 6096.4 6106.9 6110.5 6113.3 5501.6 5635.9 5637.5 5649.6 5654.0 5290.8 5384.7 5425.8 5489.6 5496.9 12285.5 13337.1 14535.2 14652.1 14657.0 1801.6 1836.2 1850.7 2108.2 2189.5 8889.8 8963.8 9033.0 9456.4 9613.0 1628.1 1685.9 1713.6 1734.7 1814.2 65042.3 68357.0 69024.3 69762.7 69995.3 9685.9 9936.9 9938.5 9957.5 10275.6 13608.2 13640.9 13652.1 13667.5 13668.9 6716.1 6829.9 6922.4 7002.3 7025.1 6311.2 6399.2 6440.9 6561.0 6570.9 4270.3 4274.2 4283.6 4319.0 4380.4 1645.6 1673.6 1695.6 1702.9 1715.8 733.8 821.1 894.5 900.3 948.9 15426.0 15434.2 15447.2 15793.2 16565.6 817.7 832.2 851.1 875.3 896.9 8670.9 8927.7 9290.5 9311.9 9458.4 399258.0 406043.0 406541.0 410635.0 411188.0 77275.0 79029.0 80754.6 81175.0 81397.0 863.0 882.8 895.8 897.3 899.2 14268.7 14366.2 14647.3 14758.8 15239.6 37549.4 38641.0 38889.8 39040.4 39400.4 51099.2 52357.7 52596.7 52883.2 53425.7 7311.7 7537.9 7673.7 7703.0 7982.4 6496.3 8688.0 10972.2 11895.5 11945.5 9200.4 9595.7 9599.6 9771.5 9882.1 9929.5 10452.2 10471.9 10508.6 10601.3 9627.2 9857.0 10041.9 10157.7 10371.2 7462.5 7668.5 7720.4 7809.3 8047.7 9275.7 9474.5 9732.0 9748.0 10050.0 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | [#3] mootools.html Cycles:180 Average:4729.66 Median:5788.98 stddev:2595.94 (44.8%) stddev-sans-first:2590.07 22:00:28 INFO - PROCESS | 7631 | Values: 1252.9 1325.9 1360.9 1365.5 1501.5 6237.5 6845.0 7159.8 7164.1 7364.2 7208.5 7215.0 7220.8 7220.9 7223.9 2126.7 2149.7 2152.0 2154.2 2156.1 2054.5 2105.5 2155.8 2156.1 2159.0 2099.9 2117.6 2126.1 2127.7 2140.9 2097.5 2104.0 2104.7 2106.3 2106.7 2036.1 2089.3 2093.3 2093.8 2094.3 7166.2 7177.7 7286.7 7288.2 7291.2 4086.5 4237.0 4260.9 4262.1 4269.5 2723.2 2844.6 2866.7 2889.5 2890.3 878.4 884.7 887.1 891.9 897.1 9429.9 9480.7 9499.8 9539.7 9541.5 3645.4 3839.4 3957.4 3977.7 3978.1 7574.1 7583.2 7586.3 7594.0 7596.3 4233.0 4238.1 4239.4 4244.7 4250.6 3338.0 3749.3 3869.6 3936.0 3937.4 3209.2 3209.6 3341.7 3347.0 3347.7 948.6 951.7 953.7 956.5 966.0 685.9 705.9 735.0 764.7 815.8 4615.4 4868.7 4942.8 4970.4 5129.7 396.5 408.5 411.2 412.6 412.9 3520.9 3534.8 3539.8 3544.3 3577.2 8409.0 8826.7 8869.2 8876.7 8881.8 8826.6 8831.2 8835.5 8849.6 8851.4 6532.2 6700.0 6805.8 6808.1 6953.7 4830.9 5035.1 5066.0 5103.1 5110.9 7833.8 7943.5 7976.8 8124.6 8156.8 7954.6 8224.0 8229.5 8235.1 8241.3 6008.0 6532.0 6674.1 6694.4 6694.4 6616.7 6843.6 6916.1 7068.3 7070.2 6122.5 6201.8 6310.6 6404.0 6421.5 6185.1 6228.8 6237.2 6239.8 6271.9 5775.2 5802.8 5822.4 5829.1 5844.8 4232.3 4299.8 4364.6 4465.7 4487.7 4275.4 4350.6 4418.6 4530.0 4537.0 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | [#4] prototype.html Cycles:180 Average:4160.79 Median:4791.71 stddev:1187.98 (24.8%) stddev-sans-first:1155.45 22:00:28 INFO - PROCESS | 7631 | Values: 301.3 308.2 308.3 315.3 316.6 4793.9 4821.5 4834.3 4861.0 4865.2 4766.8 4768.4 4798.4 4799.7 4807.2 3612.9 3656.9 3728.5 3729.8 3792.9 3785.7 3792.4 3793.7 3794.4 3796.9 3747.4 3753.0 3753.9 3754.0 3757.9 3608.5 3624.9 3739.0 3741.9 3743.7 3338.1 3340.1 3340.2 3340.5 3361.5 4635.7 4668.3 4671.9 4691.2 4718.1 4209.7 4300.9 4320.7 4331.8 4335.5 4985.5 4990.7 4995.7 5000.5 5016.9 3668.8 3673.3 3675.0 3675.3 3675.6 5080.2 5231.3 5236.9 5244.0 5244.0 3706.2 3736.7 3742.8 3751.3 3762.7 3795.5 3797.8 3805.6 3806.2 3806.7 4269.2 4278.9 4288.7 4290.7 4298.0 4791.9 4991.7 4993.6 4995.4 5006.0 1291.0 1302.7 1306.2 1306.5 1308.6 849.0 860.6 864.2 865.6 901.2 4298.8 4347.3 4348.3 4362.2 4389.8 5110.5 5116.6 5117.5 5121.5 5123.2 4243.9 4340.7 4349.8 4367.4 4384.0 2705.5 2711.3 2716.6 2729.8 2731.7 4816.3 4818.9 4821.8 4837.8 4865.5 5075.9 5140.6 5216.6 5259.4 5260.4 4794.5 4819.9 4830.8 4830.9 4836.4 5127.6 5130.1 5131.5 5141.6 5142.4 4741.6 5029.4 5036.7 5041.5 5057.3 4785.8 4791.5 4797.7 4799.2 4808.7 3691.6 3704.7 3708.8 3711.6 3728.7 4890.4 5055.8 5066.7 5067.2 5072.7 4914.0 4924.5 4930.6 4956.7 4968.4 4916.4 4919.3 4943.8 4947.0 4948.7 4734.9 4880.8 4890.4 4897.5 4923.6 4885.9 4982.6 5009.4 5073.7 5082.5 4984.7 4986.8 4990.5 4998.9 5004.8 22:00:28 INFO - PROCESS | 7631 | 22:00:28 INFO - PROCESS | 7631 | [#5] yui.html Cycles:180 Average:17415.59 Median:554.30 stddev:25468.88 (4594.8%) stddev-sans-first:25508.90 22:00:28 INFO - PROCESS | 7631 | Values: 564.5 591.4 620.8 624.5 711.9 11599.7 12123.6 12232.9 12323.6 12457.1 12821.9 12946.6 12957.7 12960.5 12970.3 623.9 754.7 775.3 777.4 777.9 757.0 779.8 780.3 784.9 785.6 815.9 817.5 817.6 818.8 819.2 645.1 810.0 811.6 811.7 812.1 573.7 589.4 591.9 594.1 594.3 10653.7 10657.9 10682.7 10731.6 10767.5 11998.3 12046.5 12568.4 12819.4 13000.2 10734.7 10819.5 10845.8 10875.8 10975.4 3933.7 3957.0 3965.1 4052.5 4055.0 61797.0 61838.4 61938.4 62715.0 62877.0 9493.8 9526.5 9666.0 9901.7 9955.7 18804.5 18805.2 18839.2 18853.0 18883.6 6260.4 6260.6 6339.0 6384.0 6465.7 3524.3 3533.6 3546.9 3553.0 3607.7 2501.5 2554.3 2560.1 2565.7 2565.9 477.9 484.1 486.6 491.9 498.6 465.1 475.4 475.6 497.0 544.1 6943.8 6956.9 7241.7 7274.2 7279.6 407.0 420.0 460.7 489.2 527.6 3345.7 3522.3 3656.5 3686.4 3701.0 80075.2 96223.0 96772.0 98967.0 99242.5 83256.6 83362.6 85272.1 86176.0 86512.1 72042.9 72427.6 73169.3 77505.0 78449.2 6861.6 6952.8 6994.1 7012.8 7310.0 45474.5 46638.0 47888.0 48095.8 48818.3 59428.0 63123.2 65158.3 67980.0 68426.7 8613.3 8960.6 9008.8 9116.2 9180.4 10494.1 10520.0 10601.3 10942.1 11123.0 9792.0 9969.0 10097.2 10174.4 10220.3 9880.6 9978.0 10011.7 10143.4 10206.1 11191.9 11356.2 11440.1 11937.8 11995.9 9502.4 9663.9 10068.1 10069.3 10069.6 9431.2 9493.8 9656.7 10034.4 10152.9 22:00:28 INFO - PROCESS | 7631 | -------- Summary: end -------- 22:00:28 INFO - PROCESS | 7631 | 22:00:29 INFO - TEST-INFO | 7631: exit 0 22:00:29 INFO - TEST-OK | dromaeo_css | took 1118093ms 22:00:29 INFO - TEST-START | kraken 22:00:29 INFO - operating with platform_type : linux_ 22:00:29 INFO - Initialising browser for kraken test... 22:00:29 INFO - TEST-INFO | started process 7822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQqrPN0/profile http://localhost:38225/getInfo.html) 22:00:32 INFO - PROCESS | 7822 | JavaScript error: , line 0: uncaught exception: undefined 22:00:33 INFO - PROCESS | 7822 | __metrics Screen width/height:1600/1200 22:00:33 INFO - PROCESS | 7822 | colorDepth:24 22:00:33 INFO - PROCESS | 7822 | Browser inner width/height: 1024/697 22:00:33 INFO - PROCESS | 7822 | __metrics 22:00:33 INFO - PROCESS | 7822 | 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" 22:00:33 INFO - PROCESS | 7822 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:00:34 INFO - TEST-INFO | 7822: exit 0 22:00:34 INFO - Browser initialized. 22:00:34 INFO - Running cycle 1/1 for kraken test... 22:00:34 INFO - TEST-INFO | started process 7904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpQqrPN0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:00:46 INFO - PROCESS | 7904 | RSS: Main: 159322112 22:00:46 INFO - PROCESS | 7904 | 22:00:49 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38225/tests/kraken/driver.html?testName=audio-beat-detection) 22:00:50 INFO - PROCESS | 7904 | RSS: Main: 215076864 22:00:50 INFO - PROCESS | 7904 | 22:00:53 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38225/tests/kraken/driver.html?testName=audio-dft) 22:00:53 INFO - PROCESS | 7904 | RSS: Main: 192733184 22:00:53 INFO - PROCESS | 7904 | 22:00:57 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38225/tests/kraken/driver.html?testName=audio-fft) 22:00:57 INFO - PROCESS | 7904 | RSS: Main: 272457728 22:00:57 INFO - PROCESS | 7904 | 22:00:59 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38225/tests/kraken/driver.html?testName=audio-oscillator) 22:01:00 INFO - PROCESS | 7904 | RSS: Main: 182001664 22:01:00 INFO - PROCESS | 7904 | 22:01:02 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:01:03 INFO - PROCESS | 7904 | RSS: Main: 186863616 22:01:03 INFO - PROCESS | 7904 | 22:01:06 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38225/tests/kraken/driver.html?testName=imaging-darkroom) 22:01:07 INFO - PROCESS | 7904 | RSS: Main: 223326208 22:01:07 INFO - PROCESS | 7904 | 22:01:10 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38225/tests/kraken/driver.html?testName=imaging-desaturate) 22:01:11 INFO - PROCESS | 7904 | RSS: Main: 216924160 22:01:11 INFO - PROCESS | 7904 | 22:01:14 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38225/tests/kraken/driver.html?testName=json-parse-financial) 22:01:14 INFO - PROCESS | 7904 | RSS: Main: 219725824 22:01:14 INFO - PROCESS | 7904 | 22:01:16 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:01:17 INFO - PROCESS | 7904 | RSS: Main: 185491456 22:01:17 INFO - PROCESS | 7904 | 22:01:18 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:01:19 INFO - PROCESS | 7904 | RSS: Main: 191385600 22:01:19 INFO - PROCESS | 7904 | 22:01:21 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:01:22 INFO - PROCESS | 7904 | RSS: Main: 196124672 22:01:22 INFO - PROCESS | 7904 | 22:01:25 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:01:25 INFO - PROCESS | 7904 | RSS: Main: 189919232 22:01:25 INFO - PROCESS | 7904 | 22:01:28 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:01:29 INFO - PROCESS | 7904 | RSS: Main: 193720320 22:01:29 INFO - PROCESS | 7904 | 22:01:30 INFO - PROCESS | 7904 | Cycle 1(1): loaded http://localhost:38225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38225/tests/kraken/driver.html?testName=ai-astar) 22:01:31 INFO - PROCESS | 7904 | RSS: Main: 244068352 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | __start_tp_report 22:01:31 INFO - PROCESS | 7904 | _x_x_mozilla_page_load 22:01:31 INFO - PROCESS | 7904 | _x_x_mozilla_page_load_details 22:01:31 INFO - PROCESS | 7904 | |i|pagename|runs| 22:01:31 INFO - PROCESS | 7904 | |0;ai-astar;103;113;113;115;114;113;113;112;112;112 22:01:31 INFO - PROCESS | 7904 | |1;audio-beat-detection;148;129;122;198;128;133;154;122;162;123 22:01:31 INFO - PROCESS | 7904 | |2;audio-dft;161;161;160;161;162;161;162;161;160;161 22:01:31 INFO - PROCESS | 7904 | |3;audio-fft;75;76;76;112;77;78;75;76;100;79 22:01:31 INFO - PROCESS | 7904 | |4;audio-oscillator;86;86;118;86;85;86;120;85;85;125 22:01:31 INFO - PROCESS | 7904 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;117;116;117 22:01:31 INFO - PROCESS | 7904 | |6;imaging-darkroom;147;147;146;147;145;148;147;147;146;146 22:01:31 INFO - PROCESS | 7904 | |7;imaging-desaturate;95;95;95;95;95;95;96;94;96;95 22:01:31 INFO - PROCESS | 7904 | |8;json-parse-financial;62;60;60;61;60;62;61;61;61;60 22:01:31 INFO - PROCESS | 7904 | |9;json-stringify-tinderbox;65;66;67;66;67;66;66;66;66;67 22:01:31 INFO - PROCESS | 7904 | |10;stanford-crypto-aes;86;80;80;79;79;89;79;87;79;80 22:01:31 INFO - PROCESS | 7904 | |11;stanford-crypto-ccm;133;131;135;132;139;127;131;142;171;130 22:01:31 INFO - PROCESS | 7904 | |12;stanford-crypto-pbkdf2;167;162;173;166;166;165;167;167;162;165 22:01:31 INFO - PROCESS | 7904 | |13;stanford-crypto-sha256-iterative;68;68;68;70;69;68;66;66;69;66 22:01:31 INFO - PROCESS | 7904 | __end_tp_report 22:01:31 INFO - PROCESS | 7904 | __start_cc_report 22:01:31 INFO - PROCESS | 7904 | _x_x_mozilla_cycle_collect,1962 22:01:31 INFO - PROCESS | 7904 | __end_cc_report 22:01:31 INFO - PROCESS | 7904 | __startTimestamp1469682091229__endTimestamp 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | ------- Summary: start ------- 22:01:31 INFO - PROCESS | 7904 | Number of tests: 14 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.30 (2.9%) stddev-sans-first:1.00 22:01:31 INFO - PROCESS | 7904 | Values: 103.0 113.0 113.0 115.0 114.0 113.0 113.0 112.0 112.0 112.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:140.50 stddev:24.32 (17.3%) stddev-sans-first:25.69 22:01:31 INFO - PROCESS | 7904 | Values: 148.0 129.0 122.0 198.0 128.0 133.0 154.0 122.0 162.0 123.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 22:01:31 INFO - PROCESS | 7904 | Values: 161.0 161.0 160.0 161.0 162.0 161.0 162.0 161.0 160.0 161.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:12.82 (16.9%) stddev-sans-first:13.31 22:01:31 INFO - PROCESS | 7904 | Values: 75.0 76.0 76.0 112.0 77.0 78.0 75.0 76.0 100.0 79.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:85.50 stddev:17.20 (20.1%) stddev-sans-first:17.85 22:01:31 INFO - PROCESS | 7904 | Values: 86.0 86.0 118.0 86.0 85.0 86.0 120.0 85.0 85.0 125.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 22:01:31 INFO - PROCESS | 7904 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 117.0 116.0 117.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 22:01:31 INFO - PROCESS | 7904 | Values: 147.0 147.0 146.0 147.0 145.0 148.0 147.0 147.0 146.0 146.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:01:31 INFO - PROCESS | 7904 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 96.0 94.0 96.0 95.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:61.00 stddev:0.79 (1.3%) stddev-sans-first:0.71 22:01:31 INFO - PROCESS | 7904 | Values: 62.0 60.0 60.0 61.0 60.0 62.0 61.0 61.0 61.0 60.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.50 22:01:31 INFO - PROCESS | 7904 | Values: 65.0 66.0 67.0 66.0 67.0 66.0 66.0 66.0 66.0 67.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:80.00 stddev:3.91 (4.9%) stddev-sans-first:3.84 22:01:31 INFO - PROCESS | 7904 | Values: 86.0 80.0 80.0 79.0 79.0 89.0 79.0 87.0 79.0 80.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:134.00 stddev:12.70 (9.5%) stddev-sans-first:13.38 22:01:31 INFO - PROCESS | 7904 | Values: 133.0 131.0 135.0 132.0 139.0 127.0 131.0 142.0 171.0 130.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.50 stddev:3.09 (1.9%) stddev-sans-first:3.26 22:01:31 INFO - PROCESS | 7904 | Values: 167.0 162.0 173.0 166.0 166.0 165.0 167.0 167.0 162.0 165.0 22:01:31 INFO - PROCESS | 7904 | 22:01:31 INFO - PROCESS | 7904 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:1.40 (2.1%) stddev-sans-first:1.48 22:01:31 INFO - PROCESS | 7904 | Values: 68.0 68.0 68.0 70.0 69.0 68.0 66.0 66.0 69.0 66.0 22:01:31 INFO - PROCESS | 7904 | -------- Summary: end -------- 22:01:31 INFO - PROCESS | 7904 | 22:01:32 INFO - TEST-INFO | 7904: exit 0 22:01:32 INFO - TEST-OK | kraken | took 62915ms 22:01:32 INFO - SUITE-END | took 1181s 22:01:32 INFO - Completed test suite (00:19:41) 22:01:32 INFO - javascript benchmark 22:01:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2577.0, 2582.8966841326346, 2619.502294564032, 2627.855467949263, 2629.6844378674564, 4581.45022597288, 4590.931136032962, 4599.770011499424, 4622.8613141605765, 4645.651576131787, 4623.5838774510285, 4641.791119399627, 4655.394798676176, 4696.577308042276, 4717.386739723831, 4211.726237794549, 4249.213895429355, 4283.935740963888, 4328.264195086843, 4455.84404545841, 4350.73895566267, 4404.845830395937, 4416.3375493675885, 4422.5577442255835, 4435.756033418161, 4181.0592616661315, 4221.472315960505, 4223.746575205498, 4259.041715613993, 4335.024619460628, 4259.084296876186, 4260.233158031553, 4266.488021437416, 4295.205387003414, 4362.280223763075, 4305.461817272841, 4310.181065597527, 4320.675949303783, 4352.499462561815, 4358.389825424443, 4534.614557762594, 4537.591616754475, 4705.1765940960195, 4712.552307530779, 4754.881127971794, 4876.146674332013, 4986.775595098229, 4988.02733466974, 5025.824096156617, 5035.194368900995, 5008.874778130539, 5026.271190677331, 5028.0, 5036.899262014739, 5037.017781532602, 4544.6818722689095, 4674.5325467452985, 4696.483386827446, 4701.55335243148, 4736.550027747358, 5202.71385073817, 5226.3205783248695, 5263.868403289948, 5275.57795376369, 5278.5513231375, 4948.356713627206, 4996.90006199874, 5042.067217564762, 5046.318746969112, 5046.369203849519, 4880.389951256093, 4964.156093464047, 4990.675606085593, 5036.143855544567, 5148.176291793295, 4942.75286235694, 4978.751062446863, 4981.501849814989, 5007.724575148329, 5008.323876276656, 4943.950560494421, 4953.6532442729385, 4963.702177869267, 4975.950240497621, 5012.022655582199, 4120.855770048034, 4157.064660451374, 4210.59999300066, 4272.060146767706, 4319.0, 3699.685526730258, 3719.479272901796, 3753.7184711146774, 3787.3750831113107, 3810.504634397566, 4294.119705460374, 4294.162638285567, 4298.226319262562, 4299.0, 4339.566043395698, 4831.710097394097, 4887.682300650446, 5029.044481548494, 5030.572401345853, 5066.82266120684, 4269.701120921506, 4301.956980430155, 4305.461817272841, 4307.655387568987, 4357.825686972549, 4531.954680453219, 4538.319252112144, 4546.158960592301, 4548.772561371984, 4589.173948689268, 4577.3591697161755, 4740.573348398591, 4742.620590352833, 4746.97626511879, 4775.808967641325, 4912.287718280901, 4927.778249978688, 4956.653034287565, 4963.10664080469, 5028.4971502850885, 4655.906881862276, 4658.510856360033, 4677.695949763254, 4689.226277664147, 4731.787069581808, 4802.8799280018275, 4910.9017819642695, 4911.68074075184, 4923.876903077452, 4966.0, 4899.167141585867, 4953.603711702983, 5040.873978150576, 5043.218301163325, 5053.140966035709, 4919.0, 4930.334404855299, 4931.679440836334, 4936.2102063669645, 4980.477049909708, 4703.3885594872445, 4898.510148985216, 4901.852944411673, 4905.313256144072, 4969.0, 4771.236602143779, 4779.904401912012, 4805.231163013901, 4853.0779151961015, 4871.756412179306, 4902.485239049848, 4903.387076615423, 4904.209963363418, 4906.730129842893, 4962.280469331999, 4599.862004139881, 4801.51984801517, 4808.2547205183255, 4808.903821923472, 4843.782029808596], "value": 4595.875225770991, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [551.4575919685152, 583.7898356591628, 589.7788329376484, 593.92575928009, 598.6946657204826, 39021.60978390215, 40554.594454055485, 40611.0, 40736.185276294455, 44636.3304550432, 40941.59058409415, 41417.0, 41435.79282103585, 41502.37746433806, 41517.7924110379, 7268.963655181743, 7690.461667683265, 7844.333231675315, 7846.725364612211, 9879.802403951917, 9701.611935522571, 10125.594976200942, 10131.189504839633, 10131.34146280493, 10133.645322413717, 10134.74663133424, 10181.898181018169, 10186.0, 10186.236032297607, 10192.643257485952, 10069.64756233532, 10111.191104711605, 10114.140298074672, 10123.89876101237, 10130.898691013106, 9958.90041099587, 10015.098641122335, 10028.147607453375, 10029.949850250776, 10039.69880903574, 38891.8055409724, 39469.0, 39494.65558788531, 39515.20969580593, 39519.40720889189, 2985.8656360463615, 3010.488217003093, 3096.086654436924, 3126.609173853268, 3297.241634424096, 27670.169894902985, 27982.580261295894, 28051.0, 28185.74514038872, 28815.0, 3125.4217969675456, 3185.378851124032, 3189.1867573768773, 3194.169515925853, 3269.0683155300626, 264077.9999999981, 267850.0, 268378.0, 268596.0, 270785.0, 50236.497635023916, 50563.49436505661, 50625.74687126541, 50637.74681126644, 50692.23961640579, 32768.344633107205, 33513.66486335154, 33775.0, 35613.0, 35655.28689426249, 14041.578752637437, 14209.431622734975, 14217.50238741639, 14234.359453824603, 14238.644033899236, 9370.67202647904, 10315.84526232107, 10548.367097974146, 10556.788864222672, 10558.208134389952, 18005.459836204936, 18043.0, 18077.27690892376, 18209.817901821076, 18219.271229150952, 6595.637239951849, 6722.596644201363, 6875.621840798704, 6935.167779866449, 6957.65211739411, 741.8664640364786, 771.9498232614862, 883.7437143228392, 960.279790157382, 966.9806603867884, 23885.164019259242, 25926.481470370487, 25937.35156621062, 25999.220023399328, 26067.608985865227, 929.7164364868645, 940.4498368454394, 948.5257371314343, 1018.4347687033768, 1022.0239671114068, 12906.35468226585, 13604.523841665494, 13626.795598066037, 13715.931420343035, 13890.722185556233, 394095.0, 397868.0, 397877.0, 398051.0, 400531.0, 49402.50597493979, 49406.0, 49776.25335619968, 49988.250176247384, 50081.24878126831, 1075.5858994287255, 1114.8104822180087, 1172.1033409441955, 1178.1458442629025, 1211.709189794443, 24692.38269043288, 28251.576226357032, 28567.571486428136, 28754.137375878756, 29189.85405072961, 40541.39187912125, 41022.79488602538, 41057.589424105376, 41070.38394424146, 41123.17753644851, 312351.0, 313713.0, 315160.0, 316344.0, 318572.0, 24654.260372188863, 25792.742072579033, 25901.0, 26962.460750785267, 27024.72975270301, 38807.805960970014, 38858.445662173195, 39496.210075798896, 39705.60294397019, 40635.1872962545, 33475.665243347255, 33563.49654755229, 33851.3229735399, 33912.0, 34159.31681366408, 33172.50241246335, 33740.49389259212, 34057.318853622295, 34292.14269643279, 34776.30447391089, 33368.33263334768, 33625.663743363235, 34044.829775850965, 34049.31901362009, 35849.283014339046, 28905.421891562473, 29267.268318292212, 33959.660403396636, 34255.31489370248, 34279.485807713405, 25077.623835642844, 25372.238832835046, 25428.618570721825, 25687.100951466608, 25729.742702573483], "value": 17536.63337187251, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.8615460738402, 353.3004650791433, 363.7399259529437, 366.5582972518115, 382.55814534212993, 12805.423755930982, 13691.178529288238, 14213.431462741479, 16959.83040169598, 16963.49109526713, 16013.519594412157, 16190.19049047546, 16776.916115419404, 16812.15939203038, 17578.033208173547, 4558.339040839076, 4689.648276379268, 4698.64760142989, 4702.0, 4723.244280915054, 3549.8580056797696, 5733.827985160452, 5997.040473524237, 6076.118962750398, 6151.261848578154, 5224.1119009768245, 6096.39036096389, 6106.938930610681, 6110.450059494645, 6113.266408031042, 5501.587380946425, 5635.943640563582, 5637.492625663669, 5649.576281778842, 5654.0, 5290.841274761763, 5384.6769193848495, 5425.755840987165, 5489.615726899118, 5496.917546236809, 12285.508579656804, 13337.066405351628, 14535.200563968978, 14652.120872747562, 14656.974011819177, 1801.639672065592, 1836.2379612460945, 1850.69463538516, 2108.156737305075, 2189.4526368407896, 8889.777755556099, 8963.775905602346, 9032.954835225783, 9456.432614043179, 9613.038696130401, 1628.145223757525, 1685.9157042147845, 1713.6058706497452, 1734.7397890316427, 1814.2289526950992, 65042.3495765041, 68357.0, 69024.3097569023, 69762.65118674425, 69995.30004699939, 9685.903140968641, 9936.850947235796, 9938.503074846229, 9957.452340121363, 10275.640352587623, 13608.183508989294, 13640.931795340959, 13652.112612680343, 13667.453301867814, 13668.863311366958, 6716.126903502513, 6829.863402731918, 6922.376986071277, 7002.253794220068, 7025.121859767529, 6311.2426508819235, 6399.232092148972, 6440.903386449207, 6560.967195163994, 6570.934290657128, 4270.316749320094, 4274.187904298174, 4283.593058659422, 4318.956810431919, 4380.430544029319, 1645.6379596488753, 1673.648533807914, 1695.5845817774725, 1702.8637708983251, 1715.785526809149, 733.7835338575027, 821.117298903681, 894.4901406198404, 900.3247564326755, 948.9430634161972, 15426.0, 15434.228288585526, 15447.150406727738, 15793.21033948298, 16565.58586035359, 817.7138001699358, 832.1803024021334, 851.114840565817, 875.3216257400566, 896.852019416789, 8670.91329086701, 8927.687530936386, 9290.489023103793, 9311.860322095174, 9458.432494050241, 399258.0, 406043.0, 406541.0, 410635.0, 411188.0, 77275.0, 79029.0, 80754.59622701966, 81175.0, 81397.0, 862.9520109777609, 882.849915514364, 895.7984453497911, 897.3090720145582, 899.2311573604604, 14268.71462570722, 14366.209858457885, 14647.26763661834, 14758.778618320519, 15239.619009524851, 37549.43675844919, 38641.0, 38889.80555097319, 39040.41439378355, 39400.40899386569, 51099.2335114981, 52357.7382113087, 52596.73701631468, 52883.20675189798, 53425.73287133539, 7311.744088956861, 7537.886931696139, 7673.654685539276, 7702.961485192538, 7982.361411087215, 6496.285408605144, 8688.0, 10972.177086718688, 11895.524179032742, 11945.522179112739, 9200.35597508168, 9595.656608075014, 9599.568019439002, 9771.511424428609, 9882.061204185879, 9929.503524823585, 10452.216083793593, 10471.895281047093, 10508.579656813641, 10601.310914790514, 9627.229821614113, 9856.950715246665, 10041.899581004096, 10157.746056348651, 10371.170306375623, 7462.477626566306, 7668.463207575416, 7720.420968427278, 7809.297163255334, 8047.678092876219, 9275.72172834816, 9474.526273686426, 9731.951340243253, 9748.0, 10050.045245701647], "value": 7967.854087803051, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1252.8684488128752, 1325.9403326850295, 1360.938757755901, 1365.4742923974268, 1501.5270189890184, 6237.46981506572, 6845.041694162815, 7159.82100447489, 7164.140303163627, 7364.153122390927, 7208.495405321616, 7214.96392518038, 7220.783376498714, 7220.855582888352, 7223.89164162538, 2126.6916297136904, 2149.720536330275, 2151.9569608607817, 2154.2244791874914, 2156.094440335063, 2054.5479994401285, 2105.4841563816885, 2155.8490905636613, 2156.051337411534, 2158.967615485769, 2099.9265025724103, 2117.6188286108495, 2126.1070350452846, 2127.7233959585233, 2140.86084404514, 2097.4861159016064, 2104.0, 2104.6527322991687, 2106.2838634864142, 2106.73665791776, 2036.0837623069679, 2089.3105275259127, 2093.267356425254, 2093.769685334612, 2094.2984100326407, 7166.247544007856, 7177.7487787927175, 7286.74496392629, 7288.198298187195, 7291.234420385889, 4086.4891888513935, 4237.0, 4260.8721738347895, 4262.147570485884, 4269.466316710411, 2723.196656986194, 2844.587534807462, 2866.65600127986, 2889.5376739721755, 2890.3496713239565, 878.3587980774007, 884.7345796261096, 887.0508555845248, 891.919727224553, 897.1253028297423, 9429.858552121723, 9480.715578532656, 9499.810003799885, 9539.713808585753, 9541.522923853849, 3645.4167333226555, 3839.424086387064, 3957.3866050762176, 3977.6618987386396, 3978.144698889753, 7574.091109066947, 7583.165851756303, 7586.317231449196, 7593.962030189814, 7596.316331530189, 4232.957670423318, 4238.088810905609, 4239.427677263591, 4244.702870799076, 4250.638695710899, 3337.983310083434, 3749.3063783200196, 3869.5550011748446, 3936.0, 3937.409388591677, 3209.245827230599, 3209.5506629071947, 3341.7159541438764, 3347.0126312737802, 3347.6652334766327, 948.6347756113946, 951.7144856543009, 953.68051702679, 956.4548207521646, 965.9855102173473, 685.9245482996866, 705.9435245180373, 734.9669264883094, 764.7399884039453, 815.759350991459, 4615.361606452923, 4868.732219727878, 4942.777575009133, 4970.428400733889, 5129.692218466904, 396.5083296712056, 408.5036680433228, 411.22073670394434, 412.61420571765495, 412.9009037830899, 3520.9471857922154, 3534.752567320263, 3539.7876127432437, 3544.2557063017057, 3577.177249232639, 8408.957955210184, 8826.691065812794, 8869.246114080375, 8876.689315873913, 8881.777955551162, 8826.55867206637, 8831.161039701217, 8835.469871807583, 8849.60176792046, 8851.380403371702, 6532.150820393414, 6700.0, 6805.795826125224, 6808.08090907731, 6953.687084081127, 4830.855074347775, 5035.0685123252315, 5065.974670126626, 5103.132467480612, 5110.948890511196, 7833.804154896173, 7943.4836735612, 7976.84046319082, 8124.593770311579, 8156.755297341089, 7954.5624990623755, 8223.95888020556, 8229.506229626242, 8235.052968908412, 8241.299489543457, 6007.969960150346, 6532.0, 6674.065630811788, 6694.364035416627, 6694.430973367318, 6616.735330586723, 6843.555168914005, 6916.100906882073, 7068.3285087916565, 7070.186928503288, 6122.5408094392205, 6201.751929922752, 6310.6213627182515, 6404.0, 6421.518386120966, 6185.072239164161, 6228.844278893064, 6237.1891654084675, 6239.7504099837315, 6271.905921411274, 5775.162601422686, 5802.796902108406, 5822.417758224144, 5829.09649004405, 5844.824655260349, 4232.32282834745, 4299.785010749388, 4364.607185353333, 4465.687401881837, 4487.7209995151225, 4275.401443797933, 4350.58669426404, 4418.5802348776815, 4530.048689775184, 4537.047220083819], "value": 3771.599618490756, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.3385618567245, 308.1525804038893, 308.34476736934016, 315.2811589575767, 316.5868541553272, 4793.928091078632, 4821.541953514419, 4834.323194752737, 4860.9756951215195, 4865.221564549673, 4766.761661916899, 4768.380110585619, 4798.448178459478, 4799.7360145192, 4807.1587472192405, 3612.855485780572, 3656.945145822815, 3728.459373390857, 3729.776213427203, 3792.924141517168, 3785.71607129465, 3792.355299599075, 3793.696504279651, 3794.392897136459, 3796.9430458543143, 3747.381682022463, 3752.9812350938346, 3753.9249215015684, 3753.9812300938456, 3757.8538545743536, 3608.5489313835637, 3624.9456258156015, 3738.9904725724173, 3741.9438708419398, 3743.7192210584044, 3338.132085657722, 3340.131565792887, 3340.248444100058, 3340.5323254744353, 3361.46216605342, 4635.675502714811, 4668.346431499593, 4671.850948563665, 4691.202495575726, 4718.1127548980685, 4209.7474151551005, 4300.9139817203795, 4320.654347652212, 4331.805068771912, 4335.523092459858, 4985.501449855022, 4990.65065445419, 4995.725235112068, 5000.499950005007, 5016.949830501721, 3668.761530500509, 3673.338799016176, 3675.0, 3675.3016926784094, 3675.558932928039, 5080.212567052113, 5231.3199284092825, 5236.947630523684, 5243.981067289083, 5244.0, 3706.184639379332, 3736.682381997506, 3742.802303262984, 3751.3435148848844, 3762.698984081267, 3795.4686343911876, 3797.7911214883447, 3805.6194380561724, 3806.1816709407613, 3806.657400833938, 4269.2184753076135, 4278.850240241577, 4288.699791014599, 4290.678199135077, 4298.0, 4791.9055917149235, 4991.700497970133, 4993.625478089158, 4995.401471529149, 5006.0, 1290.993545032269, 1302.6678197059878, 1306.1640550047978, 1306.477409036393, 1308.5681725030718, 849.0066622052213, 860.6385318166385, 864.2178828160525, 865.6321063547967, 901.2294488212484, 4298.8280468780895, 4347.260965635849, 4348.28253338202, 4362.236608593484, 4389.846355377546, 5110.488951104934, 5116.641835071509, 5117.488251174853, 5121.539061484485, 5123.154679477844, 4243.915121697611, 4340.717853339523, 4349.826006959686, 4367.410399596078, 4384.0, 2705.499482595726, 2711.295063283521, 2716.619673245747, 2729.7816174706372, 2731.658542682165, 4816.277558366203, 4818.855434336975, 4821.7589120543835, 4837.758112094381, 4865.464798872121, 5075.87310317245, 5140.588752899759, 5216.591520289577, 5259.447757985433, 5260.421353651095, 4794.520547945178, 4819.903601928012, 4830.758462076882, 4830.903381932341, 4836.371271734652, 5127.589792816491, 5130.050940576006, 5131.512506311894, 5141.562967147835, 5142.37738753231, 4741.596964257938, 5029.396472423333, 5036.6726162799305, 5041.521055499722, 5057.342545469065, 4785.832495862628, 4791.520847915181, 4797.664163508521, 4799.1601469743355, 4808.711477311373, 3691.593924668338, 3704.68510176638, 3708.796016219134, 3711.5546134462957, 3728.70170386363, 4890.413150421972, 5055.797768089235, 5066.670666406671, 5067.189249720027, 5072.746362681925, 4913.975430122827, 4924.532169444041, 4930.556249937523, 4956.653034287565, 4968.4286307075145, 4916.360873086476, 4919.286703427912, 4943.802247910044, 4946.985867897, 4948.727819969936, 4734.905301894012, 4880.829170978998, 4890.364252647133, 4897.4857639947295, 4923.556879880828, 4885.9022819544125, 4982.551570358685, 5009.423916249678, 5073.695578265316, 5082.491750824888, 4984.675996060245, 4986.825461108844, 4990.476000019946, 4998.925231075336, 5004.823866391323], "value": 3787.7110956565652, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.5398999815151, 591.4174538079991, 620.8075496596056, 624.5128799536358, 711.9074520312358, 11599.65201043969, 12123.636290911276, 12232.877671223307, 12323.63029109126, 12457.128001039919, 12821.935890320536, 12946.611601651943, 12957.676058098552, 12960.54638087667, 12970.286634235114, 623.9126522286883, 754.7358424551418, 775.2673723331454, 777.4363586399843, 777.933875620573, 756.9545827250355, 779.8440311937635, 780.325018858686, 784.8783438567032, 785.6189747972257, 815.8816971539123, 817.5217497763815, 817.648411183191, 818.7871153500073, 819.1726356380069, 645.141961191614, 810.0036954545941, 811.6063709101081, 811.6915572082601, 812.1229072601606, 573.7246121861488, 589.3605438099667, 591.8579540910193, 594.0613830148355, 594.3313772006493, 10653.733656658567, 10657.946710266398, 10682.679519614425, 10731.624393146203, 10767.4616269187, 11998.280103193749, 12046.518139274418, 12568.371581420892, 12819.423125959354, 13000.219986800823, 10734.677959661145, 10819.459027048617, 10845.83731244032, 10875.836862446991, 10975.396353200571, 3933.6853051755793, 3957.0, 3965.147493288957, 4052.5136983561865, 4054.9594504054876, 61797.0, 61838.38161618371, 61938.38061619416, 62715.0, 62876.99999999954, 9493.810123797624, 9526.476043817518, 9666.0, 9901.653442129491, 9955.701328960144, 18804.529886752942, 18805.15376808047, 18839.152238149316, 18853.0, 18883.62232755337, 6260.373962603703, 6260.624362538262, 6339.049142628643, 6383.96808015957, 6465.709043093072, 3524.2775231077567, 3533.5759708835103, 3546.8935931922083, 3552.969638804769, 3607.657272559155, 2501.4621856300982, 2554.3358726731185, 2560.065576064723, 2565.7434256574193, 2565.9230223093336, 477.91158635652516, 484.1309848821374, 486.57424753340825, 491.9335889654922, 498.5787009976564, 465.13484918052393, 475.3772557949098, 475.6147520508399, 497.03327028928385, 544.0614939229876, 6943.756968506179, 6956.8956465653055, 7241.696494631017, 7274.16347120088, 7279.563226206445, 406.9776162311042, 419.96640268778424, 460.7028466638999, 489.1879480063078, 527.5710411098804, 3345.6654334566347, 3522.330757156132, 3656.5246517952496, 3686.4286035664513, 3700.9629903701157, 80075.19924800794, 96223.0, 96772.0, 98967.0, 99242.50378748204, 83256.58371708223, 83362.58318708367, 85272.14727852766, 86176.0, 86512.13487865166, 72042.91935620969, 72427.63786181035, 73169.26830731731, 77504.99999999886, 78449.21550784534, 6861.622610756257, 6952.756653517305, 6994.125734283215, 7012.824679383056, 7310.0, 45474.54525454703, 46638.0, 47888.0, 48095.75952120217, 48818.26772598485, 59428.0, 63123.248321880776, 65158.348416515226, 67980.0, 68426.65786671035, 8613.3109351253, 8960.551972401485, 9008.819823603622, 9116.179543841086, 9180.357374983687, 10494.108000820015, 10520.0, 10601.310914790822, 10942.124630029419, 11123.0, 9792.0, 9969.0, 10097.242706796873, 10174.440405777754, 10220.335678180894, 9880.567317738743, 9977.95231500682, 10011.699649010541, 10143.442110683687, 10206.081452669296, 11191.944040279746, 11356.205065645325, 11440.141989350666, 11937.761244775229, 11995.880041199476, 9502.429854208493, 9663.855042174513, 10068.144207742424, 10069.295149339476, 10069.647562335284, 9431.245500360092, 9493.762655933657, 9656.71029869105, 10034.448105354275, 10152.898471015194], "value": 6191.8833899669235, "unit": "score"}], "name": "dromaeo_css", "value": 6200.200609259542, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 113.0, 115.0, 114.0, 113.0, 113.0, 112.0, 112.0, 112.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 129.0, 122.0, 198.0, 128.0, 133.0, 154.0, 122.0, 162.0, 123.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 160.0, 161.0, 162.0, 161.0, 162.0, 161.0, 160.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 112.0, 77.0, 78.0, 75.0, 76.0, 100.0, 79.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 118.0, 86.0, 85.0, 86.0, 120.0, 85.0, 85.0, 125.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 116.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 146.0, 147.0, 145.0, 148.0, 147.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 94.0, 96.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 61.0, 60.0, 62.0, 61.0, 61.0, 61.0, 60.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 66.0, 67.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 80.0, 80.0, 79.0, 79.0, 89.0, 79.0, 87.0, 79.0, 80.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 131.0, 135.0, 132.0, 139.0, 127.0, 131.0, 142.0, 171.0, 130.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 173.0, 166.0, 166.0, 165.0, 167.0, 167.0, 162.0, 165.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 70.0, 69.0, 68.0, 66.0, 66.0, 69.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1531.8999999999999, "alertThreshold": 2.0}]} 22:01:32 INFO - Return code: 0 22:01:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:01:32 INFO - # TBPL SUCCESS # 22:01:32 INFO - Running post-action listener: _resource_record_post_action 22:01:32 INFO - [mozharness: 2016-07-28 05:01:32.506974Z] Finished run-tests step (success) 22:01:32 INFO - Running post-run listener: _resource_record_post_run 22:01:32 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 543334400; Read time: 16; Write time: 475352 22:01:32 INFO - TinderboxPrint: CPU usage
13.0% 22:01:32 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 22:01:32 INFO - TinderboxPrint: I/O write bytes / time
543,334,400 / 475,352 22:01:32 INFO - TinderboxPrint: CPU idle
8,320.2 (87.0%) 22:01:32 INFO - TinderboxPrint: CPU user
1,198.4 (12.5%) 22:01:32 INFO - TinderboxPrint: Swap in / out
0 / 0 22:01:32 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254115840; Read time: 0; Write time: 280440 22:01:32 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289218560; Read time: 16; Write time: 194912 22:01:33 INFO - Running post-run listener: _upload_blobber_files 22:01:33 INFO - Blob upload gear active. 22:01:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:01:33 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 22:01:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:01:33 INFO - (blobuploader) - INFO - Open directory for files ... 22:01:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:01:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:01:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:01:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:01:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:01:34 INFO - (blobuploader) - INFO - Done attempting. 22:01:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:01:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:01:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:01:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:01:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:01:35 INFO - (blobuploader) - INFO - Done attempting. 22:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:01:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:01:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:01:39 INFO - (blobuploader) - INFO - Done attempting. 22:01:39 INFO - (blobuploader) - INFO - Iteration through files over. 22:01:39 INFO - Return code: 0 22:01:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:01:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:01:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6a355d94905743f391085411731ce02b362495eb90032ac0b90c669dfee61f451b7d525d84dfd6acdb57a96e5427503d3a2ebba3cc62dc3b004d316907df4185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1eb2ca8301a8186bf0cb26e4bacc67c235404335d5c9e24efc75373f085cdf4d9be8af6ca198bdcc89b5ff0c765da02aa591c46aaff1dc591d65e32d235f915d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32f32f697665b4c4673f19d133c561c07f4020ecdbe66e1db43e3edc4e6ab86599bb09e0480efef2c39f55f970670be307ac4d826479bc5c86f44687c9af01ce"} 22:01:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:01:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:01:39 INFO - Contents: 22:01:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6a355d94905743f391085411731ce02b362495eb90032ac0b90c669dfee61f451b7d525d84dfd6acdb57a96e5427503d3a2ebba3cc62dc3b004d316907df4185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1eb2ca8301a8186bf0cb26e4bacc67c235404335d5c9e24efc75373f085cdf4d9be8af6ca198bdcc89b5ff0c765da02aa591c46aaff1dc591d65e32d235f915d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32f32f697665b4c4673f19d133c561c07f4020ecdbe66e1db43e3edc4e6ab86599bb09e0480efef2c39f55f970670be307ac4d826479bc5c86f44687c9af01ce"} 22:01:39 INFO - Running post-run listener: copy_logs_to_upload_dir 22:01:39 INFO - Copying logs to upload dir... 22:01:39 INFO - mkdir: /builds/slave/test/build/upload/logs 22:01:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.951018 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-07-27 22:01:39.186407) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 22:01:39.189831) ========= 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=0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6a355d94905743f391085411731ce02b362495eb90032ac0b90c669dfee61f451b7d525d84dfd6acdb57a96e5427503d3a2ebba3cc62dc3b004d316907df4185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1eb2ca8301a8186bf0cb26e4bacc67c235404335d5c9e24efc75373f085cdf4d9be8af6ca198bdcc89b5ff0c765da02aa591c46aaff1dc591d65e32d235f915d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32f32f697665b4c4673f19d133c561c07f4020ecdbe66e1db43e3edc4e6ab86599bb09e0480efef2c39f55f970670be307ac4d826479bc5c86f44687c9af01ce"} build_url:https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/cr-yNOtaR0ykau2alffB6g/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/6a355d94905743f391085411731ce02b362495eb90032ac0b90c669dfee61f451b7d525d84dfd6acdb57a96e5427503d3a2ebba3cc62dc3b004d316907df4185", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/1eb2ca8301a8186bf0cb26e4bacc67c235404335d5c9e24efc75373f085cdf4d9be8af6ca198bdcc89b5ff0c765da02aa591c46aaff1dc591d65e32d235f915d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/32f32f697665b4c4673f19d133c561c07f4020ecdbe66e1db43e3edc4e6ab86599bb09e0480efef2c39f55f970670be307ac4d826479bc5c86f44687c9af01ce"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-27 22:01:39.215413) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:01:39.215739) ========= 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=0333a256a5e4f052382dd180000001b1-1469680055.855929-544719303 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-27 22:01:39.299418) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 22:01:39.299735) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-27 22:01:39.300030) ========= ========= Total master_lag: 0.18 =========