builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1469774386.66 results: success (0) buildid: 20160728221524 builduid: bc03e12ea9174db4a1f80e4829665f29 revision: 154bf0638857a19e2b8164488d23c05d9f83de95 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.655650) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.656109) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.656380) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.670958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.671306) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-28 23:39:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.6M=0s 2016-07-28 23:39:47 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.138863 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.824004) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.824377) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017245 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:46.873684) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-28 23:39:46.874038) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 154bf0638857a19e2b8164488d23c05d9f83de95 --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 154bf0638857a19e2b8164488d23c05d9f83de95 --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=9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-28 23:39:47,257 truncating revision to first 12 chars 2016-07-28 23:39:47,257 Setting DEBUG logging. 2016-07-28 23:39:47,257 attempt 1/10 2016-07-28 23:39:47,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/154bf0638857?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-28 23:39:48,215 unpacking tar archive at: autoland-154bf0638857/testing/mozharness/ program finished with exit code 0 elapsedTime=1.287380 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-28 23:39:48.175853) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:48.176188) ========= script_repo_revision: 154bf0638857a19e2b8164488d23c05d9f83de95 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:48.176573) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:48.176843) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-28 23:39:48.185865) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-28 23:39:48.186256) ========= /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=9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:39:48 INFO - MultiFileLogger online at 20160728 23:39:48 in /builds/slave/test 23:39:48 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 23:39:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:39:48 INFO - {'append_to_log': False, 23:39:48 INFO - 'base_work_dir': '/builds/slave/test', 23:39:48 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 23:39:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:39:48 INFO - 'branch': 'Autoland-Non-PGO', 23:39:48 INFO - 'buildbot_json_path': 'buildprops.json', 23:39:48 INFO - 'config_files': ('talos/linux_config.py',), 23:39:48 INFO - 'default_actions': ('clobber', 23:39:48 INFO - 'read-buildbot-config', 23:39:48 INFO - 'download-and-extract', 23:39:48 INFO - 'populate-webroot', 23:39:48 INFO - 'create-virtualenv', 23:39:48 INFO - 'install', 23:39:48 INFO - 'run-tests'), 23:39:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:39:48 INFO - 'download_minidump_stackwalk': True, 23:39:48 INFO - 'download_symbols': 'ondemand', 23:39:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:39:48 INFO - 'tooltool.py': '/tools/tooltool.py', 23:39:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:39:48 INFO - '/tools/misc-python/virtualenv.py')}, 23:39:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:39:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:39:48 INFO - 'installer_path': 'installer.exe', 23:39:48 INFO - 'log_level': 'info', 23:39:48 INFO - 'log_name': 'talos', 23:39:48 INFO - 'log_to_console': True, 23:39:48 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:39:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:39:48 INFO - 'opt_config_files': (), 23:39:48 INFO - 'pip_index': False, 23:39:48 INFO - 'sps_profile': False, 23:39:48 INFO - 'sps_profile_interval': 0, 23:39:48 INFO - 'suite': 'dromaeojs', 23:39:48 INFO - 'system_bits': '32', 23:39:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:39:48 INFO - 'title': 'talos-linux64-ix-029', 23:39:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:39:48 INFO - 'use_talos_json': True, 23:39:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:39:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:39:48 INFO - 'work_dir': 'build'} 23:39:48 INFO - [mozharness: 2016-07-29 06:39:48.659619Z] Running clobber step. 23:39:48 INFO - Running pre-action listener: _resource_record_pre_action 23:39:48 INFO - Running main action method: clobber 23:39:48 INFO - rmtree: /builds/slave/test/build 23:39:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:39:49 INFO - Running post-action listener: _resource_record_post_action 23:39:49 INFO - [mozharness: 2016-07-29 06:39:49.129229Z] Finished clobber step (success) 23:39:49 INFO - [mozharness: 2016-07-29 06:39:49.129331Z] Running read-buildbot-config step. 23:39:49 INFO - Running pre-action listener: _resource_record_pre_action 23:39:49 INFO - Running main action method: read_buildbot_config 23:39:49 INFO - Using buildbot properties: 23:39:49 INFO - { 23:39:49 INFO - "project": "", 23:39:49 INFO - "product": "firefox", 23:39:49 INFO - "script_repo_revision": "production", 23:39:49 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 23:39:49 INFO - "repository": "", 23:39:49 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 23:39:49 INFO - "buildid": "20160728221524", 23:39:49 INFO - "pgo_build": "False", 23:39:49 INFO - "basedir": "/builds/slave/test", 23:39:49 INFO - "buildnumber": 266, 23:39:49 INFO - "slavename": "talos-linux64-ix-029", 23:39:49 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:39:49 INFO - "platform": "ubuntu64_hw", 23:39:49 INFO - "scheduler": "tests-autoland-linux64-talos", 23:39:49 INFO - "branch": "autoland", 23:39:49 INFO - "repo_path": "integration/autoland", 23:39:49 INFO - "revision": "154bf0638857a19e2b8164488d23c05d9f83de95", 23:39:49 INFO - "stage_platform": "linux64", 23:39:49 INFO - "builduid": "bc03e12ea9174db4a1f80e4829665f29", 23:39:49 INFO - "slavebuilddir": "test" 23:39:49 INFO - } 23:39:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:39:49 INFO - Running post-action listener: _resource_record_post_action 23:39:49 INFO - [mozharness: 2016-07-29 06:39:49.131780Z] Finished read-buildbot-config step (success) 23:39:49 INFO - [mozharness: 2016-07-29 06:39:49.131870Z] Running download-and-extract step. 23:39:49 INFO - Running pre-action listener: _resource_record_pre_action 23:39:49 INFO - Running main action method: download_and_extract 23:39:49 INFO - mkdir: /builds/slave/test/build/tests 23:39:49 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')]} 23:39:49 INFO - https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:39:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:39:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:39:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:49 INFO - Downloaded 1361 bytes. 23:39:49 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:39:49 INFO - Using the following test package requirements: 23:39:49 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:39:49 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:39:49 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:39:49 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'jsshell-linux-x86_64.zip'], 23:39:49 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:39:49 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:39:49 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:39:49 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:39:49 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:39:49 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:39:49 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:39:49 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:39:49 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')]} 23:39:49 INFO - https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:39:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:39:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:39:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:51 INFO - Downloaded 22749930 bytes. 23:39:51 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'] 23:39:51 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 23:39:52 INFO - Return code: 0 23:39:52 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 23:39:52 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')]} 23:39:52 INFO - https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:39:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:39:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:39:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:53 INFO - Downloaded 22749930 bytes. 23:39:53 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'] 23:39:53 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 23:39:55 INFO - Return code: 0 23:39:55 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')]} 23:39:55 INFO - https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:39:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:39:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:39:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:55 INFO - Downloaded 14413725 bytes. 23:39:55 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'] 23:39:55 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 23:39:56 INFO - Return code: 0 23:39:56 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')]} 23:39:56 INFO - https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:39:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:39:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:39:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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 23:39:57 INFO - Downloaded 57165525 bytes. 23:39:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:39:57 INFO - mkdir: /builds/slave/test/properties 23:39:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:39:57 INFO - Writing to file /builds/slave/test/properties/build_url 23:39:57 INFO - Contents: 23:39:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:39:57 INFO - Running post-action listener: _resource_record_post_action 23:39:57 INFO - Running post-action listener: set_extra_try_arguments 23:39:57 INFO - [mozharness: 2016-07-29 06:39:57.997470Z] Finished download-and-extract step (success) 23:39:57 INFO - [mozharness: 2016-07-29 06:39:57.997704Z] Running populate-webroot step. 23:39:57 INFO - Running pre-action listener: _resource_record_pre_action 23:39:57 INFO - Running main action method: populate_webroot 23:39:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:39:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:39:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:39:58 INFO - '--tppagecycles', 23:39:58 INFO - '7'], 23:39:58 INFO - 'tests': ['tsvgm']}}, 23:39:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:39:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:39:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:39:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:39:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:39:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:39:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['damp', 'tps']}, 23:39:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'tests': ['damp', 'tps']}, 23:39:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['dromaeo_dom']}, 23:39:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:39:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['basic_compositor_video']}, 23:39:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:39:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['a11yr', 23:39:58 INFO - 'ts_paint', 23:39:58 INFO - 'tpaint', 23:39:58 INFO - 'sessionrestore', 23:39:58 INFO - 'sessionrestore_no_auto_restore', 23:39:58 INFO - 'tabpaint']}, 23:39:58 INFO - 'other-e10s': {'tests': ['a11yr', 23:39:58 INFO - 'ts_paint', 23:39:58 INFO - 'tpaint', 23:39:58 INFO - 'sessionrestore', 23:39:58 INFO - 'sessionrestore_no_auto_restore', 23:39:58 INFO - 'tabpaint']}, 23:39:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:39:58 INFO - 'ts_paint', 23:39:58 INFO - 'tpaint', 23:39:58 INFO - 'sessionrestore', 23:39:58 INFO - 'sessionrestore_no_auto_restore', 23:39:58 INFO - 'tabpaint']}, 23:39:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:39:58 INFO - 'ts_paint', 23:39:58 INFO - 'tpaint', 23:39:58 INFO - 'sessionrestore', 23:39:58 INFO - 'sessionrestore_no_auto_restore', 23:39:58 INFO - 'tabpaint']}, 23:39:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['a11yr', 23:39:58 INFO - 'ts_paint', 23:39:58 INFO - 'tpaint', 23:39:58 INFO - 'sessionrestore', 23:39:58 INFO - 'sessionrestore_no_auto_restore', 23:39:58 INFO - 'tabpaint']}, 23:39:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['a11yr', 23:39:58 INFO - 'ts_paint', 23:39:58 INFO - 'tpaint', 23:39:58 INFO - 'sessionrestore', 23:39:58 INFO - 'sessionrestore_no_auto_restore', 23:39:58 INFO - 'tabpaint']}, 23:39:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['tsvgx', 23:39:58 INFO - 'tsvgr_opacity', 23:39:58 INFO - 'tart', 23:39:58 INFO - 'tscrollx', 23:39:58 INFO - 'cart']}, 23:39:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:39:58 INFO - 'tsvgr_opacity', 23:39:58 INFO - 'tart', 23:39:58 INFO - 'tscrollx', 23:39:58 INFO - 'cart']}, 23:39:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'talos_options': ['--disable-e10s'], 23:39:58 INFO - 'tests': ['tp5o']}, 23:39:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'tests': ['tp5o']}, 23:39:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'talos_options': ['--disable-e10s', 23:39:58 INFO - '--xperf_path', 23:39:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:39:58 INFO - 'tests': ['tp5n']}, 23:39:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:39:58 INFO - 'talos_options': ['--xperf_path', 23:39:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:39:58 INFO - 'tests': ['tp5n']}}, 23:39:58 INFO - 'talos.zip': {'path': '', 23:39:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:39:58 INFO - Running post-action listener: _resource_record_post_action 23:39:58 INFO - [mozharness: 2016-07-29 06:39:58.014582Z] Finished populate-webroot step (success) 23:39:58 INFO - [mozharness: 2016-07-29 06:39:58.014671Z] Running create-virtualenv step. 23:39:58 INFO - Running pre-action listener: _resource_record_pre_action 23:39:58 INFO - Running main action method: create_virtualenv 23:39:58 INFO - Creating virtualenv /builds/slave/test/build/venv 23:39:58 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 23:39:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:39:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:39:58 INFO - Using real prefix '/usr' 23:39:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:39:58 INFO - Installing distribute.............................................................................................................................................................................................done. 23:40:00 INFO - Installing pip.................done. 23:40:00 INFO - Return code: 0 23:40:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:00 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:00 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 23:40:00 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 23:40:00 INFO - Using env: {'DISPLAY': ':0', 23:40:00 INFO - 'HOME': '/home/cltbld', 23:40:00 INFO - 'LANG': 'en_US.UTF-8', 23:40:00 INFO - 'LANGUAGE': 'en_US:en', 23:40:00 INFO - 'LOGNAME': 'cltbld', 23:40:00 INFO - 'MAIL': '/var/mail/cltbld', 23:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:00 INFO - 'MOZ_NO_REMOTE': '1', 23:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:00 INFO - 'NO_EM_RESTART': '1', 23:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:00 INFO - 'PWD': '/builds/slave/test', 23:40:00 INFO - 'SHELL': '/bin/bash', 23:40:00 INFO - 'SHLVL': '1', 23:40:00 INFO - 'TERM': 'linux', 23:40:00 INFO - 'TMOUT': '86400', 23:40:00 INFO - 'USER': 'cltbld', 23:40:00 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:00 INFO - '_': '/tools/buildbot/bin/python'} 23:40:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:00 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 23:40:00 INFO - Cleaning up... 23:40:00 INFO - Return code: 0 23:40:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:00 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:00 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 23:40:00 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 23:40:00 INFO - Using env: {'DISPLAY': ':0', 23:40:00 INFO - 'HOME': '/home/cltbld', 23:40:00 INFO - 'LANG': 'en_US.UTF-8', 23:40:00 INFO - 'LANGUAGE': 'en_US:en', 23:40:00 INFO - 'LOGNAME': 'cltbld', 23:40:00 INFO - 'MAIL': '/var/mail/cltbld', 23:40:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:00 INFO - 'MOZ_NO_REMOTE': '1', 23:40:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:00 INFO - 'NO_EM_RESTART': '1', 23:40:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:00 INFO - 'PWD': '/builds/slave/test', 23:40:00 INFO - 'SHELL': '/bin/bash', 23:40:00 INFO - 'SHLVL': '1', 23:40:00 INFO - 'TERM': 'linux', 23:40:00 INFO - 'TMOUT': '86400', 23:40:00 INFO - 'USER': 'cltbld', 23:40:00 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:00 INFO - '_': '/tools/buildbot/bin/python'} 23:40:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:01 INFO - Downloading/unpacking psutil>=3.1.1 23:40:01 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 23:40:01 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 23:40:01 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 23:40:01 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 23:40:01 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 23:40:01 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 23:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:40:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:40:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:40:03 INFO - Installing collected packages: psutil 23:40:03 INFO - Running setup.py install for psutil 23:40:03 INFO - building 'psutil._psutil_linux' extension 23:40:03 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 23:40:03 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 23:40:03 INFO - building 'psutil._psutil_posix' extension 23:40:03 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 23:40:03 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 23:40:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:40:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:40:03 INFO - Successfully installed psutil 23:40:03 INFO - Cleaning up... 23:40:03 INFO - Return code: 0 23:40:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:03 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:03 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 23:40:03 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 23:40:03 INFO - Using env: {'DISPLAY': ':0', 23:40:03 INFO - 'HOME': '/home/cltbld', 23:40:03 INFO - 'LANG': 'en_US.UTF-8', 23:40:03 INFO - 'LANGUAGE': 'en_US:en', 23:40:03 INFO - 'LOGNAME': 'cltbld', 23:40:03 INFO - 'MAIL': '/var/mail/cltbld', 23:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:03 INFO - 'MOZ_NO_REMOTE': '1', 23:40:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:03 INFO - 'NO_EM_RESTART': '1', 23:40:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:03 INFO - 'PWD': '/builds/slave/test', 23:40:03 INFO - 'SHELL': '/bin/bash', 23:40:03 INFO - 'SHLVL': '1', 23:40:03 INFO - 'TERM': 'linux', 23:40:03 INFO - 'TMOUT': '86400', 23:40:03 INFO - 'USER': 'cltbld', 23:40:03 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:03 INFO - '_': '/tools/buildbot/bin/python'} 23:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:40:03 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 23:40:03 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 23:40:03 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 23:40:03 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 23:40:03 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 23:40:03 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 23:40:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:40:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:40:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:40:05 INFO - Installing collected packages: mozsystemmonitor 23:40:05 INFO - Running setup.py install for mozsystemmonitor 23:40:05 INFO - Successfully installed mozsystemmonitor 23:40:05 INFO - Cleaning up... 23:40:05 INFO - Return code: 0 23:40:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 23:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 23:40:06 INFO - Using env: {'DISPLAY': ':0', 23:40:06 INFO - 'HOME': '/home/cltbld', 23:40:06 INFO - 'LANG': 'en_US.UTF-8', 23:40:06 INFO - 'LANGUAGE': 'en_US:en', 23:40:06 INFO - 'LOGNAME': 'cltbld', 23:40:06 INFO - 'MAIL': '/var/mail/cltbld', 23:40:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:06 INFO - 'MOZ_NO_REMOTE': '1', 23:40:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:06 INFO - 'NO_EM_RESTART': '1', 23:40:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:06 INFO - 'PWD': '/builds/slave/test', 23:40:06 INFO - 'SHELL': '/bin/bash', 23:40:06 INFO - 'SHLVL': '1', 23:40:06 INFO - 'TERM': 'linux', 23:40:06 INFO - 'TMOUT': '86400', 23:40:06 INFO - 'USER': 'cltbld', 23:40:06 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:06 INFO - '_': '/tools/buildbot/bin/python'} 23:40:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:06 INFO - Downloading/unpacking jsonschema==2.5.1 23:40:06 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 23:40:06 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 23:40:06 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 23:40:06 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 23:40:06 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 23:40:06 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 23:40:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:40:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:08 INFO - Downloading functools32-3.2.3-2.tar.gz 23:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:40:08 INFO - warning: no files found matching '*.txt' 23:40:08 INFO - no previously-included directories found matching 'build' 23:40:08 INFO - no previously-included directories found matching 'dist' 23:40:08 INFO - no previously-included directories found matching '.git*' 23:40:08 INFO - Installing collected packages: jsonschema, functools32 23:40:08 INFO - Running setup.py install for functools32 23:40:08 INFO - warning: no files found matching '*.txt' 23:40:08 INFO - no previously-included directories found matching 'build' 23:40:08 INFO - no previously-included directories found matching 'dist' 23:40:08 INFO - no previously-included directories found matching '.git*' 23:40:08 INFO - Successfully installed jsonschema functools32 23:40:08 INFO - Cleaning up... 23:40:08 INFO - Return code: 0 23:40:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:40:08 INFO - Using env: {'DISPLAY': ':0', 23:40:08 INFO - 'HOME': '/home/cltbld', 23:40:08 INFO - 'LANG': 'en_US.UTF-8', 23:40:08 INFO - 'LANGUAGE': 'en_US:en', 23:40:08 INFO - 'LOGNAME': 'cltbld', 23:40:08 INFO - 'MAIL': '/var/mail/cltbld', 23:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:08 INFO - 'MOZ_NO_REMOTE': '1', 23:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:08 INFO - 'NO_EM_RESTART': '1', 23:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:08 INFO - 'PWD': '/builds/slave/test', 23:40:08 INFO - 'SHELL': '/bin/bash', 23:40:08 INFO - 'SHLVL': '1', 23:40:08 INFO - 'TERM': 'linux', 23:40:08 INFO - 'TMOUT': '86400', 23:40:08 INFO - 'USER': 'cltbld', 23:40:08 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:08 INFO - '_': '/tools/buildbot/bin/python'} 23:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:40:08 INFO - Cleaning up... 23:40:08 INFO - Return code: 0 23:40:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:40:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:40:08 INFO - Using env: {'DISPLAY': ':0', 23:40:08 INFO - 'HOME': '/home/cltbld', 23:40:08 INFO - 'LANG': 'en_US.UTF-8', 23:40:08 INFO - 'LANGUAGE': 'en_US:en', 23:40:08 INFO - 'LOGNAME': 'cltbld', 23:40:08 INFO - 'MAIL': '/var/mail/cltbld', 23:40:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:08 INFO - 'MOZ_NO_REMOTE': '1', 23:40:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:08 INFO - 'NO_EM_RESTART': '1', 23:40:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:08 INFO - 'PWD': '/builds/slave/test', 23:40:08 INFO - 'SHELL': '/bin/bash', 23:40:08 INFO - 'SHLVL': '1', 23:40:08 INFO - 'TERM': 'linux', 23:40:08 INFO - 'TMOUT': '86400', 23:40:08 INFO - 'USER': 'cltbld', 23:40:08 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:08 INFO - '_': '/tools/buildbot/bin/python'} 23:40:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:08 INFO - Downloading/unpacking blobuploader==1.2.4 23:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:10 INFO - Downloading blobuploader-1.2.4.tar.gz 23:40:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:40:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:40:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 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 23:40:11 INFO - Downloading docopt-0.6.1.tar.gz 23:40:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:40:11 INFO - Installing collected packages: blobuploader, requests, docopt 23:40:11 INFO - Running setup.py install for blobuploader 23:40:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:40:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:40:11 INFO - Running setup.py install for requests 23:40:12 INFO - Running setup.py install for docopt 23:40:12 INFO - Successfully installed blobuploader requests docopt 23:40:12 INFO - Cleaning up... 23:40:12 INFO - Return code: 0 23:40:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:40:12 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')]} 23:40:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:12 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')]} 23:40:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:12 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:12 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 23:40:12 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 23:40:12 INFO - Using env: {'DISPLAY': ':0', 23:40:12 INFO - 'HOME': '/home/cltbld', 23:40:12 INFO - 'LANG': 'en_US.UTF-8', 23:40:12 INFO - 'LANGUAGE': 'en_US:en', 23:40:12 INFO - 'LOGNAME': 'cltbld', 23:40:12 INFO - 'MAIL': '/var/mail/cltbld', 23:40:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:12 INFO - 'MOZ_NO_REMOTE': '1', 23:40:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:12 INFO - 'NO_EM_RESTART': '1', 23:40:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:12 INFO - 'PWD': '/builds/slave/test', 23:40:12 INFO - 'SHELL': '/bin/bash', 23:40:12 INFO - 'SHLVL': '1', 23:40:12 INFO - 'TERM': 'linux', 23:40:12 INFO - 'TMOUT': '86400', 23:40:12 INFO - 'USER': 'cltbld', 23:40:12 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:12 INFO - '_': '/tools/buildbot/bin/python'} 23:40:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:40:12 INFO - Running setup.py (path:/tmp/pip-gcx9hH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:40:12 INFO - Running setup.py (path:/tmp/pip-A4rIos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:40:12 INFO - Running setup.py (path:/tmp/pip-hkd4uV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:40:12 INFO - Running setup.py (path:/tmp/pip-NXb0Iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:40:12 INFO - Running setup.py (path:/tmp/pip-HECMhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:40:12 INFO - Running setup.py (path:/tmp/pip-EDiB0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:40:12 INFO - Running setup.py (path:/tmp/pip-FeqZvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:40:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:40:12 INFO - Running setup.py (path:/tmp/pip-0zz3zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:40:13 INFO - Running setup.py (path:/tmp/pip-zEQX2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:40:13 INFO - Running setup.py (path:/tmp/pip-mUGqvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:40:13 INFO - Running setup.py (path:/tmp/pip-Uv0cYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:40:13 INFO - Running setup.py (path:/tmp/pip-paWi4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:40:13 INFO - Running setup.py (path:/tmp/pip-RRGzGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:40:13 INFO - Running setup.py (path:/tmp/pip-aSAVjG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:13 INFO - Running setup.py (path:/tmp/pip-NFswSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:40:13 INFO - Running setup.py (path:/tmp/pip-HmuShC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:40:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:40:13 INFO - Running setup.py (path:/tmp/pip-cSlSmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:40:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:40:13 INFO - Running setup.py install for manifestparser 23:40:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:40:13 INFO - Running setup.py install for mozcrash 23:40:13 INFO - Running setup.py install for mozdebug 23:40:13 INFO - Running setup.py install for mozdevice 23:40:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for mozfile 23:40:14 INFO - Running setup.py install for mozhttpd 23:40:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for mozinfo 23:40:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for mozInstall 23:40:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for mozleak 23:40:14 INFO - Running setup.py install for mozlog 23:40:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for moznetwork 23:40:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for mozprocess 23:40:14 INFO - Running setup.py install for mozprofile 23:40:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:40:14 INFO - Running setup.py install for mozrunner 23:40:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:40:15 INFO - Running setup.py install for mozscreenshot 23:40:15 INFO - Running setup.py install for moztest 23:40:15 INFO - Running setup.py install for mozversion 23:40:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:40:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:40:15 INFO - Cleaning up... 23:40:15 INFO - Return code: 0 23:40:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:40: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')]} 23:40:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40: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')]} 23:40:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:15 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:15 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 23:40:15 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 23:40:15 INFO - Using env: {'DISPLAY': ':0', 23:40:15 INFO - 'HOME': '/home/cltbld', 23:40:15 INFO - 'LANG': 'en_US.UTF-8', 23:40:15 INFO - 'LANGUAGE': 'en_US:en', 23:40:15 INFO - 'LOGNAME': 'cltbld', 23:40:15 INFO - 'MAIL': '/var/mail/cltbld', 23:40:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:15 INFO - 'MOZ_NO_REMOTE': '1', 23:40:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:15 INFO - 'NO_EM_RESTART': '1', 23:40:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:15 INFO - 'PWD': '/builds/slave/test', 23:40:15 INFO - 'SHELL': '/bin/bash', 23:40:15 INFO - 'SHLVL': '1', 23:40:15 INFO - 'TERM': 'linux', 23:40:15 INFO - 'TMOUT': '86400', 23:40:15 INFO - 'USER': 'cltbld', 23:40:15 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:15 INFO - '_': '/tools/buildbot/bin/python'} 23:40:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:40:15 INFO - Running setup.py (path:/tmp/pip-XY3qaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:40:15 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)) 23:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:40:15 INFO - Running setup.py (path:/tmp/pip-5jdpVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:40:15 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)) 23:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:40:15 INFO - Running setup.py (path:/tmp/pip-QLnm0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:40:15 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)) 23:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:40:15 INFO - Running setup.py (path:/tmp/pip-7rk_Ha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:40:15 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)) 23:40:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:40:15 INFO - Running setup.py (path:/tmp/pip-qdcSlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:40:16 INFO - Running setup.py (path:/tmp/pip-k5Cyrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:40:16 INFO - Running setup.py (path:/tmp/pip-BflhZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:40:16 INFO - Running setup.py (path:/tmp/pip-mFq53Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:40:16 INFO - Running setup.py (path:/tmp/pip-Cj6jLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:40:16 INFO - Running setup.py (path:/tmp/pip-VFeP8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:40:16 INFO - Running setup.py (path:/tmp/pip-0e1m4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:40:16 INFO - Running setup.py (path:/tmp/pip-kFq6Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:40:16 INFO - Running setup.py (path:/tmp/pip-jCJyFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:40:16 INFO - Running setup.py (path:/tmp/pip-3h9LWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:16 INFO - Running setup.py (path:/tmp/pip-MjGHkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:40:16 INFO - Running setup.py (path:/tmp/pip-Dk46en-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:40:16 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)) 23:40:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:40:16 INFO - Running setup.py (path:/tmp/pip-xG79yX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:40:16 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)) 23:40:16 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)) 23:40:16 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)) 23:40:16 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)) 23:40:16 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)) 23:40:16 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)) 23:40:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:40:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:40:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:40:18 INFO - Downloading blessings-1.6.tar.gz 23:40:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:40:19 INFO - Installing collected packages: blessings 23:40:19 INFO - Running setup.py install for blessings 23:40:19 INFO - Successfully installed blessings 23:40:19 INFO - Cleaning up... 23:40:19 INFO - Return code: 0 23:40:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:40:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:40:19 INFO - Reading from file tmpfile_stdout 23:40:19 INFO - Current package versions: 23:40:19 INFO - argparse == 1.2.1 23:40:19 INFO - blessings == 1.6 23:40:19 INFO - blobuploader == 1.2.4 23:40:19 INFO - docopt == 0.6.1 23:40:19 INFO - functools32 == 3.2.3-2 23:40:19 INFO - jsonschema == 2.5.1 23:40:19 INFO - manifestparser == 1.1 23:40:19 INFO - mozInstall == 1.12 23:40:19 INFO - mozcrash == 0.17 23:40:19 INFO - mozdebug == 0.1 23:40:19 INFO - mozdevice == 0.48 23:40:19 INFO - mozfile == 1.2 23:40:19 INFO - mozhttpd == 0.7 23:40:19 INFO - mozinfo == 0.9 23:40:19 INFO - mozleak == 0.1 23:40:19 INFO - mozlog == 3.3 23:40:19 INFO - moznetwork == 0.27 23:40:19 INFO - mozprocess == 0.23 23:40:19 INFO - mozprofile == 0.28 23:40:19 INFO - mozrunner == 6.12 23:40:19 INFO - mozscreenshot == 0.1 23:40:19 INFO - mozsystemmonitor == 0.3 23:40:19 INFO - moztest == 0.7 23:40:19 INFO - mozversion == 1.4 23:40:19 INFO - psutil == 3.1.1 23:40:19 INFO - requests == 1.2.3 23:40:19 INFO - wsgiref == 0.1.2 23:40:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:40:19 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')]} 23:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:19 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')]} 23:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:19 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:19 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 23:40:19 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 23:40:19 INFO - Using env: {'DISPLAY': ':0', 23:40:19 INFO - 'HOME': '/home/cltbld', 23:40:19 INFO - 'LANG': 'en_US.UTF-8', 23:40:19 INFO - 'LANGUAGE': 'en_US:en', 23:40:19 INFO - 'LOGNAME': 'cltbld', 23:40:19 INFO - 'MAIL': '/var/mail/cltbld', 23:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:19 INFO - 'MOZ_NO_REMOTE': '1', 23:40:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:19 INFO - 'NO_EM_RESTART': '1', 23:40:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:19 INFO - 'PWD': '/builds/slave/test', 23:40:19 INFO - 'SHELL': '/bin/bash', 23:40:19 INFO - 'SHLVL': '1', 23:40:19 INFO - 'TERM': 'linux', 23:40:19 INFO - 'TMOUT': '86400', 23:40:19 INFO - 'USER': 'cltbld', 23:40:19 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:19 INFO - '_': '/tools/buildbot/bin/python'} 23:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 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)) 23:40:19 INFO - Cleaning up... 23:40:19 INFO - Return code: 0 23:40:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:40:19 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')]} 23:40:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:40:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:19 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')]} 23:40:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:40:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:40:19 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 0x279c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x28ff8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a5d180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', '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 23:40:19 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 23:40:19 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 23:40:19 INFO - Using env: {'DISPLAY': ':0', 23:40:19 INFO - 'HOME': '/home/cltbld', 23:40:19 INFO - 'LANG': 'en_US.UTF-8', 23:40:19 INFO - 'LANGUAGE': 'en_US:en', 23:40:19 INFO - 'LOGNAME': 'cltbld', 23:40:19 INFO - 'MAIL': '/var/mail/cltbld', 23:40:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:19 INFO - 'MOZ_NO_REMOTE': '1', 23:40:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:19 INFO - 'NO_EM_RESTART': '1', 23:40:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:19 INFO - 'PWD': '/builds/slave/test', 23:40:19 INFO - 'SHELL': '/bin/bash', 23:40:19 INFO - 'SHLVL': '1', 23:40:19 INFO - 'TERM': 'linux', 23:40:19 INFO - 'TMOUT': '86400', 23:40:19 INFO - 'USER': 'cltbld', 23:40:19 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:19 INFO - '_': '/tools/buildbot/bin/python'} 23:40:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:40:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:40:19 INFO - Cleaning up... 23:40:19 INFO - Return code: 0 23:40:19 INFO - Running post-action listener: _resource_record_post_action 23:40:19 INFO - Running post-action listener: _start_resource_monitoring 23:40:19 INFO - Starting resource monitoring. 23:40:19 INFO - [mozharness: 2016-07-29 06:40:19.910622Z] Finished create-virtualenv step (success) 23:40:19 INFO - [mozharness: 2016-07-29 06:40:19.911046Z] Running install step. 23:40:19 INFO - Running pre-action listener: _resource_record_pre_action 23:40:19 INFO - Running main action method: install 23:40:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:40:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:40:20 INFO - Reading from file tmpfile_stdout 23:40:20 INFO - Detecting whether we're running mozinstall >=1.0... 23:40:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:40:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:40:20 INFO - Reading from file tmpfile_stdout 23:40:20 INFO - Output received: 23:40:20 INFO - Usage: mozinstall [options] installer 23:40:20 INFO - Options: 23:40:20 INFO - -h, --help show this help message and exit 23:40:20 INFO - -d DEST, --destination=DEST 23:40:20 INFO - Directory to install application into. [default: 23:40:20 INFO - "/builds/slave/test"] 23:40:20 INFO - --app=APP Application being installed. [default: firefox] 23:40:20 INFO - mkdir: /builds/slave/test/build/application 23:40:20 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'] 23:40:20 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 23:40:36 INFO - Reading from file tmpfile_stdout 23:40:36 INFO - Output received: 23:40:36 INFO - /builds/slave/test/build/application/firefox/firefox 23:40:36 INFO - Running post-action listener: _resource_record_post_action 23:40:36 INFO - [mozharness: 2016-07-29 06:40:36.620004Z] Finished install step (success) 23:40:36 INFO - [mozharness: 2016-07-29 06:40:36.620281Z] Running run-tests step. 23:40:36 INFO - Running pre-action listener: _resource_record_pre_action 23:40:36 INFO - Running main action method: run_tests 23:40:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:40:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:40:36 INFO - Python 2.7.3 23:40:36 INFO - Return code: 0 23:40:36 INFO - grabbing minidump binary from tooltool 23:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:40:36 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 0x2a5b3b0>, '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 0x2a5c2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a5c740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:40:36 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 23:40:36 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 23:40:36 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 23:40:36 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:40:36 INFO - Return code: 0 23:40:36 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:40:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:40:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:40:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:40:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:40:36 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/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 23:40:36 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/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 23:40:36 INFO - Using env: {'DISPLAY': ':0', 23:40:36 INFO - 'HOME': '/home/cltbld', 23:40:36 INFO - 'LANG': 'en_US.UTF-8', 23:40:36 INFO - 'LANGUAGE': 'en_US:en', 23:40:36 INFO - 'LOGNAME': 'cltbld', 23:40:36 INFO - 'MAIL': '/var/mail/cltbld', 23:40:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:40:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:40:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:40:36 INFO - 'MOZ_NO_REMOTE': '1', 23:40:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:40:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:40:36 INFO - 'NO_EM_RESTART': '1', 23:40:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:40:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:40:36 INFO - 'PWD': '/builds/slave/test', 23:40:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:40:36 INFO - 'SHELL': '/bin/bash', 23:40:36 INFO - 'SHLVL': '1', 23:40:36 INFO - 'TERM': 'linux', 23:40:36 INFO - 'TMOUT': '86400', 23:40:36 INFO - 'USER': 'cltbld', 23:40:36 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354', 23:40:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:40:36 INFO - '_': '/tools/buildbot/bin/python'} 23:40:36 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/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 23:40:36 INFO - mozversion application_buildid: 20160728221524 23:40:36 INFO - mozversion application_changeset: 154bf0638857a19e2b8164488d23c05d9f83de95 23:40:36 INFO - mozversion application_display_name: Nightly 23:40:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:40:36 INFO - mozversion application_name: Firefox 23:40:36 INFO - mozversion application_remotingname: firefox 23:40:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 23:40:36 INFO - mozversion application_vendor: Mozilla 23:40:36 INFO - mozversion application_version: 50.0a1 23:40:36 INFO - mozversion platform_buildid: 20160728221524 23:40:36 INFO - mozversion platform_changeset: 154bf0638857a19e2b8164488d23c05d9f83de95 23:40:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 23:40:36 INFO - mozversion platform_version: 50.0a1 23:40:36 INFO - using testdate: 1469774436 23:40:36 INFO - actual date: 1469774436 23:40:36 INFO - starting webserver on 'localhost:56863' 23:40:36 INFO - SUITE-START | Running 2 tests 23:40:36 INFO - TEST-START | dromaeo_css 23:40:36 INFO - operating with platform_type : linux_ 23:40:36 INFO - Initialising browser for dromaeo_css test... 23:40:36 INFO - TEST-INFO | started process 12555 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3zg7Hf/profile http://localhost:56863/getInfo.html) 23:40:40 INFO - PROCESS | 12555 | JavaScript error: , line 0: uncaught exception: undefined 23:40:41 INFO - PROCESS | 12555 | __metrics Screen width/height:1600/1200 23:40:41 INFO - PROCESS | 12555 | colorDepth:24 23:40:41 INFO - PROCESS | 12555 | Browser inner width/height: 1024/697 23:40:41 INFO - PROCESS | 12555 | __metrics 23:40:42 INFO - PROCESS | 12555 | 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" 23:40:42 INFO - PROCESS | 12555 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:40:42 INFO - PROCESS | 12555 | console.error: PushService: 23:40:42 INFO - PROCESS | 12555 | stateChangeProcessEnqueue: Error transitioning state 23:40:42 INFO - PROCESS | 12555 | UnknownError 23:40:43 INFO - TEST-INFO | 12555: exit 0 23:40:43 INFO - Browser initialized. 23:40:43 INFO - Running cycle 1/1 for dromaeo_css test... 23:40:43 INFO - TEST-INFO | started process 12635 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3zg7Hf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:40:55 INFO - PROCESS | 12635 | RSS: Main: 159301632 23:40:55 INFO - PROCESS | 12635 | 23:41:46 INFO - PROCESS | 12635 | 1469774506014 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] 23:43:59 INFO - PROCESS | 12635 | Cycle 1(1): loaded http://localhost:56863/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56863/tests/dromaeo/cssquery-ext.html) 23:44:00 INFO - PROCESS | 12635 | RSS: Main: 275775488 23:44:00 INFO - PROCESS | 12635 | 23:47:03 INFO - PROCESS | 12635 | Cycle 1(1): loaded http://localhost:56863/tests/dromaeo/cssquery-ext.html (next: http://localhost:56863/tests/dromaeo/cssquery-jquery.html) 23:47:03 INFO - PROCESS | 12635 | RSS: Main: 245645312 23:47:03 INFO - PROCESS | 12635 | 23:50:06 INFO - PROCESS | 12635 | Cycle 1(1): loaded http://localhost:56863/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56863/tests/dromaeo/cssquery-mootools.html) 23:50:06 INFO - PROCESS | 12635 | RSS: Main: 190861312 23:50:06 INFO - PROCESS | 12635 | 23:53:09 INFO - PROCESS | 12635 | Cycle 1(1): loaded http://localhost:56863/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56863/tests/dromaeo/cssquery-prototype.html) 23:53:09 INFO - PROCESS | 12635 | RSS: Main: 190324736 23:53:09 INFO - PROCESS | 12635 | 23:56:12 INFO - PROCESS | 12635 | Cycle 1(1): loaded http://localhost:56863/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56863/tests/dromaeo/cssquery-yui.html) 23:56:13 INFO - PROCESS | 12635 | RSS: Main: 191275008 23:56:13 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | Cycle 1(1): loaded http://localhost:56863/tests/dromaeo/cssquery-yui.html (next: http://localhost:56863/tests/dromaeo/cssquery-dojo.html) 23:59:16 INFO - PROCESS | 12635 | RSS: Main: 280465408 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | __start_tp_report 23:59:16 INFO - PROCESS | 12635 | _x_x_mozilla_page_load 23:59:16 INFO - PROCESS | 12635 | _x_x_mozilla_page_load_details 23:59:16 INFO - PROCESS | 12635 | |i|pagename|runs| 23:59:16 INFO - PROCESS | 12635 | |0;dojo.html;2641.96037059444;2646.536856050191;2676.437948030912;2680.5845094010424;2683.329167708073;4645.4657714362775;4694.530546945304;4822.204336284513;4846.345743324655;4885.120678277908;4704.506026867181;4718.716876987383;4728.314394412808;4815.590674792638;4871.999999999991;4425.734455932655;4455.665825063125;4468.039371535119;4473.865784026484;4491.550844915499;4342.761148136851;4426.269665505204;4461.107778444308;4464.17412778637;4471.441069866267;4439.400680908085;4478.798454069574;4492.034212644281;4497.887552811173;4503;4194.328907374806;4419.977900110479;4429.291313389858;4433.312836510346;4519.751413672247;4255.659547236244;4285.957140428587;4361.280388735855;4361.912761744779;4396.20868243716;4730.361401210827;4762.38089048425;4769.356136921506;4788.305695674143;4856.587190088811;5137.743112844343;5145.691258524501;5198.246254293106;5199.350081239845;5200.973995130038;4942.728149951751;5120.411152717448;5173.4826517347965;5186.6369354144845;5248.107821670325;4853.6602437829715;4890.731009794496;4892.0705066037335;4894.290327902434;4901.681390709592;5145.665531740425;5351.6788992659785;5353.491418315254;5358.892822143614;5366.651167674088;5055.241713742968;5103.923441148386;5105.081085404663;5105.285260063594;5168.664036837594;5200.271961925334;5218.608604354688;5220.086484865134;5262.55268302191;5265.578753699695;4963.255511673206;5175.482451754794;5178.766955487012;5183.948160518422;5239.423663397023;5014.423341315723;5025.095482813055;5101;5119.87200319995;5178.249153872667;4357.912841743211;4359.760213188305;4392.38506609075;4393.582609652078;4394.340848872695;3841.366174581163;3855;3864.632859878307;3894.571597124314;3898.2197930316197;4269.637080848101;4363.956360436418;4380.474343078851;4445.4220951276775;4483.843065492757;5183.611229157828;5185.325907631984;5186.299849520342;5224.869378265574;5231.032259032091;4374.737515749065;4407.647388208936;4413.7351758894565;4456.197884380843;4461.486929003141;4475.597196252353;4572.9314060289125;4598.97700511502;4667.253239481734;4667.929981050287;4796.232602783538;4819.734914579732;4825.179719447632;4859.781309841065;4868.269759535956;5110.514501122388;5111.488851114858;5121.07820592297;5123;5138.332016837787;4698.271767875985;4836.105320515716;4846.442659094249;4891.779869905934;4892.094962431962;5137.563307118937;5158.252053452154;5160.148575485003;5174.508421699932;5207.505286997729;5221.556167725786;5233.6074794389815;5240.71176085319;5243.659162154448;5284.550813180769;4851.151048566558;4975.975120124376;4998.950010499849;5091.159958606937;5107.668001579886;4990.326305948651;5006.924896126634;5013.172826483589;5037.395512538519;5058.64589478733;4924.901501970012;4972.627052971114;4977.626677999237;4983.8504844854715;4995.325631039763;4860.73265970375;4890.462049174517;4902.14212512816;4959.20652695567;5035.9928014398;4789.808407663654;4857.344258525195;4863.367762191034;4915.95084049155;4982.975085124551 23:59:16 INFO - PROCESS | 12635 | |1;ext.html;544.3522208571799;566.8894565559715;568.0286709726367;573.0916497351698;590.7725525672615;26479.867600661993;38468.61531384689;39490.802545987266;39895.80052099743;43479.13041739171;40350;40704.185916281654;40713.79643101781;40738.38892416609;40764;7342;7614.1624421313745;7751.689932402725;7754.806129846743;7772.455928085036;9194.632214711404;9431.669891553796;9479.241660667165;9729.610815567405;9741.902580974205;9863.210943124534;9961;9968.850467242997;10012.799744005151;10019.549120289568;9762.951185244065;9823.36148150368;9824.607015719363;9830.410175389428;9832.31173817833;9719.125278724949;9783.755406114904;9794;9798.069183427562;9807;35976.820115899514;36173.45739813905;36182.276354473026;36237.09407264813;36268.466441694785;2949.808262462933;3016.3397550784093;3021.289996850738;3058.7858849880517;3060.234941264684;28006.85996570004;28389;28601.85699071512;28792.712072879214;29417.264568385748;3048.496997995328;3176.6188057433023;3219.500977348515;3231.2406584452633;3261.315123824;242144;242894;243570;243639;243861;31689.20776980594;32786.18034549156;51727.74136129371;52114;52120.47879521232;33281.500777488356;33453.16367090842;33458;33466.83266583652;33488.16279593029;13563;13598.660033499043;13626.659333516742;13634;13688.93155534216;9136.862947055799;10735.194860385502;10783.83824242637;10965.122790176767;11160;7775.1447340792465;7806.648700808477;7810.338822827029;7849.843003139905;7897.60511974399;3087.6603573606885;3117.4544454720335;3118.251619611277;3120.953185702216;3149.9212519686735;824;872.0930232558195;876.5704804645678;900.5722281916037;917.4678686361895;27201.72798271992;27499.86250068737;28420.573691394646;28453.857730711214;28620.427591448053;950.339514037751;963.4075043847984;1000.8548760429843;1009.258195226508;1081.9242653014214;14377.137371757519;14844.703105938037;14948.551543453714;15238.619034524005;15283.312250948735;391483;395393;395653;396444;398328;47683.761581192564;48277;48288.27567586489;48327.27509087366;48329.51670483321;1054.778496515725;1147.4262868565718;1175.4945373489481;1184.8637406698167;1202.9759404811855;26900.327491812863;28202.576961346007;28266.717332826407;29003.41993160167;29434.411311774074;41736.3739543901;42250.577494225894;42311.7884410576;42328;42354.364684530374;246003;246405;247251;247580;247626;24743.752562474147;25865.5081563994;25889;26671;26675.46649066969;40668;40904.386434204105;41237.79381103075;42302.36546451744;45464.31803522884;34134.14664633404;34455.65544344525;34728.479072814436;34870.65129348674;35070.47394289036;35487.467687984186;35539.82230088833;35542;35670.64329356673;35880.64119358773;35323.29353412969;35695.28609427745;35931.64068359283;36384.454233187054;36519.63480365162;31180.688193117778;33099.50350744693;33148.834255828566;33373;33572;24672.506549868544;24721;24786.380340491633;25116.74883251217;25491.490170196863 23:59:16 INFO - PROCESS | 12635 | |2;jquery.html;347.0040982380565;366.3168191323183;370.2040612682732;370.9777413355199;387.2120235321122;12711.555095571657;13320.533781317656;13451.59645210644;16944.406945756902;17007.48977530676;16339.509814705549;16488.917555412238;16591.66816663666;16595.751063734024;16599.253033613488;4978.900421991576;4983.554769116951;5050.772715227805;5100.285959965589;5105.18317069269;5030.396352437694;5101.4133374662015;5114.641975061747;5114.795408183668;5115.155999260118;4208.852690155845;5047.798088076491;5085.1355269604255;5088.160453525164;5103.540681338679;3649.3978493548534;3826.9043273918096;5673.886522269573;5958.731857066442;6002.699865006733;4484.596386325214;5342.813001544966;5353.304070470814;5362.383325917531;5362.839114826562;11528.423578821026;12296.692582685504;12317.753644927141;12318.199317044364;12318.81521777174;1753.377550969413;1797.4967009237434;1837.1640903388925;1860.0513737993585;1995.8103980121866;8939.910600893973;9174.770630734218;12324.938375308066;12486.625401237978;13274.535391261259;1432.7564314066633;1523.1470376589034;1585.2649621765715;1593.649397132629;1596.0503500417233;60878.69560652169;62743.37256627421;66390.66804665994;66776;69177;9642.517874106266;9882.160016398542;9915.504224788732;10055.798884022279;10071;13421.993350498751;13423.932880335537;13446;13449.731005379837;13469.191848489121;7148.106486689164;7300.890486642705;7386.409087273005;7391.113066432063;7441.739539116105;7049.7885063448175;7063.612419187436;7259.201487836333;7336.376408005272;7348.154962179417;4174.123434078878;4209.642180414638;4255.127698821734;4338.284183109751;4375.7374557526655;1718.089412611318;1733.8266173382813;1758.87687861851;1789.9821001790076;1824.0873326714125;772.3589420780738;808.8422757562219;812.695239285268;857.1771099744191;906.5676231554129;15652.139132347602;17270.568235793955;17485;18004.819951800313;18966.620667586572;829.4940086547201;838.2288294768706;842.9789255268668;860.3934226370394;928.6192661009089;9067.501287429122;9084.909150908406;9386.249100071978;9427.62289508412;9457.337986341028;288169;290369;419729;421813;422631;87931.56034219915;88558.55720721355;88664.5566772175;89176;90687;917.1333090229803;917.9541022948825;943.9480828554358;954.6038394066385;962.4658314635304;15124.39502419891;15356.923215383851;15671.68656626884;15937.920310398375;16021.279042443351;37853.621463785006;38483;39236.2152756949;39535.40696889492;40104;52676.20985685295;54366.72816635892;54517.72741136269;54982.45017549773;56167.71916140393;7457.40340772726;7518.436117291116;7662.118856331594;7697.191794861459;7775.883361749691;8816.338774591803;11737.119716021161;12031.699207519881;12221.327826969602;12304.0156787455;10074.294799364267;10214.08073273409;10366.688999330032;10442.947785261025;10880.891191087987;6736.4947628929;10323.32898361604;10384.792304153723;10464.110550603284;10581.52383142745;10063.849042264226;10140.898591013995;10248.897511024794;10249.692509224735;10945.726356840823;7473.51422157558;7788.649510771916;7814.023247094114;7832.295093441389;7890.132085470708;9142.26861851037;9903.801923961624;10339.63811266602;10426;10719.356838589709 23:59:16 INFO - PROCESS | 12635 | |3;mootools.html;1353.681884757082;1390.8261467316586;1456.5703117580313;1673.7322028475446;1678.9496315110543;7576.734814281494;8104.230098140674;8106.756797296076;8114.797130071736;8134.1459146789475;6682.966585167067;7573;7809.531428114317;7856;7967.282944534991;2160.3734916874128;2189.7591264960843;2191.3316438486254;2192.342297310808;2193.7915897989747;2148.98925505373;2155.1487162570775;2179.2808373236794;2182.2144028149855;2183.4541364658835;2129.3079749081535;2155.8167555757777;2162.524244666171;2162.664786958024;2170.728658917635;2130.6164890319737;2135.1139277199945;2137.9893100534396;2138.647123224666;2138.7861213878487;2053.2300387354744;2084.114251443131;2090.0176916849055;2090.3937858021154;2091.623507768601;8129.4715843469985;8175.63209655567;8181.059178194524;8186.099529051799;8195.42632015759;4559.202139625585;4579.862604121881;4606.401167848192;4729.125111854318;4731.739754313511;2831.2780241038404;2919.255589824581;2939.4855900217376;2942.249726319796;2956.127942257039;817.8364327134597;858.433433933607;863.667488017118;873.8470767615642;876.3252295732315;11006.339619622848;11010.449477526172;11028.28316159455;11030.944845275802;11086.279391839586;4065.5121385433345;4217.493900731932;4321.351797230378;4396.670249731219;4404.11917616478;9319.301052421095;9322.974472807986;9329.393589416666;9330.58012389444;9350.251979841596;4656.185167595725;4658.627309815207;4660.347551342815;4661.137689527448;4669.766511674403;3652.324320000755;4209.810558524874;4262.4458820353775;4264.616184543344;4384.4081049058605;3577.105723569108;3631.6005239423644;3699.519062521909;3747.6439738224826;3756.286305601927;1180.3095189314176;1202.8135638975973;1203.48851738011;1203.8134089216185;1218.415160722858;752.1688534169773;776.2392855002016;820.4175035724583;828.7140936376941;836.9027681028373;5180.948190518122;5260.526552610283;5290.047791397586;5304.310439642822;5305.1776974569;385.8437332880188;402.96776257899296;404.8785364390671;405.88635182149045;406.54873090869114;3426.126337783899;3471.20162362658;3512.051746028558;3530.682238598487;3561.269939662363;9409.153176214173;9949.054839790208;9953.452560109263;9956.054174853378;9982.450965196838;9883.110520053086;9921.851172232278;9931.7020489384;9965.451900145561;9978.45118518474;7248.855022899618;7340.596267205248;7490;7508.662110205054;7511.624418779148;5047.268146118866;5117.718525480986;5209.931963947458;5244.895102098013;5280.60395470349;9091.90908090937;9171.908280917372;9175.816483670424;9179.586918588546;9253.352265341362;9056.320775941962;9254.814903702023;9351.39215950961;9435.198008169382;9448.527573621428;6152.230971128609;6677.565958212701;6909.7581584644295;6964.233934267125;6977.860442791217;7231.4214862811905;7456.51532650376;7466.402687785073;7466.887996680162;7494.325510704063;6813.182418109858;6817.625030623165;6927.7228910843005;7007.789766307019;7080.787576372717;6838.452923765987;6865.759698410531;6974.023636690766;7021.087258656342;7038.260982596754;5964.403559644001;6035.155078288955;6045.576809623284;6058.757649693963;6063.363346848518;4381.978090109529;4416.492103408149;4524.434445694288;4585.403897493438;4614.238650622609;4442.267025940683;4525.3890724753055;4554.1802475553395;4674.182018146743;4739.928901066556 23:59:16 INFO - PROCESS | 12635 | |4;prototype.html;302.89398710451337;307.0894796927111;307.852230929154;309.37660613863085;312.0326986342338;4979.900401991962;5012.974935125324;5028.622853286004;5047.015831912779;5103.515166059227;4949.430815456223;4960.479149689285;5008.39899212095;5016.27264046713;5027.245913113025;3708.5735140458855;3796.90507737306;3813.493670000348;3887.047673320026;3923.5487918889266;3879.4374815651713;3887.9805600971954;3900.824462899162;3902.122022544933;3902.687784977209;3844.8269827857675;3856.190200057991;3856.53451688358;3872.7482713623667;3887.2031233597186;3623.6014038455883;3662.3206207791227;3857.4406711026886;3885.572587015426;3894.2990261752875;3404.642512536173;3408.216110294646;3409.7613167078316;3425.3491836550975;3427.4687423449154;4830.637702172316;4849.060375849653;4860.392450943632;4881.316615673809;4892.608591312686;4198.6641068714425;4418.513963464016;4493.348464472666;4497.235469970113;4499.955000449986;5202.635815492917;5206.505381988705;5218.869528261824;5220.321358223407;5221.477852214748;3688.981555092234;3702.2780557791243;3718.7542173371953;3724.2365315110337;3725.776453412804;5374.220737992969;5462.880109577487;5514.310711161105;5537.732737684785;5557.694326812024;3760.4359346098304;3770.434434834797;3777.905552361213;3783.1866148777594;3788.5264341957254;3837.654611085016;3843.942340864889;3870.4774855394726;3873.0511024798543;3883.417487376916;4377.78111094444;4413;4424.889377765581;4431.933520997188;4433.4039745691525;4975.387921341252;5139.434662187156;5169.612279079084;5181.792728290901;5273.549773812232;1299.7595444842734;1322.4974509686258;1324.8145259663654;1326.3103186343048;1340.7117469744053;809.554744890308;829.6059371798348;853.2619284319144;862.1249431826701;862.2627653356416;4123.7525548575495;4362.258416069276;4399.472063352418;4520.841770538082;4521.638268938543;5307.867303317371;5313.760880760375;5321.494458026481;5325;5330.093884039723;4515.864524064284;4527.818887244539;4548.499665036843;4549.909001819945;4549.977250113729;2729.3040274729815;2833.4333133373407;2834.2347566157023;2865.785066120049;2867.168521128891;5037.3955125384455;5046.26829109777;5047.899042019139;5086.54221120101;5091.9123159504425;5280.181571856291;5560.248521715646;5565.749541270652;5579.079451890474;5591.9720401397735;5024.849254522297;5049.21737130738;5052.823151189764;5053.393592768891;5079.6698214615935;5412.621116521806;5415.350157981067;5420.701861397661;5422.15956526739;5451.454854514517;5071.568916642127;5215.895682086413;5250;5336.946630533645;5339.199120132012;5005.899882002413;5007.3240112584335;5020.171671674133;5026.648134630541;5053.3576587609905;3744.194998075427;3765.1528406108405;3765.5293088363956;3779.6787273082095;3810.542734871833;5211.166213405837;5308.460546441489;5355.169948657965;5385.6230063895155;5395.271638328775;5096.286519887293;5108.131617624938;5115.18157094863;5119.87200319995;5138.486151384832;5133.897322053613;5156.2523434101095;5174.741262936914;5194.68831870089;5201.193814958688;4865.245886887538;5113.6704456842235;5114.133341330462;5121.41236216774;5170.6380553360905;5044.672096313728;5112.795488180431;5247.501487358848;5275.762590683352;5284.445133260952;5088.033273677989;5118.129917913889;5140.665856719302;5142.151544995034;5166.741662916915 23:59:16 INFO - PROCESS | 12635 | |5;yui.html;555.9555235581154;560.5627610463837;572.4618858273226;615.338511100567;637.1557686065962;11364.943175284123;11832.822507662371;12132;12158.088143389237;12531.37343132842;11829.585964491245;12040;12075.275483470998;12082.879171208286;12086.09354298429;628.789355565886;745.7017193122754;763.8243204063069;765.081901717938;765.3915137465729;739.8446326271489;769.8152443413596;772.1352085664037;775.9030121234846;776.1191048160322;812.6424373275769;815.0056930544725;815.5351449673689;815.6370415165254;815.759350991459;647.3914520350855;807.5195258821021;807.7294106474338;807.9919200807975;808.2887059387731;565.8189379398594;579.0937183308151;580.8112363481843;584.9181114643953;586.7007826008723;11894.82157767634;11898.524059037629;11928.10539209554;11931.761364772743;11955.521779128825;9736.123748862565;10076;10347.223958203165;10535.631252906189;10545.209109316833;10933.890661093446;11117.499712512956;11391.202615816814;11617.825732614017;11725.355105469196;4004.939925901114;4033.334499807528;4076.6942479313875;4177.352510360899;4180.414741936101;52415.21377179345;55430.722846385914;55648.721756391365;61794.38205617931;63083.684581577254;9052;9123.315751318545;9188.90811091894;9290.767730806649;9505.382150160356;15390.3074361654;15483.767743483631;15503.37214592474;15533.689326213638;16067.357305707641;6496;6636.634985075771;6690.832729181712;6729.461643068543;6734.124563806674;3578.5705715313807;3662.3407786598136;3701.241245544657;3702.5742039665784;3731.552213734369;2628.868556572164;2729.6314997475483;2739.643846299968;2743.492453896036;2747.052266967893;505.27997603414985;506.23052959501354;509.4803300633333;512.3800201755843;517.4876871896795;460.13953906195627;511.1719015195407;535.6491498068771;568.0570253379453;568.2045136808418;7019;7275.126984761862;7359.411247100219;7476.70093196266;7543.509671871311;521.272824409946;524.3524247554268;556.5686592890543;580.9803794340904;600.1717629671026;3407.4207384744655;3464.5149679044953;3498.3877821381157;3500.982495087508;3517.525134106914;91467;95601.52199239099;98171.05486835407;99326.50336748414;99822.5008874951;46561.30158047632;56242.71878640581;83885.58057209797;87559.56220218858;90368.09631903729;60849;73431.63284183652;77764.61117694376;77923.61038194773;78654;6466.4503517201565;6772.5936443813525;6820.420264277592;6850.9314906850295;6923.6192009439965;45681.31478027766;46238.53761462343;46888.53111468842;47018.52981470142;49133;53965.73017134889;55212;56645.71677141588;59162.70418647879;61463;10115.645952391631;10502.894971050191;10527.473626318806;10600.840987385041;10620.256582039183;11499;11554.537818487166;11891.583794567148;12384.071194660257;12528.87471125277;10796.460176991277;10866.61966831157;11308.886911130783;11310.886891130984;11322.26405283654;10181.134603558781;10477.16182705397;10499.212559057949;10728.892711073102;10942.617008404668;10787.406692631666;10839.13286937059;11061.612843550438;11332.943335283271;11360.375179364884;9264.094573236214;9378.68109489497;9558.808823823623;9791.902080979384;9919.305648604528;8850.06172253341;9202.815943681222;9359.85960210611;9662.3719458235;9682.225421966084 23:59:16 INFO - PROCESS | 12635 | __end_tp_report 23:59:16 INFO - PROCESS | 12635 | __start_cc_report 23:59:16 INFO - PROCESS | 12635 | _x_x_mozilla_cycle_collect,1192 23:59:16 INFO - PROCESS | 12635 | __end_cc_report 23:59:16 INFO - PROCESS | 12635 | __startTimestamp1469775556779__endTimestamp 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | ------- Summary: start ------- 23:59:16 INFO - PROCESS | 12635 | Number of tests: 6 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | [#0] dojo.html Cycles:165 Average:4759.47 Median:4872.00 stddev:508.61 (10.4%) stddev-sans-first:482.28 23:59:16 INFO - PROCESS | 12635 | Values: 2642.0 2646.5 2676.4 2680.6 2683.3 4645.5 4694.5 4822.2 4846.3 4885.1 4704.5 4718.7 4728.3 4815.6 4872.0 4425.7 4455.7 4468.0 4473.9 4491.6 4342.8 4426.3 4461.1 4464.2 4471.4 4439.4 4478.8 4492.0 4497.9 4503.0 4194.3 4420.0 4429.3 4433.3 4519.8 4255.7 4286.0 4361.3 4361.9 4396.2 4730.4 4762.4 4769.4 4788.3 4856.6 5137.7 5145.7 5198.2 5199.4 5201.0 4942.7 5120.4 5173.5 5186.6 5248.1 4853.7 4890.7 4892.1 4894.3 4901.7 5145.7 5351.7 5353.5 5358.9 5366.7 5055.2 5103.9 5105.1 5105.3 5168.7 5200.3 5218.6 5220.1 5262.6 5265.6 4963.3 5175.5 5178.8 5183.9 5239.4 5014.4 5025.1 5101.0 5119.9 5178.2 4357.9 4359.8 4392.4 4393.6 4394.3 3841.4 3855.0 3864.6 3894.6 3898.2 4269.6 4364.0 4380.5 4445.4 4483.8 5183.6 5185.3 5186.3 5224.9 5231.0 4374.7 4407.6 4413.7 4456.2 4461.5 4475.6 4572.9 4599.0 4667.3 4667.9 4796.2 4819.7 4825.2 4859.8 4868.3 5110.5 5111.5 5121.1 5123.0 5138.3 4698.3 4836.1 4846.4 4891.8 4892.1 5137.6 5158.3 5160.1 5174.5 5207.5 5221.6 5233.6 5240.7 5243.7 5284.6 4851.2 4976.0 4999.0 5091.2 5107.7 4990.3 5006.9 5013.2 5037.4 5058.6 4924.9 4972.6 4977.6 4983.9 4995.3 4860.7 4890.5 4902.1 4959.2 5036.0 4789.8 4857.3 4863.4 4916.0 4983.0 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | [#1] ext.html Cycles:180 Average:44214.88 Median:34970.56 stddev:80017.18 (228.8%) stddev-sans-first:80174.47 23:59:16 INFO - PROCESS | 12635 | Values: 544.4 566.9 568.0 573.1 590.8 26479.9 38468.6 39490.8 39895.8 43479.1 40350.0 40704.2 40713.8 40738.4 40764.0 7342.0 7614.2 7751.7 7754.8 7772.5 9194.6 9431.7 9479.2 9729.6 9741.9 9863.2 9961.0 9968.9 10012.8 10019.5 9763.0 9823.4 9824.6 9830.4 9832.3 9719.1 9783.8 9794.0 9798.1 9807.0 35976.8 36173.5 36182.3 36237.1 36268.5 2949.8 3016.3 3021.3 3058.8 3060.2 28006.9 28389.0 28601.9 28792.7 29417.3 3048.5 3176.6 3219.5 3231.2 3261.3 242144.0 242894.0 243570.0 243639.0 243861.0 31689.2 32786.2 51727.7 52114.0 52120.5 33281.5 33453.2 33458.0 33466.8 33488.2 13563.0 13598.7 13626.7 13634.0 13688.9 9136.9 10735.2 10783.8 10965.1 11160.0 7775.1 7806.6 7810.3 7849.8 7897.6 3087.7 3117.5 3118.3 3121.0 3149.9 824.0 872.1 876.6 900.6 917.5 27201.7 27499.9 28420.6 28453.9 28620.4 950.3 963.4 1000.9 1009.3 1081.9 14377.1 14844.7 14948.6 15238.6 15283.3 391483.0 395393.0 395653.0 396444.0 398328.0 47683.8 48277.0 48288.3 48327.3 48329.5 1054.8 1147.4 1175.5 1184.9 1203.0 26900.3 28202.6 28266.7 29003.4 29434.4 41736.4 42250.6 42311.8 42328.0 42354.4 246003.0 246405.0 247251.0 247580.0 247626.0 24743.8 25865.5 25889.0 26671.0 26675.5 40668.0 40904.4 41237.8 42302.4 45464.3 34134.1 34455.7 34728.5 34870.7 35070.5 35487.5 35539.8 35542.0 35670.6 35880.6 35323.3 35695.3 35931.6 36384.5 36519.6 31180.7 33099.5 33148.8 33373.0 33572.0 24672.5 24721.0 24786.4 25116.7 25491.5 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | [#2] jquery.html Cycles:180 Average:23784.06 Median:213443.06 stddev:62299.19 (29.2%) stddev-sans-first:62449.11 23:59:16 INFO - PROCESS | 12635 | Values: 347.0 366.3 370.2 371.0 387.2 12711.6 13320.5 13451.6 16944.4 17007.5 16339.5 16488.9 16591.7 16595.8 16599.3 4978.9 4983.6 5050.8 5100.3 5105.2 5030.4 5101.4 5114.6 5114.8 5115.2 4208.9 5047.8 5085.1 5088.2 5103.5 3649.4 3826.9 5673.9 5958.7 6002.7 4484.6 5342.8 5353.3 5362.4 5362.8 11528.4 12296.7 12317.8 12318.2 12318.8 1753.4 1797.5 1837.2 1860.1 1995.8 8939.9 9174.8 12324.9 12486.6 13274.5 1432.8 1523.1 1585.3 1593.6 1596.1 60878.7 62743.4 66390.7 66776.0 69177.0 9642.5 9882.2 9915.5 10055.8 10071.0 13422.0 13423.9 13446.0 13449.7 13469.2 7148.1 7300.9 7386.4 7391.1 7441.7 7049.8 7063.6 7259.2 7336.4 7348.2 4174.1 4209.6 4255.1 4338.3 4375.7 1718.1 1733.8 1758.9 1790.0 1824.1 772.4 808.8 812.7 857.2 906.6 15652.1 17270.6 17485.0 18004.8 18966.6 829.5 838.2 843.0 860.4 928.6 9067.5 9084.9 9386.2 9427.6 9457.3 288169.0 290369.0 419729.0 421813.0 422631.0 87931.6 88558.6 88664.6 89176.0 90687.0 917.1 918.0 943.9 954.6 962.5 15124.4 15356.9 15671.7 15937.9 16021.3 37853.6 38483.0 39236.2 39535.4 40104.0 52676.2 54366.7 54517.7 54982.5 56167.7 7457.4 7518.4 7662.1 7697.2 7775.9 8816.3 11737.1 12031.7 12221.3 12304.0 10074.3 10214.1 10366.7 10442.9 10880.9 6736.5 10323.3 10384.8 10464.1 10581.5 10063.8 10140.9 10248.9 10249.7 10945.7 7473.5 7788.6 7814.0 7832.3 7890.1 9142.3 9903.8 10339.6 10426.0 10719.4 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | [#3] mootools.html Cycles:180 Average:5187.89 Median:5082.49 stddev:2994.59 (58.9%) stddev-sans-first:2989.13 23:59:16 INFO - PROCESS | 12635 | Values: 1353.7 1390.8 1456.6 1673.7 1678.9 7576.7 8104.2 8106.8 8114.8 8134.1 6683.0 7573.0 7809.5 7856.0 7967.3 2160.4 2189.8 2191.3 2192.3 2193.8 2149.0 2155.1 2179.3 2182.2 2183.5 2129.3 2155.8 2162.5 2162.7 2170.7 2130.6 2135.1 2138.0 2138.6 2138.8 2053.2 2084.1 2090.0 2090.4 2091.6 8129.5 8175.6 8181.1 8186.1 8195.4 4559.2 4579.9 4606.4 4729.1 4731.7 2831.3 2919.3 2939.5 2942.2 2956.1 817.8 858.4 863.7 873.8 876.3 11006.3 11010.4 11028.3 11030.9 11086.3 4065.5 4217.5 4321.4 4396.7 4404.1 9319.3 9323.0 9329.4 9330.6 9350.3 4656.2 4658.6 4660.3 4661.1 4669.8 3652.3 4209.8 4262.4 4264.6 4384.4 3577.1 3631.6 3699.5 3747.6 3756.3 1180.3 1202.8 1203.5 1203.8 1218.4 752.2 776.2 820.4 828.7 836.9 5180.9 5260.5 5290.0 5304.3 5305.2 385.8 403.0 404.9 405.9 406.5 3426.1 3471.2 3512.1 3530.7 3561.3 9409.2 9949.1 9953.5 9956.1 9982.5 9883.1 9921.9 9931.7 9965.5 9978.5 7248.9 7340.6 7490.0 7508.7 7511.6 5047.3 5117.7 5209.9 5244.9 5280.6 9091.9 9171.9 9175.8 9179.6 9253.4 9056.3 9254.8 9351.4 9435.2 9448.5 6152.2 6677.6 6909.8 6964.2 6977.9 7231.4 7456.5 7466.4 7466.9 7494.3 6813.2 6817.6 6927.7 7007.8 7080.8 6838.5 6865.8 6974.0 7021.1 7038.3 5964.4 6035.2 6045.6 6058.8 6063.4 4382.0 4416.5 4524.4 4585.4 4614.2 4442.3 4525.4 4554.2 4674.2 4739.9 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | [#4] prototype.html Cycles:180 Average:4315.78 Median:5006.61 stddev:1262.66 (25.2%) stddev-sans-first:1229.75 23:59:16 INFO - PROCESS | 12635 | Values: 302.9 307.1 307.9 309.4 312.0 4979.9 5013.0 5028.6 5047.0 5103.5 4949.4 4960.5 5008.4 5016.3 5027.2 3708.6 3796.9 3813.5 3887.0 3923.5 3879.4 3888.0 3900.8 3902.1 3902.7 3844.8 3856.2 3856.5 3872.7 3887.2 3623.6 3662.3 3857.4 3885.6 3894.3 3404.6 3408.2 3409.8 3425.3 3427.5 4830.6 4849.1 4860.4 4881.3 4892.6 4198.7 4418.5 4493.3 4497.2 4500.0 5202.6 5206.5 5218.9 5220.3 5221.5 3689.0 3702.3 3718.8 3724.2 3725.8 5374.2 5462.9 5514.3 5537.7 5557.7 3760.4 3770.4 3777.9 3783.2 3788.5 3837.7 3843.9 3870.5 3873.1 3883.4 4377.8 4413.0 4424.9 4431.9 4433.4 4975.4 5139.4 5169.6 5181.8 5273.5 1299.8 1322.5 1324.8 1326.3 1340.7 809.6 829.6 853.3 862.1 862.3 4123.8 4362.3 4399.5 4520.8 4521.6 5307.9 5313.8 5321.5 5325.0 5330.1 4515.9 4527.8 4548.5 4549.9 4550.0 2729.3 2833.4 2834.2 2865.8 2867.2 5037.4 5046.3 5047.9 5086.5 5091.9 5280.2 5560.2 5565.7 5579.1 5592.0 5024.8 5049.2 5052.8 5053.4 5079.7 5412.6 5415.4 5420.7 5422.2 5451.5 5071.6 5215.9 5250.0 5336.9 5339.2 5005.9 5007.3 5020.2 5026.6 5053.4 3744.2 3765.2 3765.5 3779.7 3810.5 5211.2 5308.5 5355.2 5385.6 5395.3 5096.3 5108.1 5115.2 5119.9 5138.5 5133.9 5156.3 5174.7 5194.7 5201.2 4865.2 5113.7 5114.1 5121.4 5170.6 5044.7 5112.8 5247.5 5275.8 5284.4 5088.0 5118.1 5140.7 5142.2 5166.7 23:59:16 INFO - PROCESS | 12635 | 23:59:16 INFO - PROCESS | 12635 | [#5] yui.html Cycles:180 Average:16738.45 Median:27250.69 stddev:24365.11 (89.4%) stddev-sans-first:24403.16 23:59:16 INFO - PROCESS | 12635 | Values: 556.0 560.6 572.5 615.3 637.2 11364.9 11832.8 12132.0 12158.1 12531.4 11829.6 12040.0 12075.3 12082.9 12086.1 628.8 745.7 763.8 765.1 765.4 739.8 769.8 772.1 775.9 776.1 812.6 815.0 815.5 815.6 815.8 647.4 807.5 807.7 808.0 808.3 565.8 579.1 580.8 584.9 586.7 11894.8 11898.5 11928.1 11931.8 11955.5 9736.1 10076.0 10347.2 10535.6 10545.2 10933.9 11117.5 11391.2 11617.8 11725.4 4004.9 4033.3 4076.7 4177.4 4180.4 52415.2 55430.7 55648.7 61794.4 63083.7 9052.0 9123.3 9188.9 9290.8 9505.4 15390.3 15483.8 15503.4 15533.7 16067.4 6496.0 6636.6 6690.8 6729.5 6734.1 3578.6 3662.3 3701.2 3702.6 3731.6 2628.9 2729.6 2739.6 2743.5 2747.1 505.3 506.2 509.5 512.4 517.5 460.1 511.2 535.6 568.1 568.2 7019.0 7275.1 7359.4 7476.7 7543.5 521.3 524.4 556.6 581.0 600.2 3407.4 3464.5 3498.4 3501.0 3517.5 91467.0 95601.5 98171.1 99326.5 99822.5 46561.3 56242.7 83885.6 87559.6 90368.1 60849.0 73431.6 77764.6 77923.6 78654.0 6466.5 6772.6 6820.4 6850.9 6923.6 45681.3 46238.5 46888.5 47018.5 49133.0 53965.7 55212.0 56645.7 59162.7 61463.0 10115.6 10502.9 10527.5 10600.8 10620.3 11499.0 11554.5 11891.6 12384.1 12528.9 10796.5 10866.6 11308.9 11310.9 11322.3 10181.1 10477.2 10499.2 10728.9 10942.6 10787.4 10839.1 11061.6 11332.9 11360.4 9264.1 9378.7 9558.8 9791.9 9919.3 8850.1 9202.8 9359.9 9662.4 9682.2 23:59:16 INFO - PROCESS | 12635 | -------- Summary: end -------- 23:59:16 INFO - PROCESS | 12635 | 23:59:17 INFO - TEST-INFO | 12635: exit 0 23:59:17 INFO - TEST-OK | dromaeo_css | took 1120769ms 23:59:17 INFO - TEST-START | kraken 23:59:17 INFO - operating with platform_type : linux_ 23:59:17 INFO - Initialising browser for kraken test... 23:59:17 INFO - TEST-INFO | started process 12820 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYjnkgS/profile http://localhost:56863/getInfo.html) 23:59:21 INFO - PROCESS | 12820 | JavaScript error: , line 0: uncaught exception: undefined 23:59:21 INFO - PROCESS | 12820 | __metrics Screen width/height:1600/1200 23:59:21 INFO - PROCESS | 12820 | colorDepth:24 23:59:21 INFO - PROCESS | 12820 | Browser inner width/height: 1024/697 23:59:21 INFO - PROCESS | 12820 | __metrics 23:59:22 INFO - PROCESS | 12820 | 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" 23:59:22 INFO - PROCESS | 12820 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:59:23 INFO - TEST-INFO | 12820: exit 0 23:59:23 INFO - Browser initialized. 23:59:23 INFO - Running cycle 1/1 for kraken test... 23:59:23 INFO - TEST-INFO | started process 12899 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYjnkgS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:59:35 INFO - PROCESS | 12899 | RSS: Main: 159240192 23:59:35 INFO - PROCESS | 12899 | 23:59:38 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56863/tests/kraken/driver.html?testName=audio-beat-detection) 23:59:39 INFO - PROCESS | 12899 | RSS: Main: 215023616 23:59:39 INFO - PROCESS | 12899 | 23:59:42 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56863/tests/kraken/driver.html?testName=audio-dft) 23:59:42 INFO - PROCESS | 12899 | RSS: Main: 192266240 23:59:42 INFO - PROCESS | 12899 | 23:59:46 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56863/tests/kraken/driver.html?testName=audio-fft) 23:59:46 INFO - PROCESS | 12899 | RSS: Main: 273399808 23:59:46 INFO - PROCESS | 12899 | 23:59:48 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56863/tests/kraken/driver.html?testName=audio-oscillator) 23:59:49 INFO - PROCESS | 12899 | RSS: Main: 182050816 23:59:49 INFO - PROCESS | 12899 | 23:59:51 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56863/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:59:52 INFO - PROCESS | 12899 | RSS: Main: 186601472 23:59:52 INFO - PROCESS | 12899 | 23:59:55 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56863/tests/kraken/driver.html?testName=imaging-darkroom) 23:59:56 INFO - PROCESS | 12899 | RSS: Main: 223440896 23:59:56 INFO - PROCESS | 12899 | 23:59:59 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56863/tests/kraken/driver.html?testName=imaging-desaturate) 00:00:00 INFO - PROCESS | 12899 | RSS: Main: 221822976 00:00:00 INFO - PROCESS | 12899 | 00:00:03 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56863/tests/kraken/driver.html?testName=json-parse-financial) 00:00:03 INFO - PROCESS | 12899 | RSS: Main: 222117888 00:00:03 INFO - PROCESS | 12899 | 00:00:05 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56863/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:00:06 INFO - PROCESS | 12899 | RSS: Main: 185204736 00:00:06 INFO - PROCESS | 12899 | 00:00:07 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:00:08 INFO - PROCESS | 12899 | RSS: Main: 191987712 00:00:08 INFO - PROCESS | 12899 | 00:00:10 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:00:11 INFO - PROCESS | 12899 | RSS: Main: 196993024 00:00:11 INFO - PROCESS | 12899 | 00:00:14 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:00:14 INFO - PROCESS | 12899 | RSS: Main: 190603264 00:00:14 INFO - PROCESS | 12899 | 00:00:17 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:00:18 INFO - PROCESS | 12899 | RSS: Main: 194445312 00:00:18 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | Cycle 1(1): loaded http://localhost:56863/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56863/tests/kraken/driver.html?testName=ai-astar) 00:00:20 INFO - PROCESS | 12899 | RSS: Main: 245174272 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | __start_tp_report 00:00:20 INFO - PROCESS | 12899 | _x_x_mozilla_page_load 00:00:20 INFO - PROCESS | 12899 | _x_x_mozilla_page_load_details 00:00:20 INFO - PROCESS | 12899 | |i|pagename|runs| 00:00:20 INFO - PROCESS | 12899 | |0;ai-astar;105;113;115;113;112;113;113;114;113;113 00:00:20 INFO - PROCESS | 12899 | |1;audio-beat-detection;142;120;133;188;132;127;158;124;160;128 00:00:20 INFO - PROCESS | 12899 | |2;audio-dft;161;160;160;161;163;162;161;160;163;161 00:00:20 INFO - PROCESS | 12899 | |3;audio-fft;75;76;76;119;75;76;86;75;104;75 00:00:20 INFO - PROCESS | 12899 | |4;audio-oscillator;84;84;121;85;85;85;123;86;84;122 00:00:20 INFO - PROCESS | 12899 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;117;117;117 00:00:20 INFO - PROCESS | 12899 | |6;imaging-darkroom;146;147;146;146;147;147;146;146;146;146 00:00:20 INFO - PROCESS | 12899 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;99;95 00:00:20 INFO - PROCESS | 12899 | |8;json-parse-financial;61;62;63;61;62;71;62;61;60;62 00:00:20 INFO - PROCESS | 12899 | |9;json-stringify-tinderbox;66;66;65;66;65;66;65;67;69;66 00:00:20 INFO - PROCESS | 12899 | |10;stanford-crypto-aes;79;78;78;79;79;79;79;79;80;80 00:00:20 INFO - PROCESS | 12899 | |11;stanford-crypto-ccm;129;131;127;129;129;128;129;127;206;130 00:00:20 INFO - PROCESS | 12899 | |12;stanford-crypto-pbkdf2;168;170;167;165;163;165;161;164;165;166 00:00:20 INFO - PROCESS | 12899 | |13;stanford-crypto-sha256-iterative;66;67;67;67;65;66;67;66;66;67 00:00:20 INFO - PROCESS | 12899 | __end_tp_report 00:00:20 INFO - PROCESS | 12899 | __start_cc_report 00:00:20 INFO - PROCESS | 12899 | _x_x_mozilla_cycle_collect,1945 00:00:20 INFO - PROCESS | 12899 | __end_cc_report 00:00:20 INFO - PROCESS | 12899 | __startTimestamp1469775620253__endTimestamp 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | ------- Summary: start ------- 00:00:20 INFO - PROCESS | 12899 | Number of tests: 14 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:2.72 (2.4%) stddev-sans-first:0.83 00:00:20 INFO - PROCESS | 12899 | Values: 105.0 113.0 115.0 113.0 112.0 113.0 113.0 114.0 113.0 113.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:137.50 stddev:21.34 (15.5%) stddev-sans-first:22.64 00:00:20 INFO - PROCESS | 12899 | Values: 142.0 120.0 133.0 188.0 132.0 127.0 158.0 124.0 160.0 128.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:1.14 (0.7%) stddev-sans-first:1.20 00:00:20 INFO - PROCESS | 12899 | Values: 161.0 160.0 160.0 161.0 163.0 162.0 161.0 160.0 163.0 161.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#3] audio-fft Cycles:10 Average:83.70 Median:75.50 stddev:15.43 (20.4%) stddev-sans-first:16.05 00:00:20 INFO - PROCESS | 12899 | Values: 75.0 76.0 76.0 119.0 75.0 76.0 86.0 75.0 104.0 75.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#4] audio-oscillator Cycles:10 Average:95.90 Median:84.50 stddev:18.03 (21.3%) stddev-sans-first:18.60 00:00:20 INFO - PROCESS | 12899 | Values: 84.0 84.0 121.0 85.0 85.0 85.0 123.0 86.0 84.0 122.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 00:00:20 INFO - PROCESS | 12899 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 00:00:20 INFO - PROCESS | 12899 | Values: 146.0 147.0 146.0 146.0 147.0 147.0 146.0 146.0 146.0 146.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:1.26 (1.3%) stddev-sans-first:1.33 00:00:20 INFO - PROCESS | 12899 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 99.0 95.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:62.00 stddev:3.10 (5.0%) stddev-sans-first:3.24 00:00:20 INFO - PROCESS | 12899 | Values: 61.0 62.0 63.0 61.0 62.0 71.0 62.0 61.0 60.0 62.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#9] json-stringify-tinderbox Cycles:10 Average:66.10 Median:66.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 00:00:20 INFO - PROCESS | 12899 | Values: 66.0 66.0 65.0 66.0 65.0 66.0 65.0 67.0 69.0 66.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:79.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 00:00:20 INFO - PROCESS | 12899 | Values: 79.0 78.0 78.0 79.0 79.0 79.0 79.0 79.0 80.0 80.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#11] stanford-crypto-ccm Cycles:10 Average:136.50 Median:129.00 stddev:24.45 (19.0%) stddev-sans-first:25.78 00:00:20 INFO - PROCESS | 12899 | Values: 129.0 131.0 127.0 129.0 129.0 128.0 129.0 127.0 206.0 130.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.50 stddev:2.55 (1.5%) stddev-sans-first:2.52 00:00:20 INFO - PROCESS | 12899 | Values: 168.0 170.0 167.0 165.0 163.0 165.0 161.0 164.0 165.0 166.0 00:00:20 INFO - PROCESS | 12899 | 00:00:20 INFO - PROCESS | 12899 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 00:00:20 INFO - PROCESS | 12899 | Values: 66.0 67.0 67.0 67.0 65.0 66.0 67.0 66.0 66.0 67.0 00:00:20 INFO - PROCESS | 12899 | -------- Summary: end -------- 00:00:20 INFO - PROCESS | 12899 | 00:00:21 INFO - TEST-INFO | 12899: exit 0 00:00:21 INFO - TEST-OK | kraken | took 63647ms 00:00:21 INFO - SUITE-END | took 1184s 00:00:21 INFO - Completed test suite (00:19:44) 00:00:21 INFO - javascript benchmark 00:00:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2641.96037059444, 2646.536856050191, 2676.437948030912, 2680.5845094010424, 2683.329167708073, 4645.4657714362775, 4694.530546945304, 4822.204336284513, 4846.345743324655, 4885.120678277908, 4704.506026867181, 4718.716876987383, 4728.314394412808, 4815.590674792638, 4871.999999999991, 4425.734455932655, 4455.665825063125, 4468.039371535119, 4473.865784026484, 4491.550844915499, 4342.761148136851, 4426.269665505204, 4461.107778444308, 4464.17412778637, 4471.441069866267, 4439.400680908085, 4478.798454069574, 4492.034212644281, 4497.887552811173, 4503.0, 4194.328907374806, 4419.977900110479, 4429.291313389858, 4433.312836510346, 4519.751413672247, 4255.659547236244, 4285.957140428587, 4361.280388735855, 4361.912761744779, 4396.20868243716, 4730.361401210827, 4762.38089048425, 4769.356136921506, 4788.305695674143, 4856.587190088811, 5137.743112844343, 5145.691258524501, 5198.246254293106, 5199.350081239845, 5200.973995130038, 4942.728149951751, 5120.411152717448, 5173.4826517347965, 5186.6369354144845, 5248.107821670325, 4853.6602437829715, 4890.731009794496, 4892.0705066037335, 4894.290327902434, 4901.681390709592, 5145.665531740425, 5351.6788992659785, 5353.491418315254, 5358.892822143614, 5366.651167674088, 5055.241713742968, 5103.923441148386, 5105.081085404663, 5105.285260063594, 5168.664036837594, 5200.271961925334, 5218.608604354688, 5220.086484865134, 5262.55268302191, 5265.578753699695, 4963.255511673206, 5175.482451754794, 5178.766955487012, 5183.948160518422, 5239.423663397023, 5014.423341315723, 5025.095482813055, 5101.0, 5119.87200319995, 5178.249153872667, 4357.912841743211, 4359.760213188305, 4392.38506609075, 4393.582609652078, 4394.340848872695, 3841.366174581163, 3855.0, 3864.632859878307, 3894.571597124314, 3898.2197930316197, 4269.637080848101, 4363.956360436418, 4380.474343078851, 4445.4220951276775, 4483.843065492757, 5183.611229157828, 5185.325907631984, 5186.299849520342, 5224.869378265574, 5231.032259032091, 4374.737515749065, 4407.647388208936, 4413.7351758894565, 4456.197884380843, 4461.486929003141, 4475.597196252353, 4572.9314060289125, 4598.97700511502, 4667.253239481734, 4667.929981050287, 4796.232602783538, 4819.734914579732, 4825.179719447632, 4859.781309841065, 4868.269759535956, 5110.514501122388, 5111.488851114858, 5121.07820592297, 5123.0, 5138.332016837787, 4698.271767875985, 4836.105320515716, 4846.442659094249, 4891.779869905934, 4892.094962431962, 5137.563307118937, 5158.252053452154, 5160.148575485003, 5174.508421699932, 5207.505286997729, 5221.556167725786, 5233.6074794389815, 5240.71176085319, 5243.659162154448, 5284.550813180769, 4851.151048566558, 4975.975120124376, 4998.950010499849, 5091.159958606937, 5107.668001579886, 4990.326305948651, 5006.924896126634, 5013.172826483589, 5037.395512538519, 5058.64589478733, 4924.901501970012, 4972.627052971114, 4977.626677999237, 4983.8504844854715, 4995.325631039763, 4860.73265970375, 4890.462049174517, 4902.14212512816, 4959.20652695567, 5035.9928014398, 4789.808407663654, 4857.344258525195, 4863.367762191034, 4915.95084049155, 4982.975085124551], "value": 4726.556006375117, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.3522208571799, 566.8894565559715, 568.0286709726367, 573.0916497351698, 590.7725525672615, 26479.867600661993, 38468.61531384689, 39490.802545987266, 39895.80052099743, 43479.13041739171, 40350.0, 40704.185916281654, 40713.79643101781, 40738.38892416609, 40764.0, 7342.0, 7614.1624421313745, 7751.689932402725, 7754.806129846743, 7772.455928085036, 9194.632214711404, 9431.669891553796, 9479.241660667165, 9729.610815567405, 9741.902580974205, 9863.210943124534, 9961.0, 9968.850467242997, 10012.799744005151, 10019.549120289568, 9762.951185244065, 9823.36148150368, 9824.607015719363, 9830.410175389428, 9832.31173817833, 9719.125278724949, 9783.755406114904, 9794.0, 9798.069183427562, 9807.0, 35976.820115899514, 36173.45739813905, 36182.276354473026, 36237.09407264813, 36268.466441694785, 2949.808262462933, 3016.3397550784093, 3021.289996850738, 3058.7858849880517, 3060.234941264684, 28006.85996570004, 28389.0, 28601.85699071512, 28792.712072879214, 29417.264568385748, 3048.496997995328, 3176.6188057433023, 3219.500977348515, 3231.2406584452633, 3261.315123824, 242144.0, 242894.0, 243570.0, 243639.0, 243861.0, 31689.20776980594, 32786.18034549156, 51727.74136129371, 52114.0, 52120.47879521232, 33281.500777488356, 33453.16367090842, 33458.0, 33466.83266583652, 33488.16279593029, 13563.0, 13598.660033499043, 13626.659333516742, 13634.0, 13688.93155534216, 9136.862947055799, 10735.194860385502, 10783.83824242637, 10965.122790176767, 11160.0, 7775.1447340792465, 7806.648700808477, 7810.338822827029, 7849.843003139905, 7897.60511974399, 3087.6603573606885, 3117.4544454720335, 3118.251619611277, 3120.953185702216, 3149.9212519686735, 824.0, 872.0930232558195, 876.5704804645678, 900.5722281916037, 917.4678686361895, 27201.72798271992, 27499.86250068737, 28420.573691394646, 28453.857730711214, 28620.427591448053, 950.339514037751, 963.4075043847984, 1000.8548760429843, 1009.258195226508, 1081.9242653014214, 14377.137371757519, 14844.703105938037, 14948.551543453714, 15238.619034524005, 15283.312250948735, 391483.0, 395393.0, 395653.0, 396444.0, 398328.0, 47683.761581192564, 48277.0, 48288.27567586489, 48327.27509087366, 48329.51670483321, 1054.778496515725, 1147.4262868565718, 1175.4945373489481, 1184.8637406698167, 1202.9759404811855, 26900.327491812863, 28202.576961346007, 28266.717332826407, 29003.41993160167, 29434.411311774074, 41736.3739543901, 42250.577494225894, 42311.7884410576, 42328.0, 42354.364684530374, 246003.0, 246405.0, 247251.0, 247580.0, 247626.0, 24743.752562474147, 25865.5081563994, 25889.0, 26671.0, 26675.46649066969, 40668.0, 40904.386434204105, 41237.79381103075, 42302.36546451744, 45464.31803522884, 34134.14664633404, 34455.65544344525, 34728.479072814436, 34870.65129348674, 35070.47394289036, 35487.467687984186, 35539.82230088833, 35542.0, 35670.64329356673, 35880.64119358773, 35323.29353412969, 35695.28609427745, 35931.64068359283, 36384.454233187054, 36519.63480365162, 31180.688193117778, 33099.50350744693, 33148.834255828566, 33373.0, 33572.0, 24672.506549868544, 24721.0, 24786.380340491633, 25116.74883251217, 25491.490170196863], "value": 16533.676561491688, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.0040982380565, 366.3168191323183, 370.2040612682732, 370.9777413355199, 387.2120235321122, 12711.555095571657, 13320.533781317656, 13451.59645210644, 16944.406945756902, 17007.48977530676, 16339.509814705549, 16488.917555412238, 16591.66816663666, 16595.751063734024, 16599.253033613488, 4978.900421991576, 4983.554769116951, 5050.772715227805, 5100.285959965589, 5105.18317069269, 5030.396352437694, 5101.4133374662015, 5114.641975061747, 5114.795408183668, 5115.155999260118, 4208.852690155845, 5047.798088076491, 5085.1355269604255, 5088.160453525164, 5103.540681338679, 3649.3978493548534, 3826.9043273918096, 5673.886522269573, 5958.731857066442, 6002.699865006733, 4484.596386325214, 5342.813001544966, 5353.304070470814, 5362.383325917531, 5362.839114826562, 11528.423578821026, 12296.692582685504, 12317.753644927141, 12318.199317044364, 12318.81521777174, 1753.377550969413, 1797.4967009237434, 1837.1640903388925, 1860.0513737993585, 1995.8103980121866, 8939.910600893973, 9174.770630734218, 12324.938375308066, 12486.625401237978, 13274.535391261259, 1432.7564314066633, 1523.1470376589034, 1585.2649621765715, 1593.649397132629, 1596.0503500417233, 60878.69560652169, 62743.37256627421, 66390.66804665994, 66776.0, 69177.0, 9642.517874106266, 9882.160016398542, 9915.504224788732, 10055.798884022279, 10071.0, 13421.993350498751, 13423.932880335537, 13446.0, 13449.731005379837, 13469.191848489121, 7148.106486689164, 7300.890486642705, 7386.409087273005, 7391.113066432063, 7441.739539116105, 7049.7885063448175, 7063.612419187436, 7259.201487836333, 7336.376408005272, 7348.154962179417, 4174.123434078878, 4209.642180414638, 4255.127698821734, 4338.284183109751, 4375.7374557526655, 1718.089412611318, 1733.8266173382813, 1758.87687861851, 1789.9821001790076, 1824.0873326714125, 772.3589420780738, 808.8422757562219, 812.695239285268, 857.1771099744191, 906.5676231554129, 15652.139132347602, 17270.568235793955, 17485.0, 18004.819951800313, 18966.620667586572, 829.4940086547201, 838.2288294768706, 842.9789255268668, 860.3934226370394, 928.6192661009089, 9067.501287429122, 9084.909150908406, 9386.249100071978, 9427.62289508412, 9457.337986341028, 288169.0, 290369.0, 419729.0, 421813.0, 422631.0, 87931.56034219915, 88558.55720721355, 88664.5566772175, 89176.0, 90687.0, 917.1333090229803, 917.9541022948825, 943.9480828554358, 954.6038394066385, 962.4658314635304, 15124.39502419891, 15356.923215383851, 15671.68656626884, 15937.920310398375, 16021.279042443351, 37853.621463785006, 38483.0, 39236.2152756949, 39535.40696889492, 40104.0, 52676.20985685295, 54366.72816635892, 54517.72741136269, 54982.45017549773, 56167.71916140393, 7457.40340772726, 7518.436117291116, 7662.118856331594, 7697.191794861459, 7775.883361749691, 8816.338774591803, 11737.119716021161, 12031.699207519881, 12221.327826969602, 12304.0156787455, 10074.294799364267, 10214.08073273409, 10366.688999330032, 10442.947785261025, 10880.891191087987, 6736.4947628929, 10323.32898361604, 10384.792304153723, 10464.110550603284, 10581.52383142745, 10063.849042264226, 10140.898591013995, 10248.897511024794, 10249.692509224735, 10945.726356840823, 7473.51422157558, 7788.649510771916, 7814.023247094114, 7832.295093441389, 7890.132085470708, 9142.26861851037, 9903.801923961624, 10339.63811266602, 10426.0, 10719.356838589709], "value": 8008.788142053883, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1353.681884757082, 1390.8261467316586, 1456.5703117580313, 1673.7322028475446, 1678.9496315110543, 7576.734814281494, 8104.230098140674, 8106.756797296076, 8114.797130071736, 8134.1459146789475, 6682.966585167067, 7573.0, 7809.531428114317, 7856.0, 7967.282944534991, 2160.3734916874128, 2189.7591264960843, 2191.3316438486254, 2192.342297310808, 2193.7915897989747, 2148.98925505373, 2155.1487162570775, 2179.2808373236794, 2182.2144028149855, 2183.4541364658835, 2129.3079749081535, 2155.8167555757777, 2162.524244666171, 2162.664786958024, 2170.728658917635, 2130.6164890319737, 2135.1139277199945, 2137.9893100534396, 2138.647123224666, 2138.7861213878487, 2053.2300387354744, 2084.114251443131, 2090.0176916849055, 2090.3937858021154, 2091.623507768601, 8129.4715843469985, 8175.63209655567, 8181.059178194524, 8186.099529051799, 8195.42632015759, 4559.202139625585, 4579.862604121881, 4606.401167848192, 4729.125111854318, 4731.739754313511, 2831.2780241038404, 2919.255589824581, 2939.4855900217376, 2942.249726319796, 2956.127942257039, 817.8364327134597, 858.433433933607, 863.667488017118, 873.8470767615642, 876.3252295732315, 11006.339619622848, 11010.449477526172, 11028.28316159455, 11030.944845275802, 11086.279391839586, 4065.5121385433345, 4217.493900731932, 4321.351797230378, 4396.670249731219, 4404.11917616478, 9319.301052421095, 9322.974472807986, 9329.393589416666, 9330.58012389444, 9350.251979841596, 4656.185167595725, 4658.627309815207, 4660.347551342815, 4661.137689527448, 4669.766511674403, 3652.324320000755, 4209.810558524874, 4262.4458820353775, 4264.616184543344, 4384.4081049058605, 3577.105723569108, 3631.6005239423644, 3699.519062521909, 3747.6439738224826, 3756.286305601927, 1180.3095189314176, 1202.8135638975973, 1203.48851738011, 1203.8134089216185, 1218.415160722858, 752.1688534169773, 776.2392855002016, 820.4175035724583, 828.7140936376941, 836.9027681028373, 5180.948190518122, 5260.526552610283, 5290.047791397586, 5304.310439642822, 5305.1776974569, 385.8437332880188, 402.96776257899296, 404.8785364390671, 405.88635182149045, 406.54873090869114, 3426.126337783899, 3471.20162362658, 3512.051746028558, 3530.682238598487, 3561.269939662363, 9409.153176214173, 9949.054839790208, 9953.452560109263, 9956.054174853378, 9982.450965196838, 9883.110520053086, 9921.851172232278, 9931.7020489384, 9965.451900145561, 9978.45118518474, 7248.855022899618, 7340.596267205248, 7490.0, 7508.662110205054, 7511.624418779148, 5047.268146118866, 5117.718525480986, 5209.931963947458, 5244.895102098013, 5280.60395470349, 9091.90908090937, 9171.908280917372, 9175.816483670424, 9179.586918588546, 9253.352265341362, 9056.320775941962, 9254.814903702023, 9351.39215950961, 9435.198008169382, 9448.527573621428, 6152.230971128609, 6677.565958212701, 6909.7581584644295, 6964.233934267125, 6977.860442791217, 7231.4214862811905, 7456.51532650376, 7466.402687785073, 7466.887996680162, 7494.325510704063, 6813.182418109858, 6817.625030623165, 6927.7228910843005, 7007.789766307019, 7080.787576372717, 6838.452923765987, 6865.759698410531, 6974.023636690766, 7021.087258656342, 7038.260982596754, 5964.403559644001, 6035.155078288955, 6045.576809623284, 6058.757649693963, 6063.363346848518, 4381.978090109529, 4416.492103408149, 4524.434445694288, 4585.403897493438, 4614.238650622609, 4442.267025940683, 4525.3890724753055, 4554.1802475553395, 4674.182018146743, 4739.928901066556], "value": 4067.186187877316, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.89398710451337, 307.0894796927111, 307.852230929154, 309.37660613863085, 312.0326986342338, 4979.900401991962, 5012.974935125324, 5028.622853286004, 5047.015831912779, 5103.515166059227, 4949.430815456223, 4960.479149689285, 5008.39899212095, 5016.27264046713, 5027.245913113025, 3708.5735140458855, 3796.90507737306, 3813.493670000348, 3887.047673320026, 3923.5487918889266, 3879.4374815651713, 3887.9805600971954, 3900.824462899162, 3902.122022544933, 3902.687784977209, 3844.8269827857675, 3856.190200057991, 3856.53451688358, 3872.7482713623667, 3887.2031233597186, 3623.6014038455883, 3662.3206207791227, 3857.4406711026886, 3885.572587015426, 3894.2990261752875, 3404.642512536173, 3408.216110294646, 3409.7613167078316, 3425.3491836550975, 3427.4687423449154, 4830.637702172316, 4849.060375849653, 4860.392450943632, 4881.316615673809, 4892.608591312686, 4198.6641068714425, 4418.513963464016, 4493.348464472666, 4497.235469970113, 4499.955000449986, 5202.635815492917, 5206.505381988705, 5218.869528261824, 5220.321358223407, 5221.477852214748, 3688.981555092234, 3702.2780557791243, 3718.7542173371953, 3724.2365315110337, 3725.776453412804, 5374.220737992969, 5462.880109577487, 5514.310711161105, 5537.732737684785, 5557.694326812024, 3760.4359346098304, 3770.434434834797, 3777.905552361213, 3783.1866148777594, 3788.5264341957254, 3837.654611085016, 3843.942340864889, 3870.4774855394726, 3873.0511024798543, 3883.417487376916, 4377.78111094444, 4413.0, 4424.889377765581, 4431.933520997188, 4433.4039745691525, 4975.387921341252, 5139.434662187156, 5169.612279079084, 5181.792728290901, 5273.549773812232, 1299.7595444842734, 1322.4974509686258, 1324.8145259663654, 1326.3103186343048, 1340.7117469744053, 809.554744890308, 829.6059371798348, 853.2619284319144, 862.1249431826701, 862.2627653356416, 4123.7525548575495, 4362.258416069276, 4399.472063352418, 4520.841770538082, 4521.638268938543, 5307.867303317371, 5313.760880760375, 5321.494458026481, 5325.0, 5330.093884039723, 4515.864524064284, 4527.818887244539, 4548.499665036843, 4549.909001819945, 4549.977250113729, 2729.3040274729815, 2833.4333133373407, 2834.2347566157023, 2865.785066120049, 2867.168521128891, 5037.3955125384455, 5046.26829109777, 5047.899042019139, 5086.54221120101, 5091.9123159504425, 5280.181571856291, 5560.248521715646, 5565.749541270652, 5579.079451890474, 5591.9720401397735, 5024.849254522297, 5049.21737130738, 5052.823151189764, 5053.393592768891, 5079.6698214615935, 5412.621116521806, 5415.350157981067, 5420.701861397661, 5422.15956526739, 5451.454854514517, 5071.568916642127, 5215.895682086413, 5250.0, 5336.946630533645, 5339.199120132012, 5005.899882002413, 5007.3240112584335, 5020.171671674133, 5026.648134630541, 5053.3576587609905, 3744.194998075427, 3765.1528406108405, 3765.5293088363956, 3779.6787273082095, 3810.542734871833, 5211.166213405837, 5308.460546441489, 5355.169948657965, 5385.6230063895155, 5395.271638328775, 5096.286519887293, 5108.131617624938, 5115.18157094863, 5119.87200319995, 5138.486151384832, 5133.897322053613, 5156.2523434101095, 5174.741262936914, 5194.68831870089, 5201.193814958688, 4865.245886887538, 5113.6704456842235, 5114.133341330462, 5121.41236216774, 5170.6380553360905, 5044.672096313728, 5112.795488180431, 5247.501487358848, 5275.762590683352, 5284.445133260952, 5088.033273677989, 5118.129917913889, 5140.665856719302, 5142.151544995034, 5166.741662916915], "value": 3911.893643499433, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.9555235581154, 560.5627610463837, 572.4618858273226, 615.338511100567, 637.1557686065962, 11364.943175284123, 11832.822507662371, 12132.0, 12158.088143389237, 12531.37343132842, 11829.585964491245, 12040.0, 12075.275483470998, 12082.879171208286, 12086.09354298429, 628.789355565886, 745.7017193122754, 763.8243204063069, 765.081901717938, 765.3915137465729, 739.8446326271489, 769.8152443413596, 772.1352085664037, 775.9030121234846, 776.1191048160322, 812.6424373275769, 815.0056930544725, 815.5351449673689, 815.6370415165254, 815.759350991459, 647.3914520350855, 807.5195258821021, 807.7294106474338, 807.9919200807975, 808.2887059387731, 565.8189379398594, 579.0937183308151, 580.8112363481843, 584.9181114643953, 586.7007826008723, 11894.82157767634, 11898.524059037629, 11928.10539209554, 11931.761364772743, 11955.521779128825, 9736.123748862565, 10076.0, 10347.223958203165, 10535.631252906189, 10545.209109316833, 10933.890661093446, 11117.499712512956, 11391.202615816814, 11617.825732614017, 11725.355105469196, 4004.939925901114, 4033.334499807528, 4076.6942479313875, 4177.352510360899, 4180.414741936101, 52415.21377179345, 55430.722846385914, 55648.721756391365, 61794.38205617931, 63083.684581577254, 9052.0, 9123.315751318545, 9188.90811091894, 9290.767730806649, 9505.382150160356, 15390.3074361654, 15483.767743483631, 15503.37214592474, 15533.689326213638, 16067.357305707641, 6496.0, 6636.634985075771, 6690.832729181712, 6729.461643068543, 6734.124563806674, 3578.5705715313807, 3662.3407786598136, 3701.241245544657, 3702.5742039665784, 3731.552213734369, 2628.868556572164, 2729.6314997475483, 2739.643846299968, 2743.492453896036, 2747.052266967893, 505.27997603414985, 506.23052959501354, 509.4803300633333, 512.3800201755843, 517.4876871896795, 460.13953906195627, 511.1719015195407, 535.6491498068771, 568.0570253379453, 568.2045136808418, 7019.0, 7275.126984761862, 7359.411247100219, 7476.70093196266, 7543.509671871311, 521.272824409946, 524.3524247554268, 556.5686592890543, 580.9803794340904, 600.1717629671026, 3407.4207384744655, 3464.5149679044953, 3498.3877821381157, 3500.982495087508, 3517.525134106914, 91467.0, 95601.52199239099, 98171.05486835407, 99326.50336748414, 99822.5008874951, 46561.30158047632, 56242.71878640581, 83885.58057209797, 87559.56220218858, 90368.09631903729, 60849.0, 73431.63284183652, 77764.61117694376, 77923.61038194773, 78654.0, 6466.4503517201565, 6772.5936443813525, 6820.420264277592, 6850.9314906850295, 6923.6192009439965, 45681.31478027766, 46238.53761462343, 46888.53111468842, 47018.52981470142, 49133.0, 53965.73017134889, 55212.0, 56645.71677141588, 59162.70418647879, 61463.0, 10115.645952391631, 10502.894971050191, 10527.473626318806, 10600.840987385041, 10620.256582039183, 11499.0, 11554.537818487166, 11891.583794567148, 12384.071194660257, 12528.87471125277, 10796.460176991277, 10866.61966831157, 11308.886911130783, 11310.886891130984, 11322.26405283654, 10181.134603558781, 10477.16182705397, 10499.212559057949, 10728.892711073102, 10942.617008404668, 10787.406692631666, 10839.13286937059, 11061.612843550438, 11332.943335283271, 11360.375179364884, 9264.094573236214, 9378.68109489497, 9558.808823823623, 9791.902080979384, 9919.305648604528, 8850.06172253341, 9202.815943681222, 9359.85960210611, 9662.3719458235, 9682.225421966084], "value": 6177.755247385988, "unit": "score"}], "name": "dromaeo_css", "value": 6283.087334072304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 115.0, 113.0, 112.0, 113.0, 113.0, 114.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 120.0, 133.0, 188.0, 132.0, 127.0, 158.0, 124.0, 160.0, 128.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 161.0, 163.0, 162.0, 161.0, 160.0, 163.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 119.0, 75.0, 76.0, 86.0, 75.0, 104.0, 75.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 121.0, 85.0, 85.0, 85.0, 123.0, 86.0, 84.0, 122.0], "value": 95.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 99.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 63.0, 61.0, 62.0, 71.0, 62.0, 61.0, 60.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 65.0, 66.0, 65.0, 67.0, 69.0, 66.0], "value": 66.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 78.0, 78.0, 79.0, 79.0, 79.0, 79.0, 79.0, 80.0, 80.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 127.0, 129.0, 129.0, 128.0, 129.0, 127.0, 206.0, 130.0], "value": 136.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 167.0, 165.0, 163.0, 165.0, 161.0, 164.0, 165.0, 166.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 65.0, 66.0, 67.0, 66.0, 66.0, 67.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1528.9, "alertThreshold": 2.0}]} 00:00:21 INFO - Return code: 0 00:00:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:00:21 INFO - # TBPL SUCCESS # 00:00:21 INFO - Running post-action listener: _resource_record_post_action 00:00:21 INFO - [mozharness: 2016-07-29 07:00:21.542819Z] Finished run-tests step (success) 00:00:21 INFO - Running post-run listener: _resource_record_post_run 00:00:21 INFO - Total resource usage - Wall time: 1201s; CPU: 13.0%; Read bytes: 0; Write bytes: 552271872; Read time: 0; Write time: 628072 00:00:21 INFO - TinderboxPrint: CPU usage
13.0% 00:00:21 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 00:00:21 INFO - TinderboxPrint: I/O write bytes / time
552,271,872 / 628,072 00:00:21 INFO - TinderboxPrint: CPU idle
8,339.8 (87.0%) 00:00:21 INFO - TinderboxPrint: CPU user
1,200.8 (12.5%) 00:00:21 INFO - TinderboxPrint: Swap in / out
0 / 0 00:00:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 262721536; Read time: 0; Write time: 383376 00:00:21 INFO - run-tests - Wall time: 1185s; CPU: 13.0%; Read bytes: 0; Write bytes: 289550336; Read time: 0; Write time: 244696 00:00:22 INFO - Running post-run listener: _upload_blobber_files 00:00:22 INFO - Blob upload gear active. 00:00:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:00:22 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 00:00:22 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'] 00:00:22 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 00:00:22 INFO - (blobuploader) - INFO - Open directory for files ... 00:00:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:00:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:00:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:00:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:25 INFO - (blobuploader) - INFO - Done attempting. 00:00:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:00:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:00:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:00:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:26 INFO - (blobuploader) - INFO - Done attempting. 00:00:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:00:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:00:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:00:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:00:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:00:27 INFO - (blobuploader) - INFO - Done attempting. 00:00:27 INFO - (blobuploader) - INFO - Iteration through files over. 00:00:27 INFO - Return code: 0 00:00:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:00:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:00:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/898f9a1ae8cd85c183f260908b24397f67bacc558193c84cd1b0037166ab60f6ef9932ef3f7aac0d7b017ea38f72b7eed55fc988cdc72ef1923715e7e7672009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/212e6eabfe96d798523b395c8400719554cd7062b3d4ec33c50bc62cfb5ff5c4909438af1eb597b34f07b5338edd5e53b66b18e3574763c097d66e17deccea92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/13c24daa4c86a79df25d9d9918ab8cf2b48d0ff3db3c538c074327f11367350ed5630bb34028029cce052ef2672895f2dc5d21183c5067b0a9a83ce22bb01cd1"} 00:00:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:00:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:00:27 INFO - Contents: 00:00:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/898f9a1ae8cd85c183f260908b24397f67bacc558193c84cd1b0037166ab60f6ef9932ef3f7aac0d7b017ea38f72b7eed55fc988cdc72ef1923715e7e7672009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/212e6eabfe96d798523b395c8400719554cd7062b3d4ec33c50bc62cfb5ff5c4909438af1eb597b34f07b5338edd5e53b66b18e3574763c097d66e17deccea92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/13c24daa4c86a79df25d9d9918ab8cf2b48d0ff3db3c538c074327f11367350ed5630bb34028029cce052ef2672895f2dc5d21183c5067b0a9a83ce22bb01cd1"} 00:00:27 INFO - Running post-run listener: copy_logs_to_upload_dir 00:00:27 INFO - Copying logs to upload dir... 00:00:27 INFO - mkdir: /builds/slave/test/build/upload/logs 00:00:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.647485 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-07-29 00:00:26.844150) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 00:00:26.847409) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354 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/898f9a1ae8cd85c183f260908b24397f67bacc558193c84cd1b0037166ab60f6ef9932ef3f7aac0d7b017ea38f72b7eed55fc988cdc72ef1923715e7e7672009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/212e6eabfe96d798523b395c8400719554cd7062b3d4ec33c50bc62cfb5ff5c4909438af1eb597b34f07b5338edd5e53b66b18e3574763c097d66e17deccea92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/13c24daa4c86a79df25d9d9918ab8cf2b48d0ff3db3c538c074327f11367350ed5630bb34028029cce052ef2672895f2dc5d21183c5067b0a9a83ce22bb01cd1"} build_url:https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/Ct2wcF_nSKy_GK46fDbNxQ/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/898f9a1ae8cd85c183f260908b24397f67bacc558193c84cd1b0037166ab60f6ef9932ef3f7aac0d7b017ea38f72b7eed55fc988cdc72ef1923715e7e7672009", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/212e6eabfe96d798523b395c8400719554cd7062b3d4ec33c50bc62cfb5ff5c4909438af1eb597b34f07b5338edd5e53b66b18e3574763c097d66e17deccea92", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/13c24daa4c86a79df25d9d9918ab8cf2b48d0ff3db3c538c074327f11367350ed5630bb34028029cce052ef2672895f2dc5d21183c5067b0a9a83ce22bb01cd1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-29 00:00:26.872527) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 00:00:26.872854) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1469774386.349431-560927354 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004480 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-29 00:00:26.957776) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 00:00:26.958193) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-29 00:00:26.958599) ========= ========= Total master_lag: 0.17 =========