builder: autoland_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-021 starttime: 1470196560.42 results: success (0) buildid: 20160802192350 builduid: 512cf77b247b43c39d510ac15ccf6583 revision: 238564059ef3b6fdbeda63eeedc639d123827c08 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.435722) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.436499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.436832) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.460872) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.461259) ========= 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=df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-02 20:56:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 21.8M=0.001s 2016-08-02 20:56:00 (21.8 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094322 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.572048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.572762) ========= 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=df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017766 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.626614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:00.627315) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 238564059ef3b6fdbeda63eeedc639d123827c08 --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 238564059ef3b6fdbeda63eeedc639d123827c08 --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=df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-02 20:56:00,699 truncating revision to first 12 chars 2016-08-02 20:56:00,699 Setting DEBUG logging. 2016-08-02 20:56:00,699 attempt 1/10 2016-08-02 20:56:00,699 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/238564059ef3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 20:56:00,995 unpacking tar archive at: autoland-238564059ef3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641629 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:01.283747) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:01.284072) ========= script_repo_revision: 238564059ef3b6fdbeda63eeedc639d123827c08 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:01.284527) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:01.284867) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 20:56:01.296309) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-08-02 20:56:01.296864) ========= /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=df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:56:01 INFO - MultiFileLogger online at 20160802 20:56:01 in /builds/slave/test 20:56:01 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 20:56:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:56:01 INFO - {'append_to_log': False, 20:56:01 INFO - 'base_work_dir': '/builds/slave/test', 20:56:01 INFO - 'blob_upload_branch': 'Autoland-Non-PGO', 20:56:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:56:01 INFO - 'branch': 'Autoland-Non-PGO', 20:56:01 INFO - 'buildbot_json_path': 'buildprops.json', 20:56:01 INFO - 'config_files': ('talos/linux_config.py',), 20:56:01 INFO - 'default_actions': ('clobber', 20:56:01 INFO - 'read-buildbot-config', 20:56:01 INFO - 'download-and-extract', 20:56:01 INFO - 'populate-webroot', 20:56:01 INFO - 'create-virtualenv', 20:56:01 INFO - 'install', 20:56:01 INFO - 'run-tests'), 20:56:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:56:01 INFO - 'download_minidump_stackwalk': True, 20:56:01 INFO - 'download_symbols': 'ondemand', 20:56:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:56:01 INFO - 'tooltool.py': '/tools/tooltool.py', 20:56:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:56:01 INFO - '/tools/misc-python/virtualenv.py')}, 20:56:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:56:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:56:01 INFO - 'installer_path': 'installer.exe', 20:56:01 INFO - 'log_level': 'info', 20:56:01 INFO - 'log_name': 'talos', 20:56:01 INFO - 'log_to_console': True, 20:56:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:56:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:56:01 INFO - 'opt_config_files': (), 20:56:01 INFO - 'pip_index': False, 20:56:01 INFO - 'sps_profile': False, 20:56:01 INFO - 'sps_profile_interval': 0, 20:56:01 INFO - 'suite': 'dromaeojs', 20:56:01 INFO - 'system_bits': '32', 20:56:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:56:01 INFO - 'title': 'talos-linux64-ix-021', 20:56:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:56:01 INFO - 'use_talos_json': True, 20:56:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:56:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:56:01 INFO - 'work_dir': 'build'} 20:56:01 INFO - [mozharness: 2016-08-03 03:56:01.458409Z] Running clobber step. 20:56:01 INFO - Running pre-action listener: _resource_record_pre_action 20:56:01 INFO - Running main action method: clobber 20:56:01 INFO - rmtree: /builds/slave/test/build 20:56:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:56:02 INFO - Running post-action listener: _resource_record_post_action 20:56:02 INFO - [mozharness: 2016-08-03 03:56:02.428825Z] Finished clobber step (success) 20:56:02 INFO - [mozharness: 2016-08-03 03:56:02.428925Z] Running read-buildbot-config step. 20:56:02 INFO - Running pre-action listener: _resource_record_pre_action 20:56:02 INFO - Running main action method: read_buildbot_config 20:56:02 INFO - Using buildbot properties: 20:56:02 INFO - { 20:56:02 INFO - "project": "", 20:56:02 INFO - "product": "firefox", 20:56:02 INFO - "script_repo_revision": "production", 20:56:02 INFO - "builddir": "autoland_ubuntu64_hw_test-dromaeojs", 20:56:02 INFO - "repository": "", 20:56:02 INFO - "buildername": "Ubuntu HW 12.04 x64 autoland talos dromaeojs", 20:56:02 INFO - "buildid": "20160802192350", 20:56:02 INFO - "pgo_build": "False", 20:56:02 INFO - "basedir": "/builds/slave/test", 20:56:02 INFO - "buildnumber": 130, 20:56:02 INFO - "slavename": "talos-linux64-ix-021", 20:56:02 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 20:56:02 INFO - "platform": "ubuntu64_hw", 20:56:02 INFO - "scheduler": "tests-autoland-linux64-talos", 20:56:02 INFO - "branch": "autoland", 20:56:02 INFO - "repo_path": "integration/autoland", 20:56:02 INFO - "revision": "238564059ef3b6fdbeda63eeedc639d123827c08", 20:56:02 INFO - "stage_platform": "linux64", 20:56:02 INFO - "builduid": "512cf77b247b43c39d510ac15ccf6583", 20:56:02 INFO - "slavebuilddir": "test" 20:56:02 INFO - } 20:56:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:56:02 INFO - Running post-action listener: _resource_record_post_action 20:56:02 INFO - [mozharness: 2016-08-03 03:56:02.431350Z] Finished read-buildbot-config step (success) 20:56:02 INFO - [mozharness: 2016-08-03 03:56:02.431439Z] Running download-and-extract step. 20:56:02 INFO - Running pre-action listener: _resource_record_pre_action 20:56:02 INFO - Running main action method: download_and_extract 20:56:02 INFO - mkdir: /builds/slave/test/build/tests 20:56:02 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')]} 20:56:02 INFO - https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 20:56:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:56:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:56:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 20:56:09 INFO - Downloaded 1361 bytes. 20:56:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:56:09 INFO - Using the following test package requirements: 20:56:09 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:56:09 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:56:09 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:56:09 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'jsshell-linux-x86_64.zip'], 20:56:09 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:56:09 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:56:09 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:56:09 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:56:09 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:56:09 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:56:09 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:56:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:56:09 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')]} 20:56:09 INFO - https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:56:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:56:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:56:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:56:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:56:13 INFO - Downloaded 20239488 bytes. 20:56:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:56:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:56:14 INFO - Return code: 0 20:56:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 20:56:14 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')]} 20:56:14 INFO - https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 20:56:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:56:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:56:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:56:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 20:56:19 INFO - Downloaded 20239488 bytes. 20:56:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:56:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 20:56:20 INFO - Return code: 0 20:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:20 INFO - https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 20:56:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:56:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:56:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:56:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 20:56:21 INFO - Downloaded 14413723 bytes. 20:56:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:56:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 20:56:21 INFO - Return code: 0 20:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:21 INFO - https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 20:56:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:56:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:56:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 20:56:22 INFO - Downloaded 55635629 bytes. 20:56:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:56:22 INFO - mkdir: /builds/slave/test/properties 20:56:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:56:22 INFO - Writing to file /builds/slave/test/properties/build_url 20:56:22 INFO - Contents: 20:56:22 INFO - build_url:https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:56:23 INFO - Running post-action listener: _resource_record_post_action 20:56:23 INFO - Running post-action listener: set_extra_try_arguments 20:56:23 INFO - [mozharness: 2016-08-03 03:56:23.489574Z] Finished download-and-extract step (success) 20:56:23 INFO - [mozharness: 2016-08-03 03:56:23.489806Z] Running populate-webroot step. 20:56:23 INFO - Running pre-action listener: _resource_record_pre_action 20:56:23 INFO - Running main action method: populate_webroot 20:56:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:56:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:56:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:56:23 INFO - '--tppagecycles', 20:56:23 INFO - '7'], 20:56:23 INFO - 'tests': ['tsvgm']}}, 20:56:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:56:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:56:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:56:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:56:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:56:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:56:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['damp', 'tps']}, 20:56:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'tests': ['damp', 'tps']}, 20:56:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['dromaeo_dom']}, 20:56:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:56:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['basic_compositor_video']}, 20:56:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:56:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['a11yr', 20:56:23 INFO - 'ts_paint', 20:56:23 INFO - 'tpaint', 20:56:23 INFO - 'sessionrestore', 20:56:23 INFO - 'sessionrestore_no_auto_restore', 20:56:23 INFO - 'tabpaint']}, 20:56:23 INFO - 'other-e10s': {'tests': ['a11yr', 20:56:23 INFO - 'ts_paint', 20:56:23 INFO - 'tpaint', 20:56:23 INFO - 'sessionrestore', 20:56:23 INFO - 'sessionrestore_no_auto_restore', 20:56:23 INFO - 'tabpaint']}, 20:56:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:56:23 INFO - 'ts_paint', 20:56:23 INFO - 'tpaint', 20:56:23 INFO - 'sessionrestore', 20:56:23 INFO - 'sessionrestore_no_auto_restore', 20:56:23 INFO - 'tabpaint']}, 20:56:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:56:23 INFO - 'ts_paint', 20:56:23 INFO - 'tpaint', 20:56:23 INFO - 'sessionrestore', 20:56:23 INFO - 'sessionrestore_no_auto_restore', 20:56:23 INFO - 'tabpaint']}, 20:56:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['a11yr', 20:56:23 INFO - 'ts_paint', 20:56:23 INFO - 'tpaint', 20:56:23 INFO - 'sessionrestore', 20:56:23 INFO - 'sessionrestore_no_auto_restore', 20:56:23 INFO - 'tabpaint']}, 20:56:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['a11yr', 20:56:23 INFO - 'ts_paint', 20:56:23 INFO - 'tpaint', 20:56:23 INFO - 'sessionrestore', 20:56:23 INFO - 'sessionrestore_no_auto_restore', 20:56:23 INFO - 'tabpaint']}, 20:56:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['tsvgx', 20:56:23 INFO - 'tsvgr_opacity', 20:56:23 INFO - 'tart', 20:56:23 INFO - 'tscrollx', 20:56:23 INFO - 'cart']}, 20:56:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:56:23 INFO - 'tsvgr_opacity', 20:56:23 INFO - 'tart', 20:56:23 INFO - 'tscrollx', 20:56:23 INFO - 'cart']}, 20:56:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'talos_options': ['--disable-e10s'], 20:56:23 INFO - 'tests': ['tp5o']}, 20:56:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'tests': ['tp5o']}, 20:56:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'talos_options': ['--disable-e10s', 20:56:23 INFO - '--xperf_path', 20:56:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:56:23 INFO - 'tests': ['tp5n']}, 20:56:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:56:23 INFO - 'talos_options': ['--xperf_path', 20:56:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:56:23 INFO - 'tests': ['tp5n']}}, 20:56:23 INFO - 'talos.zip': {'path': '', 20:56:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:56:23 INFO - Running post-action listener: _resource_record_post_action 20:56:23 INFO - [mozharness: 2016-08-03 03:56:23.510408Z] Finished populate-webroot step (success) 20:56:23 INFO - [mozharness: 2016-08-03 03:56:23.510497Z] Running create-virtualenv step. 20:56:23 INFO - Running pre-action listener: _resource_record_pre_action 20:56:23 INFO - Running main action method: create_virtualenv 20:56:23 INFO - Creating virtualenv /builds/slave/test/build/venv 20:56:23 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 20:56:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:56:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:56:23 INFO - Using real prefix '/usr' 20:56:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:56:24 INFO - Installing distribute.............................................................................................................................................................................................done. 20:56:26 INFO - Installing pip.................done. 20:56:26 INFO - Return code: 0 20:56:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:26 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:26 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 20:56:26 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 20:56:26 INFO - Using env: {'DISPLAY': ':0', 20:56:26 INFO - 'HOME': '/home/cltbld', 20:56:26 INFO - 'LANG': 'en_US.UTF-8', 20:56:26 INFO - 'LANGUAGE': 'en_US:en', 20:56:26 INFO - 'LOGNAME': 'cltbld', 20:56:26 INFO - 'MAIL': '/var/mail/cltbld', 20:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:26 INFO - 'MOZ_NO_REMOTE': '1', 20:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:26 INFO - 'NO_EM_RESTART': '1', 20:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:26 INFO - 'PWD': '/builds/slave/test', 20:56:26 INFO - 'SHELL': '/bin/bash', 20:56:26 INFO - 'SHLVL': '1', 20:56:26 INFO - 'TERM': 'linux', 20:56:26 INFO - 'TMOUT': '86400', 20:56:26 INFO - 'USER': 'cltbld', 20:56:26 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:26 INFO - '_': '/tools/buildbot/bin/python'} 20:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:26 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 20:56:26 INFO - Cleaning up... 20:56:26 INFO - Return code: 0 20:56:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:26 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:26 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 20:56:26 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 20:56:26 INFO - Using env: {'DISPLAY': ':0', 20:56:26 INFO - 'HOME': '/home/cltbld', 20:56:26 INFO - 'LANG': 'en_US.UTF-8', 20:56:26 INFO - 'LANGUAGE': 'en_US:en', 20:56:26 INFO - 'LOGNAME': 'cltbld', 20:56:26 INFO - 'MAIL': '/var/mail/cltbld', 20:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:26 INFO - 'MOZ_NO_REMOTE': '1', 20:56:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:26 INFO - 'NO_EM_RESTART': '1', 20:56:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:26 INFO - 'PWD': '/builds/slave/test', 20:56:26 INFO - 'SHELL': '/bin/bash', 20:56:26 INFO - 'SHLVL': '1', 20:56:26 INFO - 'TERM': 'linux', 20:56:26 INFO - 'TMOUT': '86400', 20:56:26 INFO - 'USER': 'cltbld', 20:56:26 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:26 INFO - '_': '/tools/buildbot/bin/python'} 20:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:26 INFO - Downloading/unpacking psutil>=3.1.1 20:56:26 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 20:56:26 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 20:56:26 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 20:56:26 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 20:56:26 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 20:56:26 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 20:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:56:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:56:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:56:28 INFO - Installing collected packages: psutil 20:56:28 INFO - Running setup.py install for psutil 20:56:28 INFO - building 'psutil._psutil_linux' extension 20:56:28 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 20:56:29 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 20:56:29 INFO - building 'psutil._psutil_posix' extension 20:56:29 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 20:56:29 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 20:56:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:56:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:56:29 INFO - Successfully installed psutil 20:56:29 INFO - Cleaning up... 20:56:29 INFO - Return code: 0 20:56:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:29 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:29 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 20:56:29 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 20:56:29 INFO - Using env: {'DISPLAY': ':0', 20:56:29 INFO - 'HOME': '/home/cltbld', 20:56:29 INFO - 'LANG': 'en_US.UTF-8', 20:56:29 INFO - 'LANGUAGE': 'en_US:en', 20:56:29 INFO - 'LOGNAME': 'cltbld', 20:56:29 INFO - 'MAIL': '/var/mail/cltbld', 20:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:29 INFO - 'MOZ_NO_REMOTE': '1', 20:56:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:29 INFO - 'NO_EM_RESTART': '1', 20:56:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:29 INFO - 'PWD': '/builds/slave/test', 20:56:29 INFO - 'SHELL': '/bin/bash', 20:56:29 INFO - 'SHLVL': '1', 20:56:29 INFO - 'TERM': 'linux', 20:56:29 INFO - 'TMOUT': '86400', 20:56:29 INFO - 'USER': 'cltbld', 20:56:29 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:29 INFO - '_': '/tools/buildbot/bin/python'} 20:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:56:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:56:31 INFO - Installing collected packages: mozsystemmonitor 20:56:31 INFO - Running setup.py install for mozsystemmonitor 20:56:31 INFO - Successfully installed mozsystemmonitor 20:56:31 INFO - Cleaning up... 20:56:31 INFO - Return code: 0 20:56:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:31 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:31 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 20:56:31 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 20:56:31 INFO - Using env: {'DISPLAY': ':0', 20:56:31 INFO - 'HOME': '/home/cltbld', 20:56:31 INFO - 'LANG': 'en_US.UTF-8', 20:56:31 INFO - 'LANGUAGE': 'en_US:en', 20:56:31 INFO - 'LOGNAME': 'cltbld', 20:56:31 INFO - 'MAIL': '/var/mail/cltbld', 20:56:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:31 INFO - 'MOZ_NO_REMOTE': '1', 20:56:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:31 INFO - 'NO_EM_RESTART': '1', 20:56:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:31 INFO - 'PWD': '/builds/slave/test', 20:56:31 INFO - 'SHELL': '/bin/bash', 20:56:31 INFO - 'SHLVL': '1', 20:56:31 INFO - 'TERM': 'linux', 20:56:31 INFO - 'TMOUT': '86400', 20:56:31 INFO - 'USER': 'cltbld', 20:56:31 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:31 INFO - '_': '/tools/buildbot/bin/python'} 20:56:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:31 INFO - Downloading/unpacking jsonschema==2.5.1 20:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:56:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:56:33 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 20:56:33 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 20:56:33 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 20:56:33 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 20:56:33 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 20:56:33 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 20:56:33 INFO - Downloading functools32-3.2.3-2.tar.gz 20:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:56:33 INFO - warning: no files found matching '*.txt' 20:56:33 INFO - no previously-included directories found matching 'build' 20:56:33 INFO - no previously-included directories found matching 'dist' 20:56:33 INFO - no previously-included directories found matching '.git*' 20:56:33 INFO - Installing collected packages: jsonschema, functools32 20:56:34 INFO - Running setup.py install for functools32 20:56:34 INFO - warning: no files found matching '*.txt' 20:56:34 INFO - no previously-included directories found matching 'build' 20:56:34 INFO - no previously-included directories found matching 'dist' 20:56:34 INFO - no previously-included directories found matching '.git*' 20:56:34 INFO - Successfully installed jsonschema functools32 20:56:34 INFO - Cleaning up... 20:56:34 INFO - Return code: 0 20:56:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 20:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 20:56:34 INFO - Using env: {'DISPLAY': ':0', 20:56:34 INFO - 'HOME': '/home/cltbld', 20:56:34 INFO - 'LANG': 'en_US.UTF-8', 20:56:34 INFO - 'LANGUAGE': 'en_US:en', 20:56:34 INFO - 'LOGNAME': 'cltbld', 20:56:34 INFO - 'MAIL': '/var/mail/cltbld', 20:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:34 INFO - 'MOZ_NO_REMOTE': '1', 20:56:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:34 INFO - 'NO_EM_RESTART': '1', 20:56:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:34 INFO - 'PWD': '/builds/slave/test', 20:56:34 INFO - 'SHELL': '/bin/bash', 20:56:34 INFO - 'SHLVL': '1', 20:56:34 INFO - 'TERM': 'linux', 20:56:34 INFO - 'TMOUT': '86400', 20:56:34 INFO - 'USER': 'cltbld', 20:56:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:34 INFO - '_': '/tools/buildbot/bin/python'} 20:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:56:34 INFO - Cleaning up... 20:56:34 INFO - Return code: 0 20:56:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:56:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:56:34 INFO - Using env: {'DISPLAY': ':0', 20:56:34 INFO - 'HOME': '/home/cltbld', 20:56:34 INFO - 'LANG': 'en_US.UTF-8', 20:56:34 INFO - 'LANGUAGE': 'en_US:en', 20:56:34 INFO - 'LOGNAME': 'cltbld', 20:56:34 INFO - 'MAIL': '/var/mail/cltbld', 20:56:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:34 INFO - 'MOZ_NO_REMOTE': '1', 20:56:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:34 INFO - 'NO_EM_RESTART': '1', 20:56:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:34 INFO - 'PWD': '/builds/slave/test', 20:56:34 INFO - 'SHELL': '/bin/bash', 20:56:34 INFO - 'SHLVL': '1', 20:56:34 INFO - 'TERM': 'linux', 20:56:34 INFO - 'TMOUT': '86400', 20:56:34 INFO - 'USER': 'cltbld', 20:56:34 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:34 INFO - '_': '/tools/buildbot/bin/python'} 20:56:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:34 INFO - Downloading/unpacking blobuploader==1.2.4 20:56:34 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 20:56:34 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 20:56:34 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 20:56:34 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 20:56:34 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 20:56:34 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 20:56:36 INFO - Downloading blobuploader-1.2.4.tar.gz 20:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:56:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:56:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:56:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:56:37 INFO - Downloading docopt-0.6.1.tar.gz 20:56:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:56:37 INFO - Installing collected packages: blobuploader, requests, docopt 20:56:37 INFO - Running setup.py install for blobuploader 20:56:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:56:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:56:37 INFO - Running setup.py install for requests 20:56:37 INFO - Running setup.py install for docopt 20:56:37 INFO - Successfully installed blobuploader requests docopt 20:56:37 INFO - Cleaning up... 20:56:37 INFO - Return code: 0 20:56:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:56:37 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')]} 20:56:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:37 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')]} 20:56:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:37 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:37 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 20:56:37 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 20:56:37 INFO - Using env: {'DISPLAY': ':0', 20:56:37 INFO - 'HOME': '/home/cltbld', 20:56:37 INFO - 'LANG': 'en_US.UTF-8', 20:56:37 INFO - 'LANGUAGE': 'en_US:en', 20:56:37 INFO - 'LOGNAME': 'cltbld', 20:56:37 INFO - 'MAIL': '/var/mail/cltbld', 20:56:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:37 INFO - 'MOZ_NO_REMOTE': '1', 20:56:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:37 INFO - 'NO_EM_RESTART': '1', 20:56:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:37 INFO - 'PWD': '/builds/slave/test', 20:56:37 INFO - 'SHELL': '/bin/bash', 20:56:37 INFO - 'SHLVL': '1', 20:56:37 INFO - 'TERM': 'linux', 20:56:37 INFO - 'TMOUT': '86400', 20:56:37 INFO - 'USER': 'cltbld', 20:56:37 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:37 INFO - '_': '/tools/buildbot/bin/python'} 20:56:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:56:38 INFO - Running setup.py (path:/tmp/pip-GUnsSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:56:38 INFO - Running setup.py (path:/tmp/pip-PXPMkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:56:38 INFO - Running setup.py (path:/tmp/pip-FOeN5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:56:38 INFO - Running setup.py (path:/tmp/pip-HZzskQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:56:38 INFO - Running setup.py (path:/tmp/pip-GZCYaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:56:38 INFO - Running setup.py (path:/tmp/pip-fulIag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:56:38 INFO - Running setup.py (path:/tmp/pip-Fh9A1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:56:38 INFO - Running setup.py (path:/tmp/pip-Y_DL7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:56:38 INFO - Running setup.py (path:/tmp/pip-TnugKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:56:38 INFO - Running setup.py (path:/tmp/pip-FLHmAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:56:38 INFO - Running setup.py (path:/tmp/pip-PakdB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:56:38 INFO - Running setup.py (path:/tmp/pip-y8DP86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:56:38 INFO - Running setup.py (path:/tmp/pip-C28PIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:56:39 INFO - Running setup.py (path:/tmp/pip-1daXec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:56:39 INFO - Running setup.py (path:/tmp/pip-eMm5fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:56:39 INFO - Running setup.py (path:/tmp/pip-UkvyLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:56:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:56:39 INFO - Running setup.py (path:/tmp/pip-rzEYmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:56:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:56:39 INFO - Running setup.py install for manifestparser 20:56:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:56:39 INFO - Running setup.py install for mozcrash 20:56:39 INFO - Running setup.py install for mozdebug 20:56:39 INFO - Running setup.py install for mozdevice 20:56:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:56:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:56:39 INFO - Running setup.py install for mozfile 20:56:39 INFO - Running setup.py install for mozhttpd 20:56:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:56:39 INFO - Running setup.py install for mozinfo 20:56:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Running setup.py install for mozInstall 20:56:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Running setup.py install for mozleak 20:56:40 INFO - Running setup.py install for mozlog 20:56:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Running setup.py install for moznetwork 20:56:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Running setup.py install for mozprocess 20:56:40 INFO - Running setup.py install for mozprofile 20:56:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Running setup.py install for mozrunner 20:56:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:56:40 INFO - Running setup.py install for mozscreenshot 20:56:40 INFO - Running setup.py install for moztest 20:56:41 INFO - Running setup.py install for mozversion 20:56:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:56:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:56:41 INFO - Cleaning up... 20:56:41 INFO - Return code: 0 20:56:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:41 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:41 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 20:56:41 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 20:56:41 INFO - Using env: {'DISPLAY': ':0', 20:56:41 INFO - 'HOME': '/home/cltbld', 20:56:41 INFO - 'LANG': 'en_US.UTF-8', 20:56:41 INFO - 'LANGUAGE': 'en_US:en', 20:56:41 INFO - 'LOGNAME': 'cltbld', 20:56:41 INFO - 'MAIL': '/var/mail/cltbld', 20:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:41 INFO - 'MOZ_NO_REMOTE': '1', 20:56:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:41 INFO - 'NO_EM_RESTART': '1', 20:56:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:41 INFO - 'PWD': '/builds/slave/test', 20:56:41 INFO - 'SHELL': '/bin/bash', 20:56:41 INFO - 'SHLVL': '1', 20:56:41 INFO - 'TERM': 'linux', 20:56:41 INFO - 'TMOUT': '86400', 20:56:41 INFO - 'USER': 'cltbld', 20:56:41 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:41 INFO - '_': '/tools/buildbot/bin/python'} 20:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:56:41 INFO - Running setup.py (path:/tmp/pip-2QwfI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:56:41 INFO - Running setup.py (path:/tmp/pip-TCa2DI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:56:41 INFO - Running setup.py (path:/tmp/pip-mJaTit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:56:41 INFO - Running setup.py (path:/tmp/pip-knr2EC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:56:41 INFO - Running setup.py (path:/tmp/pip-FDMofQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:56:41 INFO - Running setup.py (path:/tmp/pip-Bbb4s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:56:41 INFO - Running setup.py (path:/tmp/pip-V3DC7w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:56:41 INFO - Running setup.py (path:/tmp/pip-k77MxU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:56:41 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)) 20:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:56:41 INFO - Running setup.py (path:/tmp/pip-v6dGJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:56:42 INFO - Running setup.py (path:/tmp/pip-cDBaJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:56:42 INFO - Running setup.py (path:/tmp/pip-8UfM8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:56:42 INFO - Running setup.py (path:/tmp/pip-HunCFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:56:42 INFO - Running setup.py (path:/tmp/pip-nhdFj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:56:42 INFO - Running setup.py (path:/tmp/pip-OwqeQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:56:42 INFO - Running setup.py (path:/tmp/pip-VLkefe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:56:42 INFO - Running setup.py (path:/tmp/pip-9XuQw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:56:42 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)) 20:56:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:56:42 INFO - Running setup.py (path:/tmp/pip-Xz8OPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:56:42 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)) 20:56:42 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)) 20:56:42 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)) 20:56:42 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)) 20:56:42 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)) 20:56:42 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)) 20:56:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:56:42 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 20:56:42 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 20:56:42 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 20:56:42 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 20:56:42 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 20:56:42 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 20:56:44 INFO - Downloading blessings-1.6.tar.gz 20:56:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:56:44 INFO - Installing collected packages: blessings 20:56:44 INFO - Running setup.py install for blessings 20:56:44 INFO - Successfully installed blessings 20:56:44 INFO - Cleaning up... 20:56:44 INFO - Return code: 0 20:56:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:56:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:56:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:56:45 INFO - Reading from file tmpfile_stdout 20:56:45 INFO - Current package versions: 20:56:45 INFO - argparse == 1.2.1 20:56:45 INFO - blessings == 1.6 20:56:45 INFO - blobuploader == 1.2.4 20:56:45 INFO - docopt == 0.6.1 20:56:45 INFO - functools32 == 3.2.3-2 20:56:45 INFO - jsonschema == 2.5.1 20:56:45 INFO - manifestparser == 1.1 20:56:45 INFO - mozInstall == 1.12 20:56:45 INFO - mozcrash == 0.17 20:56:45 INFO - mozdebug == 0.1 20:56:45 INFO - mozdevice == 0.48 20:56:45 INFO - mozfile == 1.2 20:56:45 INFO - mozhttpd == 0.7 20:56:45 INFO - mozinfo == 0.9 20:56:45 INFO - mozleak == 0.1 20:56:45 INFO - mozlog == 3.3 20:56:45 INFO - moznetwork == 0.27 20:56:45 INFO - mozprocess == 0.23 20:56:45 INFO - mozprofile == 0.28 20:56:45 INFO - mozrunner == 6.12 20:56:45 INFO - mozscreenshot == 0.1 20:56:45 INFO - mozsystemmonitor == 0.3 20:56:45 INFO - moztest == 0.7 20:56:45 INFO - mozversion == 1.4 20:56:45 INFO - psutil == 3.1.1 20:56:45 INFO - requests == 1.2.3 20:56:45 INFO - wsgiref == 0.1.2 20:56:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:56:45 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')]} 20:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:45 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')]} 20:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:45 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:45 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 20:56:45 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 20:56:45 INFO - Using env: {'DISPLAY': ':0', 20:56:45 INFO - 'HOME': '/home/cltbld', 20:56:45 INFO - 'LANG': 'en_US.UTF-8', 20:56:45 INFO - 'LANGUAGE': 'en_US:en', 20:56:45 INFO - 'LOGNAME': 'cltbld', 20:56:45 INFO - 'MAIL': '/var/mail/cltbld', 20:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:45 INFO - 'MOZ_NO_REMOTE': '1', 20:56:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:45 INFO - 'NO_EM_RESTART': '1', 20:56:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:45 INFO - 'PWD': '/builds/slave/test', 20:56:45 INFO - 'SHELL': '/bin/bash', 20:56:45 INFO - 'SHLVL': '1', 20:56:45 INFO - 'TERM': 'linux', 20:56:45 INFO - 'TMOUT': '86400', 20:56:45 INFO - 'USER': 'cltbld', 20:56:45 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:45 INFO - '_': '/tools/buildbot/bin/python'} 20:56:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 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)) 20:56:45 INFO - Cleaning up... 20:56:45 INFO - Return code: 0 20:56:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:56:45 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')]} 20:56:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:56:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:45 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')]} 20:56:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:56:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:56:45 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 0x2289030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23ec8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, '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': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', '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 20:56:45 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 20:56:45 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 20:56:45 INFO - Using env: {'DISPLAY': ':0', 20:56:45 INFO - 'HOME': '/home/cltbld', 20:56:45 INFO - 'LANG': 'en_US.UTF-8', 20:56:45 INFO - 'LANGUAGE': 'en_US:en', 20:56:45 INFO - 'LOGNAME': 'cltbld', 20:56:45 INFO - 'MAIL': '/var/mail/cltbld', 20:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:56:45 INFO - 'MOZ_NO_REMOTE': '1', 20:56:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:56:45 INFO - 'NO_EM_RESTART': '1', 20:56:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:56:45 INFO - 'PWD': '/builds/slave/test', 20:56:45 INFO - 'SHELL': '/bin/bash', 20:56:45 INFO - 'SHLVL': '1', 20:56:45 INFO - 'TERM': 'linux', 20:56:45 INFO - 'TMOUT': '86400', 20:56:45 INFO - 'USER': 'cltbld', 20:56:45 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:56:45 INFO - '_': '/tools/buildbot/bin/python'} 20:56:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:56:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:56:45 INFO - Cleaning up... 20:56:45 INFO - Return code: 0 20:56:45 INFO - Running post-action listener: _resource_record_post_action 20:56:45 INFO - Running post-action listener: _start_resource_monitoring 20:56:45 INFO - Starting resource monitoring. 20:56:45 INFO - [mozharness: 2016-08-03 03:56:45.488315Z] Finished create-virtualenv step (success) 20:56:45 INFO - [mozharness: 2016-08-03 03:56:45.488749Z] Running install step. 20:56:45 INFO - Running pre-action listener: _resource_record_pre_action 20:56:45 INFO - Running main action method: install 20:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:56:45 INFO - Reading from file tmpfile_stdout 20:56:45 INFO - Detecting whether we're running mozinstall >=1.0... 20:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:56:45 INFO - Reading from file tmpfile_stdout 20:56:45 INFO - Output received: 20:56:45 INFO - Usage: mozinstall [options] installer 20:56:45 INFO - Options: 20:56:45 INFO - -h, --help show this help message and exit 20:56:45 INFO - -d DEST, --destination=DEST 20:56:45 INFO - Directory to install application into. [default: 20:56:45 INFO - "/builds/slave/test"] 20:56:45 INFO - --app=APP Application being installed. [default: firefox] 20:56:45 INFO - mkdir: /builds/slave/test/build/application 20:56:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 20:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 20:57:01 INFO - Reading from file tmpfile_stdout 20:57:01 INFO - Output received: 20:57:01 INFO - /builds/slave/test/build/application/firefox/firefox 20:57:01 INFO - Running post-action listener: _resource_record_post_action 20:57:01 INFO - [mozharness: 2016-08-03 03:57:01.855731Z] Finished install step (success) 20:57:01 INFO - [mozharness: 2016-08-03 03:57:01.856002Z] Running run-tests step. 20:57:01 INFO - Running pre-action listener: _resource_record_pre_action 20:57:01 INFO - Running main action method: run_tests 20:57:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:57:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:57:01 INFO - Python 2.7.3 20:57:01 INFO - Return code: 0 20:57:01 INFO - grabbing minidump binary from tooltool 20:57:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:57:01 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 0x25483b0>, '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 0x25492b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2549740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:57:01 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 20:57:01 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 20:57:01 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 20:57:01 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:57:01 INFO - Return code: 0 20:57:01 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:57:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:57:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:57:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:57:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:57:01 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/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 20:57:01 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/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --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 20:57:01 INFO - Using env: {'DISPLAY': ':0', 20:57:01 INFO - 'HOME': '/home/cltbld', 20:57:01 INFO - 'LANG': 'en_US.UTF-8', 20:57:01 INFO - 'LANGUAGE': 'en_US:en', 20:57:01 INFO - 'LOGNAME': 'cltbld', 20:57:01 INFO - 'MAIL': '/var/mail/cltbld', 20:57:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:57:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:57:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:57:01 INFO - 'MOZ_NO_REMOTE': '1', 20:57:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:57:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:57:01 INFO - 'NO_EM_RESTART': '1', 20:57:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:57:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:57:01 INFO - 'PWD': '/builds/slave/test', 20:57:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:57:01 INFO - 'SHELL': '/bin/bash', 20:57:01 INFO - 'SHLVL': '1', 20:57:01 INFO - 'TERM': 'linux', 20:57:01 INFO - 'TMOUT': '86400', 20:57:01 INFO - 'USER': 'cltbld', 20:57:01 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593', 20:57:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:57:01 INFO - '_': '/tools/buildbot/bin/python'} 20:57:01 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/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--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 20:57:02 INFO - mozversion application_buildid: 20160802192350 20:57:02 INFO - mozversion application_changeset: 238564059ef3b6fdbeda63eeedc639d123827c08 20:57:02 INFO - mozversion application_display_name: Nightly 20:57:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:57:02 INFO - mozversion application_name: Firefox 20:57:02 INFO - mozversion application_remotingname: firefox 20:57:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:57:02 INFO - mozversion application_vendor: Mozilla 20:57:02 INFO - mozversion application_version: 51.0a1 20:57:02 INFO - mozversion platform_buildid: 20160802192350 20:57:02 INFO - mozversion platform_changeset: 238564059ef3b6fdbeda63eeedc639d123827c08 20:57:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:57:02 INFO - mozversion platform_version: 51.0a1 20:57:02 INFO - using testdate: 1470196622 20:57:02 INFO - actual date: 1470196622 20:57:02 INFO - starting webserver on 'localhost:40044' 20:57:02 INFO - SUITE-START | Running 2 tests 20:57:02 INFO - TEST-START | dromaeo_css 20:57:02 INFO - operating with platform_type : linux_ 20:57:02 INFO - Initialising browser for dromaeo_css test... 20:57:02 INFO - TEST-INFO | started process 12890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgM9dYa/profile http://localhost:40044/getInfo.html) 20:57:07 INFO - PROCESS | 12890 | __metrics Screen width/height:1600/1200 20:57:07 INFO - PROCESS | 12890 | colorDepth:24 20:57:07 INFO - PROCESS | 12890 | Browser inner width/height: 1024/697 20:57:07 INFO - PROCESS | 12890 | __metrics 20:57:08 INFO - PROCESS | 12890 | 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" 20:57:08 INFO - PROCESS | 12890 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:57:09 INFO - PROCESS | 12890 | console.error: PushService: 20:57:09 INFO - PROCESS | 12890 | stateChangeProcessEnqueue: Error transitioning state 20:57:09 INFO - PROCESS | 12890 | UnknownError 20:57:09 INFO - TEST-INFO | 12890: exit 0 20:57:09 INFO - Browser initialized. 20:57:09 INFO - Running cycle 1/1 for dromaeo_css test... 20:57:09 INFO - TEST-INFO | started process 12970 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpgM9dYa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:57:21 INFO - PROCESS | 12970 | RSS: Main: 157495296 20:57:21 INFO - PROCESS | 12970 | 20:58:12 INFO - PROCESS | 12970 | 1470196692336 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 21:00:25 INFO - PROCESS | 12970 | Cycle 1(1): loaded http://localhost:40044/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40044/tests/dromaeo/cssquery-ext.html) 21:00:26 INFO - PROCESS | 12970 | RSS: Main: 232013824 21:00:26 INFO - PROCESS | 12970 | 21:03:29 INFO - PROCESS | 12970 | Cycle 1(1): loaded http://localhost:40044/tests/dromaeo/cssquery-ext.html (next: http://localhost:40044/tests/dromaeo/cssquery-jquery.html) 21:03:30 INFO - PROCESS | 12970 | RSS: Main: 239194112 21:03:30 INFO - PROCESS | 12970 | 21:06:32 INFO - PROCESS | 12970 | Cycle 1(1): loaded http://localhost:40044/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40044/tests/dromaeo/cssquery-mootools.html) 21:06:32 INFO - PROCESS | 12970 | RSS: Main: 213000192 21:06:32 INFO - PROCESS | 12970 | 21:09:34 INFO - PROCESS | 12970 | Cycle 1(1): loaded http://localhost:40044/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40044/tests/dromaeo/cssquery-prototype.html) 21:09:35 INFO - PROCESS | 12970 | RSS: Main: 189247488 21:09:35 INFO - PROCESS | 12970 | 21:12:38 INFO - PROCESS | 12970 | Cycle 1(1): loaded http://localhost:40044/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40044/tests/dromaeo/cssquery-yui.html) 21:12:38 INFO - PROCESS | 12970 | RSS: Main: 188952576 21:12:38 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | Cycle 1(1): loaded http://localhost:40044/tests/dromaeo/cssquery-yui.html (next: http://localhost:40044/tests/dromaeo/cssquery-dojo.html) 21:15:42 INFO - PROCESS | 12970 | RSS: Main: 282738688 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | __start_tp_report 21:15:42 INFO - PROCESS | 12970 | _x_x_mozilla_page_load 21:15:42 INFO - PROCESS | 12970 | _x_x_mozilla_page_load_details 21:15:42 INFO - PROCESS | 12970 | |i|pagename|runs| 21:15:42 INFO - PROCESS | 12970 | |0;dojo.html;2617.1625079974406;2623.278598385445;2648.801339899508;2652.7479889410497;2656.269525880383;4468.19572476954;4539.364488971547;4683.976580117099;4703.2709929960865;4735.573798358151;4581.977090114553;4584.564466375689;4662.79017444215;4708.246680531116;4736.194846876031;4279.122779830127;4339.457567804025;4363.432753742025;4371.235033869076;4372.650187984969;4340.696151269412;4377.6935614507;4405.933910991338;4421.602055814976;4468.195724769556;4358.8910277243;4380.8685739427865;4400.867973960786;4427.77861106945;4429.047754732732;4194.056337324108;4236.343366778124;4273.551277115889;4306.397104405386;4318.935215971763;4276.144771045803;4278.336857787048;4302.311630139163;4325.199838029943;4392.143532011259;4731.550502702238;4744.383230180055;4788.664793464459;4789.544993225638;4790.760461976922;4879.560839524424;4964.15609346412;5126.948730512684;5136.922946155811;5138.229265610188;4974.626902982291;5092.8472145835685;5092.9236061459105;5128.384593848762;5141.485851414828;4735.384400028009;4762.285657151455;4775.570198682135;4791.401074865642;4798.928016079762;4977.751112444364;5148.176291793295;5184.170532714747;5238.842834714965;5251.76367063483;4994.300797888299;5025.095482813055;5035.697858128524;5043.52086551777;5046.7476626168545;5088.771005304696;5091.719955402415;5092.159793634009;5143.871403214949;5147.613928955268;4806.2310030394965;4978.950210497921;5003.2995380646735;5033.5469807716745;5040.521150490697;4988.401391833003;5034.89930201394;5040;5066.923996140061;5081.593472522263;4246.426732391088;4264.381664658607;4266.06146647737;4301.462317210348;4318.546552611931;3658.0854786303425;3660.7254455915913;3701.5373078365205;3714;3778.5465744110884;4331.5884990925815;4374.409454723572;4374.496932852762;4410.07388448423;4410.272305069691;5014.52362025607;5017.247412888096;5022.271770593244;5023.673461225008;5067.163917953497;4378.146261478981;4383.145286669069;4385.386045953505;4386.780660966939;4427.645788336926;4372.300431930876;4422.115576884636;4428.4243048403505;4428.512863585003;4507.008458139204;4652.604528615106;4654.627629789541;4692.249240121564;4698.694584851974;4705.60002399793;4847.80608775645;4884.853454396374;4893.119238537098;4938.185199442052;4943.728094954812;4674.298855171752;4785.066911952275;4834.516548345137;4847.127517046965;4847.781849816837;4902.1911384621135;4909.558139767438;4913.508649135058;4913.656044077024;4959.132151873336;4973.179425394914;4983.725895075806;4984.252362145562;4988.276699878425;5036.949630503795;4665.48679645232;4859.173940430064;4902.411710594752;4906.852794416173;4985.177445721415;4900.26496025599;4928.507149285187;4931.950680493149;4982.676126051795;5027.497250274943;4904.043711476228;4914.70511769295;4957.454679985271;4959.181735013826;4960.8759781005765;4744.312074749073;4925.38432695913;4957.529034741694;4961.5286547777905;5015.1223535880345;4870.78081486341;4882.242730557248;4882.316475693335;4884.340614017062;4936.2102063669645 21:15:42 INFO - PROCESS | 12970 | |1;ext.html;571.5227784799692;574.5891687443477;579.0330148651751;587.5094296262621;591.163503642346;28759.568606470893;42466.78766606167;43892.780536097314;44028.119437611225;44081;40622.59377406233;41047;41136.794316028456;41179;41214.38178427326;7696.345810606092;7843.921560784404;7848.9607551962445;7855.332296754754;7856.685732570691;7438.590877501735;7512;7518.248175182465;7519.887201691951;7521.887171692429;7489.775306740807;7569.053868266466;7574.962125189366;7579.355754760852;7587.393008559302;7497.137829149636;7529.134149572815;7532.774016779451;7533.886991695129;7535.698572057138;7599.886001709979;7657.157712651604;7671.53970761756;7676;7676.616169191574;38159.23681526354;38512;38551;38591.42112868309;38629.61370386288;3063.9846800766077;3064.862081206351;3080.9229769255726;3105.720485156347;3498.6151523332414;29742.402777500538;29827.552586711216;29881.850590746908;31007.534886976715;31272.53091203611;2981.373911478592;3024.1532370936175;3024.848757562113;3029.0609910927683;3080;257579;258743;259969;260233;260429;39957.80021099934;39977.60022399797;40156.59843401587;40163.19673606512;40224;33532.329353412795;34334.31331373358;34439.82780086083;35875;35903;15151.469698560497;15322.770158447633;15344;15349.15579643108;15351.769723454157;11135.220534562503;11198.720031999103;11199.160062995308;11223.943880280709;11226.438678066064;18269.725954110698;18385.448436546925;18497.352592659463;18532.444026679223;18556;6994.4404447644065;7052.576845389293;7072.929270707227;7140.143942423072;7189.424846012306;828.660249297785;934.3739694404765;948.4404201521136;966.6133546581424;979.6620166042704;28181.57727634087;28571.285717856805;28593;28594.857025714737;29503;796.2555011064674;975.9463229522445;977.1449981266392;1012.7974405119006;1018.6536577563515;14369.13785172893;15382.077075375513;15396.846031539766;15519.922400387926;15523.223838808015;368677;369874;371691;371774;372244;48728;49373.753131234116;49389;49394.259086113736;49511;1044.7231483656838;1091.4542728635681;1155.4569352404355;1204.307523174161;1213.8179273108929;28232.15303540897;29353.41293174082;29524.557131643473;29555.408891821615;30218.697813021587;41041.384379234936;41576;41610.58389416067;41617.79191104146;41638.58361416347;297499;298340;298768;299257;299372;24969.500609988063;26092.217233483025;26147.869260653573;27270.590941135502;27274;41840.790796045825;42375.57624423716;42786.35820462758;43428;43557.564424355354;36128.63871361253;36560.79550851686;36625.45061824128;37270.62729372671;37380.25239495249;36101.48329421187;36924.63075369319;37049.629503704615;37697.62302377051;37733.622663773014;35380.46929296114;35765.82117089398;35888.64111358853;35891.64108358987;36780.26439471249;31241.218969525944;33198;33533.496997545546;33546.832265838515;33660.83169584136;23181.304560863202;23906.402339940945;23942.309127918066;24127.879360603085;24201 21:15:42 INFO - PROCESS | 12970 | |2;jquery.html;343.8521435782616;363.5982239625212;364.4460420161355;367.2801309433511;380.4977429792673;13053.543125990593;14002.78995815061;14090.929545352259;15110.697786044273;15850.445234416798;16857.07286099264;17050;17078.573035674115;17111.57221069474;17177.82822171778;4342.326939324394;4447.66642501812;4474.776261186944;4625.722456652595;4828.686135401205;5037.974810125944;5142.434332223453;5142.717150556718;5145.691258524501;5148.047611191922;4941.505849415065;5091.490850914897;5093.414257360414;5095.872603184913;5136.255242989764;5109.923351149736;5144.9742751286;5150.227465880111;5153.536181743623;5153.613478989072;4597.241455159895;4599.609033232179;4599.954000459986;4680.602148817355;4929.580985616227;11932.821007684892;12171.573994910135;12174.634760957186;12178.573749918798;13138.934305328508;1907.3610340535938;1993.2824183294003;2135.4554588579967;2296.2881506732965;2335.9182428614918;8644.567771611395;9175.954120229422;9316.068393160698;10938.289011214245;13677.179369237878;1583.8891277610571;1585.9286332115082;1636.6153953820842;1672.6989141954566;1693.2041940288045;67177.66411167962;70549.29450705479;70663.17347306102;74753.62623186849;75433.62283188604;9504.904950950402;9792;9927.354721943051;10679.09227715653;10806.729831754112;12866.871331286568;12996;13029.218246905217;13051.412686429134;13085.214887106613;6837.589744615239;6928.307169283032;6995.160580730345;7052.506324557232;7080.610566418896;6491.707873145625;6552.967235163794;6573.1454910861285;6700.83247918806;6718.596884186964;3805.7145714071557;3831.942520862189;3848.9615103849164;3932.3708206686792;4169.687273454503;1715.5282297368074;1719.2091637846481;1819.5178277756406;1835.7246413038151;1839.2826797548967;367.7793324005575;368.158757239706;396.33415861352626;649.76283656466;894.9418287811271;10084.092431681183;10195.490225488697;10503.474826258656;10569.841452378221;10666.600535661237;390.4494662525825;397.9263836190314;398.4123417958488;398.75875095566767;412.28262822688305;5295.311609490742;5494.615376923577;5538.972305138448;5564.638298510584;5586.720663966786;386221;394438;394704;395863;404131;88160;88587.11412885918;88865;90126.54936725274;90189.54905225564;403.2097089704213;410.9773962432095;412.40407611001814;412.73172437915593;413.605007218106;9385.296102792454;9721.70834874955;9727.902720972983;9779.070988256104;9887.604495820087;36583.81708091443;37323.81338093292;37493.62506375011;38023.809880950415;39924.00189995273;54941.72529137489;55814;56952.71523642521;58176.70911645415;60135.39864601473;7426.4430167736555;7437.665305061177;7528.887066693895;7596;7806.687732490636;8404.747857564284;11540.480678369326;11822.586209482626;12065.939670301943;12171.75656486883;9673;9785.119339259501;9811.460369679737;9948.701538953843;10427.68716938493;10038.598456061676;10295.17638588896;10459.94770026145;10491.475426228506;10574.629887954192;9768.56041478121;10282.485875706334;10662.84005739899;10700.197485188486;10754.247202695737;7223.891641625275;7424.331810137113;7445.255474452511;7457.664405101675;7618.961905190438;9030.96143943455;9217.262618990599;9970.551325190529;10192.592296308065;10443.68668939933 21:15:42 INFO - PROCESS | 12970 | |3;mootools.html;1402.3619253239776;1422.139605538649;1521.863032327091;1697.5756060984754;1698.4480043985707;7566.924330756691;8129.796755081126;8140.837183256339;8141.511509309438;8145;6532.836679083025;7415.369693576039;7727.459077864552;7757.534547927128;8001.439899207058;2131.254061078622;2150.021740108255;2150.494633761065;2156.482444213393;2156.611809874222;2066.86565373251;2086.2906611752;2148.32327816738;2148.817350525207;2158.665406861931;1770.9734353984636;1839.172372432404;1850.8982005989665;1851.333519932823;2061.6392131377024;2066.0392917293457;2083.8437117216267;2090.968635470469;2090.9754220432033;2094.3821572636107;2091.4616871853186;2119.9258025969016;2126.457753272921;2136.9679454808193;2152.763196048433;7808.804779880548;7828.843423131562;7864.72473463426;7900.486468379537;7901.367890568798;4407.294832826765;4477.708948918343;4561.585908368384;4566.429196350457;4570.451545814491;2768.9030883919168;2864.4843928092937;2919.824810511355;2923.8391888446126;2929.6923822998706;860.4665107633244;895.632790555869;899.806541593554;899.9235065019482;915.6154415145588;10887.455627218687;10936.289141205876;10954.561817527288;10963.068139208177;10963.397013164273;3920.941185882214;4304.720193187432;4364.476262848415;4365.257906155961;4370.213361594943;9492.145706886413;9492.857607135898;9494.667686631074;9495.240380769523;9496.572654230577;4528.954710452919;4560.429946256718;4562.840300589463;4562.954370456319;4564.726116432958;4034.11249525104;4044.5955404459905;4069.2268468990796;4138.048248902769;4138.110306284163;3665.6884164845756;3718.200586873835;3775.3959366501226;3803.4865293185067;3818.675412589961;1209.2442223610244;1214.6841821126393;1217.0385395537426;1217.6955761059735;1228.784962631536;538.302897747418;664.8703502816904;737.6238118559465;770.5068755996167;797.5453991224946;4429.734215947054;4956.35567376246;4961.925571116436;5113.181890897438;5120.7951681933055;803.1727320859524;812.6140083460309;822.6956026270318;855;874.5059041641452;3344.782589131699;3470.531478250404;3472.5832900052155;3475.374432602105;3543.645635436436;9872.210223182128;9960.452175130293;10021.749456263651;10070.496475176358;10086.74783130413;10023.248256380655;10051.547680354402;10052.346597471142;10059.144972677404;10068.345557538736;7216.134063912364;7345.522541034816;7375.151857536344;7394.667239974322;7452;4997.550220480174;5044.192929131322;5069.771860266371;5117.4114976776655;5149.910053967739;8798.384113112277;8901.465912045298;8937.195652391316;9019.684311049345;9030.187283144549;8885.333599979898;9035.3675242735;9071.909280907106;9127.269818414377;9195.080491950752;6700.46396288286;6852.486063545353;6938.236793952762;6967.7212911485;6987.755428559976;7569.8107547309555;7811.375089992901;7835.529868207926;7847.9607601961625;7897.723579674684;6890.44876409896;6906.758263460755;6955.200151982587;7045.260247674126;7235.493515453868;6711.63086030273;6834.077399550997;6864.313568643096;6904.654767261516;6950.992106144682;6300.9684951574945;6369.9681501592195;6371.49028077762;6373.553851230369;6378.202724659418;4346.652267818504;4493.191225579297;4554.817807287672;4566.086782643551;4597.839075632337;4275.422817919665;4296.742195468282;4390.538993405647;4451.910961780811;4495.617872480745 21:15:42 INFO - PROCESS | 12970 | |4;prototype.html;292.4764671238483;293.8457309912296;298.24097671426216;305.73401141007315;306.48663488656496;4772.546608072232;4818.229083346657;4834.830780922668;4840.975795121029;4849.175640141176;4744.813796550862;4754.9524504754945;4775.164346239403;4785.234362502;4787.252652781735;3564.5009698642148;3655.6344365563364;3679.951213904038;3701.2597480504005;3703.6851867591286;3585.8386372613163;3693.593704692482;3737.084414318496;3746.5129533160652;3751.381022131345;3699.741018128732;3707.2400157967554;3723.5531736191697;3727.329080765461;3743.3823419135947;3601.2797440512004;3710.5547334319917;3725.292194483067;3734.271816995687;3771.6039815819495;3296.3901678189613;3299.884504042371;3303.6544053721886;3313.7680362374645;3316.502524621302;4616.907661846778;4620.861374158781;4629.282461218483;4631.16639004979;4656.8602941911795;3998.980260033673;4154.563770804052;4164.895877603054;4243.8090285937205;4244;4913.312136300885;4917.001258464667;4920.384951881015;4929.162042452792;4966.428860680996;3494.52823868777;3505.8430717863043;3510.80690562019;3511.894643160709;3518.5074089627215;5058.595312375001;5200.271961925371;5209.296744939423;5241.554467870235;5248.343957005374;3545.1137215696076;3567.8761190224996;3575.4994300797907;3581.0510214793103;3582.3132650512116;3640.690541304019;3643.890683279506;3650.817459127033;3657.798821064867;3658.853645854136;4143.129942712004;4163.708540402143;4165.625093741578;4168.499780026355;4170.624643782075;4807.874881327165;4877.390461147813;4909.116359055404;4924.261360795909;4970.875728106754;1228.766534358469;1247.7005518675453;1250.8186312984749;1251.361805479194;1257.798752199662;775.1976704111217;801.1507801730185;803.6102490292203;811.3225456743567;816.1063635319316;3974.5230572331507;4241.766702831374;4281.850135245251;4365.148795984753;4413.22768515515;5073.518015788494;5076.340075790124;5076.7207803571155;5079.415867175322;5084.872878178076;4423.535528769497;4425.955740442619;4436.7594364845545;4439.733615983051;4441.489228738672;2593.4813037392596;2658.3885706287274;2666.7466590673853;2675.39803544201;2677.9598306025423;4823.155947709207;4825.5898248649255;4828.155072862305;4832.420109586872;4845.103655823614;5145.9742701287005;5152.217802386745;5157.432682404932;5166.61250406221;5197.636165468457;4678.719276843401;4778.805298675331;4799.352087468147;4820.71075735464;4832.178529650038;5021.899562008813;5022.522860328263;5046.34397528319;5050.646454748133;5087.821926232564;4964.702117872939;4974.527419895104;4988.952320012838;4990.8752281193265;5008.348914641219;4644.783037133436;4779.402574678165;4816.229403295456;4824.782884770124;4853.670238285572;3590.982045089758;3607.783532988029;3620.4026335655376;3627.836747346323;3633.8728144514816;5066.36670416198;5077.517635824591;5088.6946783193125;5092.541671249605;5104.69371837691;4695.741734204652;4837.975810120927;4840.854774356775;4893.926591101065;4908.263760435963;4866.562009419169;4873.731944742932;4879.585235255043;4884.780184891617;4887.902241955212;4827.565519103297;4835.975820120877;4838.685485443435;4851.248056551241;4856.004519073652;4698.389209402756;4705.2942058690105;4864.246041863516;4877.585405240452;4910.754462276944;4687.695299805502;4798.352222449925;4805.7597120143155;4813.470518243061;4826.300186472871 21:15:42 INFO - PROCESS | 12970 | |5;yui.html;602.5029350785602;606.9591347349776;666.1356168475556;675.7128220506285;749.3106342165207;12098.213616114957;12604.936975315111;12940.870591294084;12979.415926283316;12987.740245195091;13296.135751176167;13502.797458038138;13638.113522621048;13657.385417656204;13658.112222705542;642.7075680565355;768.0821418405009;792.1761368177089;795.9880601790978;796.0208943000114;766.7814672818248;788.0149812734082;789.206847118645;794.308951212446;794.499465336837;834.1783343406739;836.5440834745078;837.5477242289156;838.1074156023845;838.651959436833;665.8002599220215;840.5209030852417;841.3521588376976;841.9747407577782;842;589.0839744197771;609.2354095609982;612.6140531465148;613.5950272819962;614.5237440983229;12036.939815300955;12069.637910862688;12076.335801530913;12091.758164836654;12117.93941030298;12218.389080545938;12587.244765314019;12932.353382330846;13026.283554404505;13069.738605227938;12077.879221207764;12157.51369945201;12199.512019519208;12334.75330493394;12855.742885142245;4118.073433477473;4165.021220013295;4177.916441671149;4293.22721910056;4301.225779359714;59957.7002114991;63471;63567;63590.999999999534;63726.68136659287;10189.286749927434;10687.519061642244;10715.249932504807;10799.946000269949;11399.658010259705;20542.07560659774;20543;20553.075111619713;20566.69149962752;20567.485812854797;6631.734730610721;6735.696893639798;6755.088063111515;6893.551919125241;6926.61903595297;3610.548681414823;3613.5455998575108;3647.6717095461536;3656.3418584654487;3702.777833330009;2998.9100326990224;3112.377524495065;3152.3064925716308;3153.8265395403014;3165.287810242723;497.47267896030326;511.7134404733361;514.8841510660071;516.468037920942;522.2975098492522;460.1441319146452;498.64097849548267;539.8677324055553;550.4914748212129;572.0361191392517;7249.420046396275;7448.515846470071;7615.276548727862;7855.567943763148;8042.67829286822;566.1790403914341;567.8012695556522;568.5196009372074;572.461885827321;575.9078547432391;3475;3519.6832285094465;3623.1123374772824;3768.5666148393284;3782.867599634;58312.70843645755;59679;83869;106188.46905765422;106309.4684526588;54092;71246.64376678083;77878.22121778823;87331;87617.56191219004;47360.28959565609;63753.04370434447;67839.66080169567;70712;82338;6786.4910131739325;6891.173059232924;6899.17209934811;6914.9308506914285;6972.860542789217;45218;46012.76993615011;47040.529594703614;47249.527504724516;48913.75543122261;37973.81013094917;39170.608293916695;52339;66297;69312;9253.814923701622;9458.101480359355;9829.31194816356;9960.203183745138;10036;10647;10810.945945270223;10841.67474975752;11535.260665385342;11894.643160705193;9050.939511195573;9452.904457079165;9599.040095990624;9937.354071985297;9959.103680668775;9771.951140244253;10140.492975351055;10243.129334006693;10363.32638378503;10513.737156571147;9395.577199025924;9737.318387712792;9830.459324737209;10023.148032417326;10068.144207742424;8045.798855028671;8356.247937685637;8556.57217139128;8644.524551149747;8737.519436431057;7976.601169941364;8140.470869393471;8415.915840841513;8655.264302534104;8808.603612837565 21:15:42 INFO - PROCESS | 12970 | __end_tp_report 21:15:42 INFO - PROCESS | 12970 | __start_cc_report 21:15:42 INFO - PROCESS | 12970 | _x_x_mozilla_cycle_collect,1101 21:15:42 INFO - PROCESS | 12970 | __end_cc_report 21:15:42 INFO - PROCESS | 12970 | __startTimestamp1470197742261__endTimestamp 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | ------- Summary: start ------- 21:15:42 INFO - PROCESS | 12970 | Number of tests: 6 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | [#0] dojo.html Cycles:165 Average:4658.17 Median:4791.40 stddev:489.56 (10.2%) stddev-sans-first:464.15 21:15:42 INFO - PROCESS | 12970 | Values: 2617.2 2623.3 2648.8 2652.7 2656.3 4468.2 4539.4 4684.0 4703.3 4735.6 4582.0 4584.6 4662.8 4708.2 4736.2 4279.1 4339.5 4363.4 4371.2 4372.7 4340.7 4377.7 4405.9 4421.6 4468.2 4358.9 4380.9 4400.9 4427.8 4429.0 4194.1 4236.3 4273.6 4306.4 4318.9 4276.1 4278.3 4302.3 4325.2 4392.1 4731.6 4744.4 4788.7 4789.5 4790.8 4879.6 4964.2 5126.9 5136.9 5138.2 4974.6 5092.8 5092.9 5128.4 5141.5 4735.4 4762.3 4775.6 4791.4 4798.9 4977.8 5148.2 5184.2 5238.8 5251.8 4994.3 5025.1 5035.7 5043.5 5046.7 5088.8 5091.7 5092.2 5143.9 5147.6 4806.2 4979.0 5003.3 5033.5 5040.5 4988.4 5034.9 5040.0 5066.9 5081.6 4246.4 4264.4 4266.1 4301.5 4318.5 3658.1 3660.7 3701.5 3714.0 3778.5 4331.6 4374.4 4374.5 4410.1 4410.3 5014.5 5017.2 5022.3 5023.7 5067.2 4378.1 4383.1 4385.4 4386.8 4427.6 4372.3 4422.1 4428.4 4428.5 4507.0 4652.6 4654.6 4692.2 4698.7 4705.6 4847.8 4884.9 4893.1 4938.2 4943.7 4674.3 4785.1 4834.5 4847.1 4847.8 4902.2 4909.6 4913.5 4913.7 4959.1 4973.2 4983.7 4984.3 4988.3 5036.9 4665.5 4859.2 4902.4 4906.9 4985.2 4900.3 4928.5 4932.0 4982.7 5027.5 4904.0 4914.7 4957.5 4959.2 4960.9 4744.3 4925.4 4957.5 4961.5 5015.1 4870.8 4882.2 4882.3 4884.3 4936.2 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | [#1] ext.html Cycles:180 Average:45949.99 Median:36702.86 stddev:82207.31 (224.0%) stddev-sans-first:82367.32 21:15:42 INFO - PROCESS | 12970 | Values: 571.5 574.6 579.0 587.5 591.2 28759.6 42466.8 43892.8 44028.1 44081.0 40622.6 41047.0 41136.8 41179.0 41214.4 7696.3 7843.9 7849.0 7855.3 7856.7 7438.6 7512.0 7518.2 7519.9 7521.9 7489.8 7569.1 7575.0 7579.4 7587.4 7497.1 7529.1 7532.8 7533.9 7535.7 7599.9 7657.2 7671.5 7676.0 7676.6 38159.2 38512.0 38551.0 38591.4 38629.6 3064.0 3064.9 3080.9 3105.7 3498.6 29742.4 29827.6 29881.9 31007.5 31272.5 2981.4 3024.2 3024.8 3029.1 3080.0 257579.0 258743.0 259969.0 260233.0 260429.0 39957.8 39977.6 40156.6 40163.2 40224.0 33532.3 34334.3 34439.8 35875.0 35903.0 15151.5 15322.8 15344.0 15349.2 15351.8 11135.2 11198.7 11199.2 11223.9 11226.4 18269.7 18385.4 18497.4 18532.4 18556.0 6994.4 7052.6 7072.9 7140.1 7189.4 828.7 934.4 948.4 966.6 979.7 28181.6 28571.3 28593.0 28594.9 29503.0 796.3 975.9 977.1 1012.8 1018.7 14369.1 15382.1 15396.8 15519.9 15523.2 368677.0 369874.0 371691.0 371774.0 372244.0 48728.0 49373.8 49389.0 49394.3 49511.0 1044.7 1091.5 1155.5 1204.3 1213.8 28232.2 29353.4 29524.6 29555.4 30218.7 41041.4 41576.0 41610.6 41617.8 41638.6 297499.0 298340.0 298768.0 299257.0 299372.0 24969.5 26092.2 26147.9 27270.6 27274.0 41840.8 42375.6 42786.4 43428.0 43557.6 36128.6 36560.8 36625.5 37270.6 37380.3 36101.5 36924.6 37049.6 37697.6 37733.6 35380.5 35765.8 35888.6 35891.6 36780.3 31241.2 33198.0 33533.5 33546.8 33660.8 23181.3 23906.4 23942.3 24127.9 24201.0 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | [#2] jquery.html Cycles:180 Average:24165.87 Median:413.17 stddev:65813.85 (15929.1%) stddev-sans-first:65974.16 21:15:42 INFO - PROCESS | 12970 | Values: 343.9 363.6 364.4 367.3 380.5 13053.5 14002.8 14090.9 15110.7 15850.4 16857.1 17050.0 17078.6 17111.6 17177.8 4342.3 4447.7 4474.8 4625.7 4828.7 5038.0 5142.4 5142.7 5145.7 5148.0 4941.5 5091.5 5093.4 5095.9 5136.3 5109.9 5145.0 5150.2 5153.5 5153.6 4597.2 4599.6 4600.0 4680.6 4929.6 11932.8 12171.6 12174.6 12178.6 13138.9 1907.4 1993.3 2135.5 2296.3 2335.9 8644.6 9176.0 9316.1 10938.3 13677.2 1583.9 1585.9 1636.6 1672.7 1693.2 67177.7 70549.3 70663.2 74753.6 75433.6 9504.9 9792.0 9927.4 10679.1 10806.7 12866.9 12996.0 13029.2 13051.4 13085.2 6837.6 6928.3 6995.2 7052.5 7080.6 6491.7 6553.0 6573.1 6700.8 6718.6 3805.7 3831.9 3849.0 3932.4 4169.7 1715.5 1719.2 1819.5 1835.7 1839.3 367.8 368.2 396.3 649.8 894.9 10084.1 10195.5 10503.5 10569.8 10666.6 390.4 397.9 398.4 398.8 412.3 5295.3 5494.6 5539.0 5564.6 5586.7 386221.0 394438.0 394704.0 395863.0 404131.0 88160.0 88587.1 88865.0 90126.5 90189.5 403.2 411.0 412.4 412.7 413.6 9385.3 9721.7 9727.9 9779.1 9887.6 36583.8 37323.8 37493.6 38023.8 39924.0 54941.7 55814.0 56952.7 58176.7 60135.4 7426.4 7437.7 7528.9 7596.0 7806.7 8404.7 11540.5 11822.6 12065.9 12171.8 9673.0 9785.1 9811.5 9948.7 10427.7 10038.6 10295.2 10459.9 10491.5 10574.6 9768.6 10282.5 10662.8 10700.2 10754.2 7223.9 7424.3 7445.3 7457.7 7619.0 9031.0 9217.3 9970.6 10192.6 10443.7 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | [#3] mootools.html Cycles:180 Average:5168.84 Median:4566.26 stddev:2989.82 (65.5%) stddev-sans-first:2984.81 21:15:42 INFO - PROCESS | 12970 | Values: 1402.4 1422.1 1521.9 1697.6 1698.4 7566.9 8129.8 8140.8 8141.5 8145.0 6532.8 7415.4 7727.5 7757.5 8001.4 2131.3 2150.0 2150.5 2156.5 2156.6 2066.9 2086.3 2148.3 2148.8 2158.7 1771.0 1839.2 1850.9 1851.3 2061.6 2066.0 2083.8 2091.0 2091.0 2094.4 2091.5 2119.9 2126.5 2137.0 2152.8 7808.8 7828.8 7864.7 7900.5 7901.4 4407.3 4477.7 4561.6 4566.4 4570.5 2768.9 2864.5 2919.8 2923.8 2929.7 860.5 895.6 899.8 899.9 915.6 10887.5 10936.3 10954.6 10963.1 10963.4 3920.9 4304.7 4364.5 4365.3 4370.2 9492.1 9492.9 9494.7 9495.2 9496.6 4529.0 4560.4 4562.8 4563.0 4564.7 4034.1 4044.6 4069.2 4138.0 4138.1 3665.7 3718.2 3775.4 3803.5 3818.7 1209.2 1214.7 1217.0 1217.7 1228.8 538.3 664.9 737.6 770.5 797.5 4429.7 4956.4 4961.9 5113.2 5120.8 803.2 812.6 822.7 855.0 874.5 3344.8 3470.5 3472.6 3475.4 3543.6 9872.2 9960.5 10021.7 10070.5 10086.7 10023.2 10051.5 10052.3 10059.1 10068.3 7216.1 7345.5 7375.2 7394.7 7452.0 4997.6 5044.2 5069.8 5117.4 5149.9 8798.4 8901.5 8937.2 9019.7 9030.2 8885.3 9035.4 9071.9 9127.3 9195.1 6700.5 6852.5 6938.2 6967.7 6987.8 7569.8 7811.4 7835.5 7848.0 7897.7 6890.4 6906.8 6955.2 7045.3 7235.5 6711.6 6834.1 6864.3 6904.7 6951.0 6301.0 6370.0 6371.5 6373.6 6378.2 4346.7 4493.2 4554.8 4566.1 4597.8 4275.4 4296.7 4390.5 4451.9 4495.6 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | [#4] prototype.html Cycles:180 Average:4100.30 Median:4725.05 stddev:1190.86 (25.2%) stddev-sans-first:1159.40 21:15:42 INFO - PROCESS | 12970 | Values: 292.5 293.8 298.2 305.7 306.5 4772.5 4818.2 4834.8 4841.0 4849.2 4744.8 4755.0 4775.2 4785.2 4787.3 3564.5 3655.6 3680.0 3701.3 3703.7 3585.8 3693.6 3737.1 3746.5 3751.4 3699.7 3707.2 3723.6 3727.3 3743.4 3601.3 3710.6 3725.3 3734.3 3771.6 3296.4 3299.9 3303.7 3313.8 3316.5 4616.9 4620.9 4629.3 4631.2 4656.9 3999.0 4154.6 4164.9 4243.8 4244.0 4913.3 4917.0 4920.4 4929.2 4966.4 3494.5 3505.8 3510.8 3511.9 3518.5 5058.6 5200.3 5209.3 5241.6 5248.3 3545.1 3567.9 3575.5 3581.1 3582.3 3640.7 3643.9 3650.8 3657.8 3658.9 4143.1 4163.7 4165.6 4168.5 4170.6 4807.9 4877.4 4909.1 4924.3 4970.9 1228.8 1247.7 1250.8 1251.4 1257.8 775.2 801.2 803.6 811.3 816.1 3974.5 4241.8 4281.9 4365.1 4413.2 5073.5 5076.3 5076.7 5079.4 5084.9 4423.5 4426.0 4436.8 4439.7 4441.5 2593.5 2658.4 2666.7 2675.4 2678.0 4823.2 4825.6 4828.2 4832.4 4845.1 5146.0 5152.2 5157.4 5166.6 5197.6 4678.7 4778.8 4799.4 4820.7 4832.2 5021.9 5022.5 5046.3 5050.6 5087.8 4964.7 4974.5 4989.0 4990.9 5008.3 4644.8 4779.4 4816.2 4824.8 4853.7 3591.0 3607.8 3620.4 3627.8 3633.9 5066.4 5077.5 5088.7 5092.5 5104.7 4695.7 4838.0 4840.9 4893.9 4908.3 4866.6 4873.7 4879.6 4884.8 4887.9 4827.6 4836.0 4838.7 4851.2 4856.0 4698.4 4705.3 4864.2 4877.6 4910.8 4687.7 4798.4 4805.8 4813.5 4826.3 21:15:42 INFO - PROCESS | 12970 | 21:15:42 INFO - PROCESS | 12970 | [#5] yui.html Cycles:180 Average:16437.86 Median:570.28 stddev:23246.01 (4076.3%) stddev-sans-first:23280.81 21:15:42 INFO - PROCESS | 12970 | Values: 602.5 607.0 666.1 675.7 749.3 12098.2 12604.9 12940.9 12979.4 12987.7 13296.1 13502.8 13638.1 13657.4 13658.1 642.7 768.1 792.2 796.0 796.0 766.8 788.0 789.2 794.3 794.5 834.2 836.5 837.5 838.1 838.7 665.8 840.5 841.4 842.0 842.0 589.1 609.2 612.6 613.6 614.5 12036.9 12069.6 12076.3 12091.8 12117.9 12218.4 12587.2 12932.4 13026.3 13069.7 12077.9 12157.5 12199.5 12334.8 12855.7 4118.1 4165.0 4177.9 4293.2 4301.2 59957.7 63471.0 63567.0 63591.0 63726.7 10189.3 10687.5 10715.2 10799.9 11399.7 20542.1 20543.0 20553.1 20566.7 20567.5 6631.7 6735.7 6755.1 6893.6 6926.6 3610.5 3613.5 3647.7 3656.3 3702.8 2998.9 3112.4 3152.3 3153.8 3165.3 497.5 511.7 514.9 516.5 522.3 460.1 498.6 539.9 550.5 572.0 7249.4 7448.5 7615.3 7855.6 8042.7 566.2 567.8 568.5 572.5 575.9 3475.0 3519.7 3623.1 3768.6 3782.9 58312.7 59679.0 83869.0 106188.5 106309.5 54092.0 71246.6 77878.2 87331.0 87617.6 47360.3 63753.0 67839.7 70712.0 82338.0 6786.5 6891.2 6899.2 6914.9 6972.9 45218.0 46012.8 47040.5 47249.5 48913.8 37973.8 39170.6 52339.0 66297.0 69312.0 9253.8 9458.1 9829.3 9960.2 10036.0 10647.0 10810.9 10841.7 11535.3 11894.6 9050.9 9452.9 9599.0 9937.4 9959.1 9772.0 10140.5 10243.1 10363.3 10513.7 9395.6 9737.3 9830.5 10023.1 10068.1 8045.8 8356.2 8556.6 8644.5 8737.5 7976.6 8140.5 8415.9 8655.3 8808.6 21:15:42 INFO - PROCESS | 12970 | -------- Summary: end -------- 21:15:42 INFO - PROCESS | 12970 | 21:15:43 INFO - TEST-INFO | 12970: exit 0 21:15:43 INFO - TEST-OK | dromaeo_css | took 1121043ms 21:15:43 INFO - TEST-START | kraken 21:15:43 INFO - operating with platform_type : linux_ 21:15:43 INFO - Initialising browser for kraken test... 21:15:43 INFO - TEST-INFO | started process 13153 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMHQccB/profile http://localhost:40044/getInfo.html) 21:15:47 INFO - PROCESS | 13153 | __metrics Screen width/height:1600/1200 21:15:47 INFO - PROCESS | 13153 | colorDepth:24 21:15:47 INFO - PROCESS | 13153 | Browser inner width/height: 1024/697 21:15:47 INFO - PROCESS | 13153 | __metrics 21:15:49 INFO - PROCESS | 13153 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:15:49 INFO - PROCESS | 13153 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 21:15:50 INFO - TEST-INFO | 13153: exit 0 21:15:50 INFO - Browser initialized. 21:15:50 INFO - Running cycle 1/1 for kraken test... 21:15:50 INFO - TEST-INFO | started process 13235 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMHQccB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:16:02 INFO - PROCESS | 13235 | RSS: Main: 157380608 21:16:02 INFO - PROCESS | 13235 | 21:16:05 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40044/tests/kraken/driver.html?testName=audio-beat-detection) 21:16:06 INFO - PROCESS | 13235 | RSS: Main: 213970944 21:16:06 INFO - PROCESS | 13235 | 21:16:08 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40044/tests/kraken/driver.html?testName=audio-dft) 21:16:09 INFO - PROCESS | 13235 | RSS: Main: 191803392 21:16:09 INFO - PROCESS | 13235 | 21:16:12 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40044/tests/kraken/driver.html?testName=audio-fft) 21:16:13 INFO - PROCESS | 13235 | RSS: Main: 272310272 21:16:13 INFO - PROCESS | 13235 | 21:16:15 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40044/tests/kraken/driver.html?testName=audio-oscillator) 21:16:16 INFO - PROCESS | 13235 | RSS: Main: 180137984 21:16:16 INFO - PROCESS | 13235 | 21:16:18 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40044/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:16:18 INFO - PROCESS | 13235 | RSS: Main: 185790464 21:16:18 INFO - PROCESS | 13235 | 21:16:22 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40044/tests/kraken/driver.html?testName=imaging-darkroom) 21:16:22 INFO - PROCESS | 13235 | RSS: Main: 221982720 21:16:22 INFO - PROCESS | 13235 | 21:16:26 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40044/tests/kraken/driver.html?testName=imaging-desaturate) 21:16:26 INFO - PROCESS | 13235 | RSS: Main: 221368320 21:16:26 INFO - PROCESS | 13235 | 21:16:29 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40044/tests/kraken/driver.html?testName=json-parse-financial) 21:16:30 INFO - PROCESS | 13235 | RSS: Main: 220762112 21:16:30 INFO - PROCESS | 13235 | 21:16:31 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40044/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:16:32 INFO - PROCESS | 13235 | RSS: Main: 185450496 21:16:32 INFO - PROCESS | 13235 | 21:16:34 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:16:35 INFO - PROCESS | 13235 | RSS: Main: 191541248 21:16:35 INFO - PROCESS | 13235 | 21:16:37 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:16:37 INFO - PROCESS | 13235 | RSS: Main: 195293184 21:16:37 INFO - PROCESS | 13235 | 21:16:40 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:16:41 INFO - PROCESS | 13235 | RSS: Main: 188383232 21:16:41 INFO - PROCESS | 13235 | 21:16:44 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:16:44 INFO - PROCESS | 13235 | RSS: Main: 193777664 21:16:44 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | Cycle 1(1): loaded http://localhost:40044/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40044/tests/kraken/driver.html?testName=ai-astar) 21:16:46 INFO - PROCESS | 13235 | RSS: Main: 244346880 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | __start_tp_report 21:16:46 INFO - PROCESS | 13235 | _x_x_mozilla_page_load 21:16:46 INFO - PROCESS | 13235 | _x_x_mozilla_page_load_details 21:16:46 INFO - PROCESS | 13235 | |i|pagename|runs| 21:16:46 INFO - PROCESS | 13235 | |0;ai-astar;105;115;114;116;115;114;114;114;116;115 21:16:46 INFO - PROCESS | 13235 | |1;audio-beat-detection;148;127;129;190;128;123;151;121;169;124 21:16:46 INFO - PROCESS | 13235 | |2;audio-dft;161;160;162;161;162;162;162;162;162;161 21:16:46 INFO - PROCESS | 13235 | |3;audio-fft;75;75;76;122;75;75;74;75;97;75 21:16:46 INFO - PROCESS | 13235 | |4;audio-oscillator;85;85;123;84;85;84;120;84;85;131 21:16:46 INFO - PROCESS | 13235 | |5;imaging-gaussian-blur;117;118;116;117;117;117;118;118;117;117 21:16:46 INFO - PROCESS | 13235 | |6;imaging-darkroom;146;147;147;146;146;146;148;147;148;147 21:16:46 INFO - PROCESS | 13235 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;95;95 21:16:46 INFO - PROCESS | 13235 | |8;json-parse-financial;65;64;63;65;65;64;63;64;65;64 21:16:46 INFO - PROCESS | 13235 | |9;json-stringify-tinderbox;64;65;65;64;65;66;65;64;65;65 21:16:46 INFO - PROCESS | 13235 | |10;stanford-crypto-aes;82;79;79;77;79;79;79;78;79;83 21:16:46 INFO - PROCESS | 13235 | |11;stanford-crypto-ccm;130;140;146;139;130;127;130;136;212;138 21:16:46 INFO - PROCESS | 13235 | |12;stanford-crypto-pbkdf2;170;168;167;167;168;167;168;165;168;171 21:16:46 INFO - PROCESS | 13235 | |13;stanford-crypto-sha256-iterative;67;70;67;68;69;65;67;65;65;66 21:16:46 INFO - PROCESS | 13235 | __end_tp_report 21:16:46 INFO - PROCESS | 13235 | __start_cc_report 21:16:46 INFO - PROCESS | 13235 | _x_x_mozilla_cycle_collect,1902 21:16:46 INFO - PROCESS | 13235 | __end_cc_report 21:16:46 INFO - PROCESS | 13235 | __startTimestamp1470197806898__endTimestamp 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | ------- Summary: start ------- 21:16:46 INFO - PROCESS | 13235 | Number of tests: 14 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#0] ai-astar Cycles:10 Average:113.80 Median:115.00 stddev:3.19 (2.8%) stddev-sans-first:0.83 21:16:46 INFO - PROCESS | 13235 | Values: 105.0 115.0 114.0 116.0 115.0 114.0 114.0 114.0 116.0 115.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:138.50 stddev:23.23 (16.8%) stddev-sans-first:24.50 21:16:46 INFO - PROCESS | 13235 | Values: 148.0 127.0 129.0 190.0 128.0 123.0 151.0 121.0 169.0 124.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.71 (0.4%) stddev-sans-first:0.73 21:16:46 INFO - PROCESS | 13235 | Values: 161.0 160.0 162.0 161.0 162.0 162.0 162.0 162.0 162.0 161.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:15.70 (20.9%) stddev-sans-first:16.45 21:16:46 INFO - PROCESS | 13235 | Values: 75.0 75.0 76.0 122.0 75.0 75.0 74.0 75.0 97.0 75.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:84.50 stddev:19.56 (23.1%) stddev-sans-first:20.29 21:16:46 INFO - PROCESS | 13235 | Values: 85.0 85.0 123.0 84.0 85.0 84.0 120.0 84.0 85.0 131.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 21:16:46 INFO - PROCESS | 13235 | Values: 117.0 118.0 116.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#6] imaging-darkroom Cycles:10 Average:146.80 Median:147.00 stddev:0.79 (0.5%) stddev-sans-first:0.78 21:16:46 INFO - PROCESS | 13235 | Values: 146.0 147.0 147.0 146.0 146.0 146.0 148.0 147.0 148.0 147.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 21:16:46 INFO - PROCESS | 13235 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:64.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 21:16:46 INFO - PROCESS | 13235 | Values: 65.0 64.0 63.0 65.0 65.0 64.0 63.0 64.0 65.0 64.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#9] json-stringify-tinderbox Cycles:10 Average:64.80 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 21:16:46 INFO - PROCESS | 13235 | Values: 64.0 65.0 65.0 64.0 65.0 66.0 65.0 64.0 65.0 65.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:79.00 stddev:1.78 (2.2%) stddev-sans-first:1.62 21:16:46 INFO - PROCESS | 13235 | Values: 82.0 79.0 79.0 77.0 79.0 79.0 79.0 78.0 79.0 83.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#11] stanford-crypto-ccm Cycles:10 Average:142.80 Median:138.50 stddev:25.01 (18.1%) stddev-sans-first:26.10 21:16:46 INFO - PROCESS | 13235 | Values: 130.0 140.0 146.0 139.0 130.0 127.0 130.0 136.0 212.0 138.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:1.66 (1.0%) stddev-sans-first:1.58 21:16:46 INFO - PROCESS | 13235 | Values: 170.0 168.0 167.0 167.0 168.0 167.0 168.0 165.0 168.0 171.0 21:16:46 INFO - PROCESS | 13235 | 21:16:46 INFO - PROCESS | 13235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.73 (2.6%) stddev-sans-first:1.83 21:16:46 INFO - PROCESS | 13235 | Values: 67.0 70.0 67.0 68.0 69.0 65.0 67.0 65.0 65.0 66.0 21:16:46 INFO - PROCESS | 13235 | -------- Summary: end -------- 21:16:46 INFO - PROCESS | 13235 | 21:16:48 INFO - TEST-INFO | 13235: exit 0 21:16:48 INFO - TEST-OK | kraken | took 65060ms 21:16:48 INFO - SUITE-END | took 1186s 21:16:48 INFO - Completed test suite (00:19:46) 21:16:48 INFO - javascript benchmark 21:16:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2617.1625079974406, 2623.278598385445, 2648.801339899508, 2652.7479889410497, 2656.269525880383, 4468.19572476954, 4539.364488971547, 4683.976580117099, 4703.2709929960865, 4735.573798358151, 4581.977090114553, 4584.564466375689, 4662.79017444215, 4708.246680531116, 4736.194846876031, 4279.122779830127, 4339.457567804025, 4363.432753742025, 4371.235033869076, 4372.650187984969, 4340.696151269412, 4377.6935614507, 4405.933910991338, 4421.602055814976, 4468.195724769556, 4358.8910277243, 4380.8685739427865, 4400.867973960786, 4427.77861106945, 4429.047754732732, 4194.056337324108, 4236.343366778124, 4273.551277115889, 4306.397104405386, 4318.935215971763, 4276.144771045803, 4278.336857787048, 4302.311630139163, 4325.199838029943, 4392.143532011259, 4731.550502702238, 4744.383230180055, 4788.664793464459, 4789.544993225638, 4790.760461976922, 4879.560839524424, 4964.15609346412, 5126.948730512684, 5136.922946155811, 5138.229265610188, 4974.626902982291, 5092.8472145835685, 5092.9236061459105, 5128.384593848762, 5141.485851414828, 4735.384400028009, 4762.285657151455, 4775.570198682135, 4791.401074865642, 4798.928016079762, 4977.751112444364, 5148.176291793295, 5184.170532714747, 5238.842834714965, 5251.76367063483, 4994.300797888299, 5025.095482813055, 5035.697858128524, 5043.52086551777, 5046.7476626168545, 5088.771005304696, 5091.719955402415, 5092.159793634009, 5143.871403214949, 5147.613928955268, 4806.2310030394965, 4978.950210497921, 5003.2995380646735, 5033.5469807716745, 5040.521150490697, 4988.401391833003, 5034.89930201394, 5040.0, 5066.923996140061, 5081.593472522263, 4246.426732391088, 4264.381664658607, 4266.06146647737, 4301.462317210348, 4318.546552611931, 3658.0854786303425, 3660.7254455915913, 3701.5373078365205, 3714.0, 3778.5465744110884, 4331.5884990925815, 4374.409454723572, 4374.496932852762, 4410.07388448423, 4410.272305069691, 5014.52362025607, 5017.247412888096, 5022.271770593244, 5023.673461225008, 5067.163917953497, 4378.146261478981, 4383.145286669069, 4385.386045953505, 4386.780660966939, 4427.645788336926, 4372.300431930876, 4422.115576884636, 4428.4243048403505, 4428.512863585003, 4507.008458139204, 4652.604528615106, 4654.627629789541, 4692.249240121564, 4698.694584851974, 4705.60002399793, 4847.80608775645, 4884.853454396374, 4893.119238537098, 4938.185199442052, 4943.728094954812, 4674.298855171752, 4785.066911952275, 4834.516548345137, 4847.127517046965, 4847.781849816837, 4902.1911384621135, 4909.558139767438, 4913.508649135058, 4913.656044077024, 4959.132151873336, 4973.179425394914, 4983.725895075806, 4984.252362145562, 4988.276699878425, 5036.949630503795, 4665.48679645232, 4859.173940430064, 4902.411710594752, 4906.852794416173, 4985.177445721415, 4900.26496025599, 4928.507149285187, 4931.950680493149, 4982.676126051795, 5027.497250274943, 4904.043711476228, 4914.70511769295, 4957.454679985271, 4959.181735013826, 4960.8759781005765, 4744.312074749073, 4925.38432695913, 4957.529034741694, 4961.5286547777905, 5015.1223535880345, 4870.78081486341, 4882.242730557248, 4882.316475693335, 4884.340614017062, 4936.2102063669645], "value": 4626.964393788829, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.5227784799692, 574.5891687443477, 579.0330148651751, 587.5094296262621, 591.163503642346, 28759.568606470893, 42466.78766606167, 43892.780536097314, 44028.119437611225, 44081.0, 40622.59377406233, 41047.0, 41136.794316028456, 41179.0, 41214.38178427326, 7696.345810606092, 7843.921560784404, 7848.9607551962445, 7855.332296754754, 7856.685732570691, 7438.590877501735, 7512.0, 7518.248175182465, 7519.887201691951, 7521.887171692429, 7489.775306740807, 7569.053868266466, 7574.962125189366, 7579.355754760852, 7587.393008559302, 7497.137829149636, 7529.134149572815, 7532.774016779451, 7533.886991695129, 7535.698572057138, 7599.886001709979, 7657.157712651604, 7671.53970761756, 7676.0, 7676.616169191574, 38159.23681526354, 38512.0, 38551.0, 38591.42112868309, 38629.61370386288, 3063.9846800766077, 3064.862081206351, 3080.9229769255726, 3105.720485156347, 3498.6151523332414, 29742.402777500538, 29827.552586711216, 29881.850590746908, 31007.534886976715, 31272.53091203611, 2981.373911478592, 3024.1532370936175, 3024.848757562113, 3029.0609910927683, 3080.0, 257579.0, 258743.0, 259969.0, 260233.0, 260429.0, 39957.80021099934, 39977.60022399797, 40156.59843401587, 40163.19673606512, 40224.0, 33532.329353412795, 34334.31331373358, 34439.82780086083, 35875.0, 35903.0, 15151.469698560497, 15322.770158447633, 15344.0, 15349.15579643108, 15351.769723454157, 11135.220534562503, 11198.720031999103, 11199.160062995308, 11223.943880280709, 11226.438678066064, 18269.725954110698, 18385.448436546925, 18497.352592659463, 18532.444026679223, 18556.0, 6994.4404447644065, 7052.576845389293, 7072.929270707227, 7140.143942423072, 7189.424846012306, 828.660249297785, 934.3739694404765, 948.4404201521136, 966.6133546581424, 979.6620166042704, 28181.57727634087, 28571.285717856805, 28593.0, 28594.857025714737, 29503.0, 796.2555011064674, 975.9463229522445, 977.1449981266392, 1012.7974405119006, 1018.6536577563515, 14369.13785172893, 15382.077075375513, 15396.846031539766, 15519.922400387926, 15523.223838808015, 368677.0, 369874.0, 371691.0, 371774.0, 372244.0, 48728.0, 49373.753131234116, 49389.0, 49394.259086113736, 49511.0, 1044.7231483656838, 1091.4542728635681, 1155.4569352404355, 1204.307523174161, 1213.8179273108929, 28232.15303540897, 29353.41293174082, 29524.557131643473, 29555.408891821615, 30218.697813021587, 41041.384379234936, 41576.0, 41610.58389416067, 41617.79191104146, 41638.58361416347, 297499.0, 298340.0, 298768.0, 299257.0, 299372.0, 24969.500609988063, 26092.217233483025, 26147.869260653573, 27270.590941135502, 27274.0, 41840.790796045825, 42375.57624423716, 42786.35820462758, 43428.0, 43557.564424355354, 36128.63871361253, 36560.79550851686, 36625.45061824128, 37270.62729372671, 37380.25239495249, 36101.48329421187, 36924.63075369319, 37049.629503704615, 37697.62302377051, 37733.622663773014, 35380.46929296114, 35765.82117089398, 35888.64111358853, 35891.64108358987, 36780.26439471249, 31241.218969525944, 33198.0, 33533.496997545546, 33546.832265838515, 33660.83169584136, 23181.304560863202, 23906.402339940945, 23942.309127918066, 24127.879360603085, 24201.0], "value": 17184.26365637006, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8521435782616, 363.5982239625212, 364.4460420161355, 367.2801309433511, 380.4977429792673, 13053.543125990593, 14002.78995815061, 14090.929545352259, 15110.697786044273, 15850.445234416798, 16857.07286099264, 17050.0, 17078.573035674115, 17111.57221069474, 17177.82822171778, 4342.326939324394, 4447.66642501812, 4474.776261186944, 4625.722456652595, 4828.686135401205, 5037.974810125944, 5142.434332223453, 5142.717150556718, 5145.691258524501, 5148.047611191922, 4941.505849415065, 5091.490850914897, 5093.414257360414, 5095.872603184913, 5136.255242989764, 5109.923351149736, 5144.9742751286, 5150.227465880111, 5153.536181743623, 5153.613478989072, 4597.241455159895, 4599.609033232179, 4599.954000459986, 4680.602148817355, 4929.580985616227, 11932.821007684892, 12171.573994910135, 12174.634760957186, 12178.573749918798, 13138.934305328508, 1907.3610340535938, 1993.2824183294003, 2135.4554588579967, 2296.2881506732965, 2335.9182428614918, 8644.567771611395, 9175.954120229422, 9316.068393160698, 10938.289011214245, 13677.179369237878, 1583.8891277610571, 1585.9286332115082, 1636.6153953820842, 1672.6989141954566, 1693.2041940288045, 67177.66411167962, 70549.29450705479, 70663.17347306102, 74753.62623186849, 75433.62283188604, 9504.904950950402, 9792.0, 9927.354721943051, 10679.09227715653, 10806.729831754112, 12866.871331286568, 12996.0, 13029.218246905217, 13051.412686429134, 13085.214887106613, 6837.589744615239, 6928.307169283032, 6995.160580730345, 7052.506324557232, 7080.610566418896, 6491.707873145625, 6552.967235163794, 6573.1454910861285, 6700.83247918806, 6718.596884186964, 3805.7145714071557, 3831.942520862189, 3848.9615103849164, 3932.3708206686792, 4169.687273454503, 1715.5282297368074, 1719.2091637846481, 1819.5178277756406, 1835.7246413038151, 1839.2826797548967, 367.7793324005575, 368.158757239706, 396.33415861352626, 649.76283656466, 894.9418287811271, 10084.092431681183, 10195.490225488697, 10503.474826258656, 10569.841452378221, 10666.600535661237, 390.4494662525825, 397.9263836190314, 398.4123417958488, 398.75875095566767, 412.28262822688305, 5295.311609490742, 5494.615376923577, 5538.972305138448, 5564.638298510584, 5586.720663966786, 386221.0, 394438.0, 394704.0, 395863.0, 404131.0, 88160.0, 88587.11412885918, 88865.0, 90126.54936725274, 90189.54905225564, 403.2097089704213, 410.9773962432095, 412.40407611001814, 412.73172437915593, 413.605007218106, 9385.296102792454, 9721.70834874955, 9727.902720972983, 9779.070988256104, 9887.604495820087, 36583.81708091443, 37323.81338093292, 37493.62506375011, 38023.809880950415, 39924.00189995273, 54941.72529137489, 55814.0, 56952.71523642521, 58176.70911645415, 60135.39864601473, 7426.4430167736555, 7437.665305061177, 7528.887066693895, 7596.0, 7806.687732490636, 8404.747857564284, 11540.480678369326, 11822.586209482626, 12065.939670301943, 12171.75656486883, 9673.0, 9785.119339259501, 9811.460369679737, 9948.701538953843, 10427.68716938493, 10038.598456061676, 10295.17638588896, 10459.94770026145, 10491.475426228506, 10574.629887954192, 9768.56041478121, 10282.485875706334, 10662.84005739899, 10700.197485188486, 10754.247202695737, 7223.891641625275, 7424.331810137113, 7445.255474452511, 7457.664405101675, 7618.961905190438, 9030.96143943455, 9217.262618990599, 9970.551325190529, 10192.592296308065, 10443.68668939933], "value": 7231.76407987357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.3619253239776, 1422.139605538649, 1521.863032327091, 1697.5756060984754, 1698.4480043985707, 7566.924330756691, 8129.796755081126, 8140.837183256339, 8141.511509309438, 8145.0, 6532.836679083025, 7415.369693576039, 7727.459077864552, 7757.534547927128, 8001.439899207058, 2131.254061078622, 2150.021740108255, 2150.494633761065, 2156.482444213393, 2156.611809874222, 2066.86565373251, 2086.2906611752, 2148.32327816738, 2148.817350525207, 2158.665406861931, 1770.9734353984636, 1839.172372432404, 1850.8982005989665, 1851.333519932823, 2061.6392131377024, 2066.0392917293457, 2083.8437117216267, 2090.968635470469, 2090.9754220432033, 2094.3821572636107, 2091.4616871853186, 2119.9258025969016, 2126.457753272921, 2136.9679454808193, 2152.763196048433, 7808.804779880548, 7828.843423131562, 7864.72473463426, 7900.486468379537, 7901.367890568798, 4407.294832826765, 4477.708948918343, 4561.585908368384, 4566.429196350457, 4570.451545814491, 2768.9030883919168, 2864.4843928092937, 2919.824810511355, 2923.8391888446126, 2929.6923822998706, 860.4665107633244, 895.632790555869, 899.806541593554, 899.9235065019482, 915.6154415145588, 10887.455627218687, 10936.289141205876, 10954.561817527288, 10963.068139208177, 10963.397013164273, 3920.941185882214, 4304.720193187432, 4364.476262848415, 4365.257906155961, 4370.213361594943, 9492.145706886413, 9492.857607135898, 9494.667686631074, 9495.240380769523, 9496.572654230577, 4528.954710452919, 4560.429946256718, 4562.840300589463, 4562.954370456319, 4564.726116432958, 4034.11249525104, 4044.5955404459905, 4069.2268468990796, 4138.048248902769, 4138.110306284163, 3665.6884164845756, 3718.200586873835, 3775.3959366501226, 3803.4865293185067, 3818.675412589961, 1209.2442223610244, 1214.6841821126393, 1217.0385395537426, 1217.6955761059735, 1228.784962631536, 538.302897747418, 664.8703502816904, 737.6238118559465, 770.5068755996167, 797.5453991224946, 4429.734215947054, 4956.35567376246, 4961.925571116436, 5113.181890897438, 5120.7951681933055, 803.1727320859524, 812.6140083460309, 822.6956026270318, 855.0, 874.5059041641452, 3344.782589131699, 3470.531478250404, 3472.5832900052155, 3475.374432602105, 3543.645635436436, 9872.210223182128, 9960.452175130293, 10021.749456263651, 10070.496475176358, 10086.74783130413, 10023.248256380655, 10051.547680354402, 10052.346597471142, 10059.144972677404, 10068.345557538736, 7216.134063912364, 7345.522541034816, 7375.151857536344, 7394.667239974322, 7452.0, 4997.550220480174, 5044.192929131322, 5069.771860266371, 5117.4114976776655, 5149.910053967739, 8798.384113112277, 8901.465912045298, 8937.195652391316, 9019.684311049345, 9030.187283144549, 8885.333599979898, 9035.3675242735, 9071.909280907106, 9127.269818414377, 9195.080491950752, 6700.46396288286, 6852.486063545353, 6938.236793952762, 6967.7212911485, 6987.755428559976, 7569.8107547309555, 7811.375089992901, 7835.529868207926, 7847.9607601961625, 7897.723579674684, 6890.44876409896, 6906.758263460755, 6955.200151982587, 7045.260247674126, 7235.493515453868, 6711.63086030273, 6834.077399550997, 6864.313568643096, 6904.654767261516, 6950.992106144682, 6300.9684951574945, 6369.9681501592195, 6371.49028077762, 6373.553851230369, 6378.202724659418, 4346.652267818504, 4493.191225579297, 4554.817807287672, 4566.086782643551, 4597.839075632337, 4275.422817919665, 4296.742195468282, 4390.538993405647, 4451.910961780811, 4495.617872480745], "value": 4104.688439193793, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.4764671238483, 293.8457309912296, 298.24097671426216, 305.73401141007315, 306.48663488656496, 4772.546608072232, 4818.229083346657, 4834.830780922668, 4840.975795121029, 4849.175640141176, 4744.813796550862, 4754.9524504754945, 4775.164346239403, 4785.234362502, 4787.252652781735, 3564.5009698642148, 3655.6344365563364, 3679.951213904038, 3701.2597480504005, 3703.6851867591286, 3585.8386372613163, 3693.593704692482, 3737.084414318496, 3746.5129533160652, 3751.381022131345, 3699.741018128732, 3707.2400157967554, 3723.5531736191697, 3727.329080765461, 3743.3823419135947, 3601.2797440512004, 3710.5547334319917, 3725.292194483067, 3734.271816995687, 3771.6039815819495, 3296.3901678189613, 3299.884504042371, 3303.6544053721886, 3313.7680362374645, 3316.502524621302, 4616.907661846778, 4620.861374158781, 4629.282461218483, 4631.16639004979, 4656.8602941911795, 3998.980260033673, 4154.563770804052, 4164.895877603054, 4243.8090285937205, 4244.0, 4913.312136300885, 4917.001258464667, 4920.384951881015, 4929.162042452792, 4966.428860680996, 3494.52823868777, 3505.8430717863043, 3510.80690562019, 3511.894643160709, 3518.5074089627215, 5058.595312375001, 5200.271961925371, 5209.296744939423, 5241.554467870235, 5248.343957005374, 3545.1137215696076, 3567.8761190224996, 3575.4994300797907, 3581.0510214793103, 3582.3132650512116, 3640.690541304019, 3643.890683279506, 3650.817459127033, 3657.798821064867, 3658.853645854136, 4143.129942712004, 4163.708540402143, 4165.625093741578, 4168.499780026355, 4170.624643782075, 4807.874881327165, 4877.390461147813, 4909.116359055404, 4924.261360795909, 4970.875728106754, 1228.766534358469, 1247.7005518675453, 1250.8186312984749, 1251.361805479194, 1257.798752199662, 775.1976704111217, 801.1507801730185, 803.6102490292203, 811.3225456743567, 816.1063635319316, 3974.5230572331507, 4241.766702831374, 4281.850135245251, 4365.148795984753, 4413.22768515515, 5073.518015788494, 5076.340075790124, 5076.7207803571155, 5079.415867175322, 5084.872878178076, 4423.535528769497, 4425.955740442619, 4436.7594364845545, 4439.733615983051, 4441.489228738672, 2593.4813037392596, 2658.3885706287274, 2666.7466590673853, 2675.39803544201, 2677.9598306025423, 4823.155947709207, 4825.5898248649255, 4828.155072862305, 4832.420109586872, 4845.103655823614, 5145.9742701287005, 5152.217802386745, 5157.432682404932, 5166.61250406221, 5197.636165468457, 4678.719276843401, 4778.805298675331, 4799.352087468147, 4820.71075735464, 4832.178529650038, 5021.899562008813, 5022.522860328263, 5046.34397528319, 5050.646454748133, 5087.821926232564, 4964.702117872939, 4974.527419895104, 4988.952320012838, 4990.8752281193265, 5008.348914641219, 4644.783037133436, 4779.402574678165, 4816.229403295456, 4824.782884770124, 4853.670238285572, 3590.982045089758, 3607.783532988029, 3620.4026335655376, 3627.836747346323, 3633.8728144514816, 5066.36670416198, 5077.517635824591, 5088.6946783193125, 5092.541671249605, 5104.69371837691, 4695.741734204652, 4837.975810120927, 4840.854774356775, 4893.926591101065, 4908.263760435963, 4866.562009419169, 4873.731944742932, 4879.585235255043, 4884.780184891617, 4887.902241955212, 4827.565519103297, 4835.975820120877, 4838.685485443435, 4851.248056551241, 4856.004519073652, 4698.389209402756, 4705.2942058690105, 4864.246041863516, 4877.585405240452, 4910.754462276944, 4687.695299805502, 4798.352222449925, 4805.7597120143155, 4813.470518243061, 4826.300186472871], "value": 3720.115731093743, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.5029350785602, 606.9591347349776, 666.1356168475556, 675.7128220506285, 749.3106342165207, 12098.213616114957, 12604.936975315111, 12940.870591294084, 12979.415926283316, 12987.740245195091, 13296.135751176167, 13502.797458038138, 13638.113522621048, 13657.385417656204, 13658.112222705542, 642.7075680565355, 768.0821418405009, 792.1761368177089, 795.9880601790978, 796.0208943000114, 766.7814672818248, 788.0149812734082, 789.206847118645, 794.308951212446, 794.499465336837, 834.1783343406739, 836.5440834745078, 837.5477242289156, 838.1074156023845, 838.651959436833, 665.8002599220215, 840.5209030852417, 841.3521588376976, 841.9747407577782, 842.0, 589.0839744197771, 609.2354095609982, 612.6140531465148, 613.5950272819962, 614.5237440983229, 12036.939815300955, 12069.637910862688, 12076.335801530913, 12091.758164836654, 12117.93941030298, 12218.389080545938, 12587.244765314019, 12932.353382330846, 13026.283554404505, 13069.738605227938, 12077.879221207764, 12157.51369945201, 12199.512019519208, 12334.75330493394, 12855.742885142245, 4118.073433477473, 4165.021220013295, 4177.916441671149, 4293.22721910056, 4301.225779359714, 59957.7002114991, 63471.0, 63567.0, 63590.999999999534, 63726.68136659287, 10189.286749927434, 10687.519061642244, 10715.249932504807, 10799.946000269949, 11399.658010259705, 20542.07560659774, 20543.0, 20553.075111619713, 20566.69149962752, 20567.485812854797, 6631.734730610721, 6735.696893639798, 6755.088063111515, 6893.551919125241, 6926.61903595297, 3610.548681414823, 3613.5455998575108, 3647.6717095461536, 3656.3418584654487, 3702.777833330009, 2998.9100326990224, 3112.377524495065, 3152.3064925716308, 3153.8265395403014, 3165.287810242723, 497.47267896030326, 511.7134404733361, 514.8841510660071, 516.468037920942, 522.2975098492522, 460.1441319146452, 498.64097849548267, 539.8677324055553, 550.4914748212129, 572.0361191392517, 7249.420046396275, 7448.515846470071, 7615.276548727862, 7855.567943763148, 8042.67829286822, 566.1790403914341, 567.8012695556522, 568.5196009372074, 572.461885827321, 575.9078547432391, 3475.0, 3519.6832285094465, 3623.1123374772824, 3768.5666148393284, 3782.867599634, 58312.70843645755, 59679.0, 83869.0, 106188.46905765422, 106309.4684526588, 54092.0, 71246.64376678083, 77878.22121778823, 87331.0, 87617.56191219004, 47360.28959565609, 63753.04370434447, 67839.66080169567, 70712.0, 82338.0, 6786.4910131739325, 6891.173059232924, 6899.17209934811, 6914.9308506914285, 6972.860542789217, 45218.0, 46012.76993615011, 47040.529594703614, 47249.527504724516, 48913.75543122261, 37973.81013094917, 39170.608293916695, 52339.0, 66297.0, 69312.0, 9253.814923701622, 9458.101480359355, 9829.31194816356, 9960.203183745138, 10036.0, 10647.0, 10810.945945270223, 10841.67474975752, 11535.260665385342, 11894.643160705193, 9050.939511195573, 9452.904457079165, 9599.040095990624, 9937.354071985297, 9959.103680668775, 9771.951140244253, 10140.492975351055, 10243.129334006693, 10363.32638378503, 10513.737156571147, 9395.577199025924, 9737.318387712792, 9830.459324737209, 10023.148032417326, 10068.144207742424, 8045.798855028671, 8356.247937685637, 8556.57217139128, 8644.524551149747, 8737.519436431057, 7976.601169941364, 8140.470869393471, 8415.915840841513, 8655.264302534104, 8808.603612837565], "value": 6283.256562933705, "unit": "score"}], "name": "dromaeo_css", "value": 6170.071766125689, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 116.0, 115.0, 114.0, 114.0, 114.0, 116.0, 115.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 127.0, 129.0, 190.0, 128.0, 123.0, 151.0, 121.0, 169.0, 124.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 162.0, 161.0, 162.0, 162.0, 162.0, 162.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 122.0, 75.0, 75.0, 74.0, 75.0, 97.0, 75.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 123.0, 84.0, 85.0, 84.0, 120.0, 84.0, 85.0, 131.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 116.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 146.0, 146.0, 146.0, 148.0, 147.0, 148.0, 147.0], "value": 146.8, "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, 96.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 63.0, 65.0, 65.0, 64.0, 63.0, 64.0, 65.0, 64.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 64.0, 65.0, 66.0, 65.0, 64.0, 65.0, 65.0], "value": 64.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 79.0, 77.0, 79.0, 79.0, 79.0, 78.0, 79.0, 83.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 140.0, 146.0, 139.0, 130.0, 127.0, 130.0, 136.0, 212.0, 138.0], "value": 142.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 167.0, 167.0, 168.0, 167.0, 168.0, 165.0, 168.0, 171.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 68.0, 69.0, 65.0, 67.0, 65.0, 65.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1539.9000000000005, "alertThreshold": 2.0}]} 21:16:48 INFO - Return code: 0 21:16:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:16:48 INFO - # TBPL SUCCESS # 21:16:48 INFO - Running post-action listener: _resource_record_post_action 21:16:48 INFO - [mozharness: 2016-08-03 04:16:48.692170Z] Finished run-tests step (success) 21:16:48 INFO - Running post-run listener: _resource_record_post_run 21:16:48 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 8192; Write bytes: 531959808; Read time: 24; Write time: 1663124 21:16:48 INFO - TinderboxPrint: CPU usage
13.1% 21:16:48 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 21:16:48 INFO - TinderboxPrint: I/O write bytes / time
531,959,808 / 1,663,124 21:16:48 INFO - TinderboxPrint: CPU idle
8,344.0 (86.9%) 21:16:48 INFO - TinderboxPrint: CPU user
1,199.2 (12.5%) 21:16:48 INFO - TinderboxPrint: Swap in / out
0 / 0 21:16:48 INFO - install - Wall time: 16s; CPU: 20.0%; Read bytes: 0; Write bytes: 237588480; Read time: 0; Write time: 1100728 21:16:48 INFO - run-tests - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294371328; Read time: 16; Write time: 562396 21:16:49 INFO - Running post-run listener: _upload_blobber_files 21:16:49 INFO - Blob upload gear active. 21:16:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:16:49 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 21:16:49 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'] 21:16:49 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 21:16:49 INFO - (blobuploader) - INFO - Open directory for files ... 21:16:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:16:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:16:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:16:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:16:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:16:53 INFO - (blobuploader) - INFO - Done attempting. 21:16:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:16:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:16:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:16:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:16:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:16:53 INFO - (blobuploader) - INFO - Done attempting. 21:16:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:16:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:16:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:16:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:16:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:16:54 INFO - (blobuploader) - INFO - Done attempting. 21:16:54 INFO - (blobuploader) - INFO - Iteration through files over. 21:16:54 INFO - Return code: 0 21:16:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:16:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:16:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/09a57c2b55320728a6181a6e48d42e64cb6e027da60440b97a38b779a8339b73d0292474f178e71f06b4340726ad3e2b983b398b04c4e48c69eb2597020750c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/961535292171a286f592f636d68c8289aa6416b529290a5fe44e786e48835d8dcf2da338647feb28428ceebf7ae3b3a3505ecaeec2969d600de17051a3645355", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aad1922dc007427befed7f47facb21d5121a907d799da8babc0c7804ff34ea8ab8b98f794291ba33b081c6df68488dd4529b962680c41e406a4fb6befd758894"} 21:16:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:16:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:16:54 INFO - Contents: 21:16:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/09a57c2b55320728a6181a6e48d42e64cb6e027da60440b97a38b779a8339b73d0292474f178e71f06b4340726ad3e2b983b398b04c4e48c69eb2597020750c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/961535292171a286f592f636d68c8289aa6416b529290a5fe44e786e48835d8dcf2da338647feb28428ceebf7ae3b3a3505ecaeec2969d600de17051a3645355", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aad1922dc007427befed7f47facb21d5121a907d799da8babc0c7804ff34ea8ab8b98f794291ba33b081c6df68488dd4529b962680c41e406a4fb6befd758894"} 21:16:54 INFO - Running post-run listener: copy_logs_to_upload_dir 21:16:54 INFO - Copying logs to upload dir... 21:16:54 INFO - mkdir: /builds/slave/test/build/upload/logs 21:16:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.294562 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-08-02 21:16:54.606194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 21:16:54.609490) ========= 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=df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593 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/09a57c2b55320728a6181a6e48d42e64cb6e027da60440b97a38b779a8339b73d0292474f178e71f06b4340726ad3e2b983b398b04c4e48c69eb2597020750c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/961535292171a286f592f636d68c8289aa6416b529290a5fe44e786e48835d8dcf2da338647feb28428ceebf7ae3b3a3505ecaeec2969d600de17051a3645355", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aad1922dc007427befed7f47facb21d5121a907d799da8babc0c7804ff34ea8ab8b98f794291ba33b081c6df68488dd4529b962680c41e406a4fb6befd758894"} build_url:https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011296 build_url: 'https://queue.taskcluster.net/v1/task/RzQiOghvRw269BS-D_F-Yw/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/09a57c2b55320728a6181a6e48d42e64cb6e027da60440b97a38b779a8339b73d0292474f178e71f06b4340726ad3e2b983b398b04c4e48c69eb2597020750c3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/961535292171a286f592f636d68c8289aa6416b529290a5fe44e786e48835d8dcf2da338647feb28428ceebf7ae3b3a3505ecaeec2969d600de17051a3645355", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland-Non-PGO/sha512/aad1922dc007427befed7f47facb21d5121a907d799da8babc0c7804ff34ea8ab8b98f794291ba33b081c6df68488dd4529b962680c41e406a4fb6befd758894"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 21:16:54.635590) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 21:16:54.635908) ========= 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=df17445c3590ff7897617a0e00000225-1470192155.173973-2050830593 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 21:16:54.722194) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 21:16:54.722593) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 21:16:54.722862) ========= ========= Total master_lag: 0.18 =========