builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-033 starttime: 1455882960.27 results: failure (2) buildid: 20160219025400 builduid: e2660a90a32944438a98b3ad462a5d9d revision: 55991a2134a089f328988760ed79601dad8a4cd6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.274977) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.275742) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.276278) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] 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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006171 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.302816) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.303455) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.319611) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.320259) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', '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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.371796) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.372330) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.372931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.373433) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-02-19 03: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.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.3M=0s 2016-02-19 03:56:00 (40.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099002 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.491311) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.491906) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] 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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022190 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.543183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:00.543567) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 55991a2134a089f328988760ed79601dad8a4cd6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 55991a2134a089f328988760ed79601dad8a4cd6 --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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-02-19 03:56:00,545 truncating revision to first 12 chars 2016-02-19 03:56:00,545 Setting DEBUG logging. 2016-02-19 03:56:00,545 attempt 1/10 2016-02-19 03:56:00,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/55991a2134a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-02-19 03:56:01,186 unpacking tar archive at: fx-team-55991a2134a0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.892746 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:01.451338) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:01.452231) ========= echo 55991a2134a089f328988760ed79601dad8a4cd6 in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'55991a2134a089f328988760ed79601dad8a4cd6'] 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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 55991a2134a089f328988760ed79601dad8a4cd6 program finished with exit code 0 elapsedTime=0.003947 script_repo_revision: '55991a2134a089f328988760ed79601dad8a4cd6' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:01.504765) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:01.505098) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-02-19 03:56:01.516304) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 22 mins, 15 secs) (at 2016-02-19 03:56:01.516629) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 03:56:01 INFO - MultiFileLogger online at 20160219 03:56:01 in /builds/slave/test 03:56:01 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 03:56:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:56:01 INFO - {'append_to_log': False, 03:56:01 INFO - 'base_work_dir': '/builds/slave/test', 03:56:01 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 03:56:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:56:01 INFO - 'branch': 'Fx-Team-Non-PGO', 03:56:01 INFO - 'buildbot_json_path': 'buildprops.json', 03:56:01 INFO - 'config_files': ('talos/linux_config.py',), 03:56:01 INFO - 'default_actions': ('clobber', 03:56:01 INFO - 'read-buildbot-config', 03:56:01 INFO - 'download-and-extract', 03:56:01 INFO - 'populate-webroot', 03:56:01 INFO - 'create-virtualenv', 03:56:01 INFO - 'install', 03:56:01 INFO - 'run-tests'), 03:56:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:56:01 INFO - 'download_minidump_stackwalk': True, 03:56:01 INFO - 'download_symbols': 'ondemand', 03:56:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:56:01 INFO - 'tooltool.py': '/tools/tooltool.py', 03:56:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:56:01 INFO - '/tools/misc-python/virtualenv.py')}, 03:56:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:56:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:56:01 INFO - 'installer_path': 'installer.exe', 03:56:01 INFO - 'log_level': 'info', 03:56:01 INFO - 'log_name': 'talos', 03:56:01 INFO - 'log_to_console': True, 03:56:01 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 03:56:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 03:56:01 INFO - 'opt_config_files': (), 03:56:01 INFO - 'pip_index': False, 03:56:01 INFO - 'sps_profile': False, 03:56:01 INFO - 'sps_profile_interval': 0, 03:56:01 INFO - 'suite': 'dromaeojs-e10s', 03:56:01 INFO - 'system_bits': '32', 03:56:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:56:01 INFO - 'title': 'talos-linux64-ix-033', 03:56:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:56:01 INFO - 'use_talos_json': True, 03:56:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:56:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:56:01 INFO - 'work_dir': 'build'} 03:56:01 INFO - ##### 03:56:01 INFO - ##### Running clobber step. 03:56:01 INFO - ##### 03:56:01 INFO - Running pre-action listener: _resource_record_pre_action 03:56:01 INFO - Running main action method: clobber 03:56:01 INFO - rmtree: /builds/slave/test/build 03:56:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:56:02 INFO - Running post-action listener: _resource_record_post_action 03:56:02 INFO - ##### 03:56:02 INFO - ##### Running read-buildbot-config step. 03:56:02 INFO - ##### 03:56:02 INFO - Running pre-action listener: _resource_record_pre_action 03:56:02 INFO - Running main action method: read_buildbot_config 03:56:02 INFO - Using buildbot properties: 03:56:02 INFO - { 03:56:02 INFO - "project": "", 03:56:02 INFO - "product": "firefox", 03:56:02 INFO - "script_repo_revision": "production", 03:56:02 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 03:56:02 INFO - "repository": "", 03:56:02 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 03:56:02 INFO - "buildid": "20160219025400", 03:56:02 INFO - "pgo_build": "False", 03:56:02 INFO - "basedir": "/builds/slave/test", 03:56:02 INFO - "buildnumber": 1108, 03:56:02 INFO - "slavename": "talos-linux64-ix-033", 03:56:02 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 03:56:02 INFO - "platform": "ubuntu64_hw", 03:56:02 INFO - "scheduler": "tests-fx-team-linux64-talos", 03:56:02 INFO - "branch": "fx-team", 03:56:02 INFO - "repo_path": "integration/fx-team", 03:56:02 INFO - "revision": "55991a2134a089f328988760ed79601dad8a4cd6", 03:56:02 INFO - "stage_platform": "linux64", 03:56:02 INFO - "builduid": "e2660a90a32944438a98b3ad462a5d9d", 03:56:02 INFO - "slavebuilddir": "test" 03:56:02 INFO - } 03:56:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 03:56:02 INFO - Running post-action listener: _resource_record_post_action 03:56:02 INFO - ##### 03:56:02 INFO - ##### Running download-and-extract step. 03:56:02 INFO - ##### 03:56:02 INFO - Running pre-action listener: _resource_record_pre_action 03:56:02 INFO - Running main action method: download_and_extract 03:56:02 INFO - mkdir: /builds/slave/test/build/tests 03: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')]} 03:56:02 INFO - https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 03:56:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/test_packages.json 03:56:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/test_packages.json 03:56:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 03:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 03:56:02 INFO - Downloaded 1448 bytes. 03:56:02 INFO - Reading from file /builds/slave/test/build/test_packages.json 03:56:02 INFO - Using the following test package requirements: 03:56:02 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 03:56:02 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 03:56:02 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 03:56:02 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'jsshell-linux-x86_64.zip'], 03:56:02 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 03:56:02 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 03:56:02 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 03:56:02 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 03:56:02 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 03:56:02 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 03:56:02 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 03:56:02 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 03:56:02 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 03: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')]} 03:56:02 INFO - https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:56:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 03:56:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 03:56:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 03:56:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:56:03 INFO - Downloaded 22033040 bytes. 03:56:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:56:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:56:04 INFO - Return code: 0 03:56:04 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 03:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:04 INFO - https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 03:56:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 03:56:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 03:56:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 03:56:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 03:56:06 INFO - Downloaded 22033040 bytes. 03:56:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:56:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 03:56:08 INFO - Return code: 0 03:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:08 INFO - https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 03:56:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 03:56:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 03:56:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 03:56:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 03:56:09 INFO - Downloaded 10707015 bytes. 03:56:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:56:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 03:56:09 INFO - Return code: 0 03: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')]} 03:56:09 INFO - https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 03:56:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 03:56:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 03:56:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 03:56:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 03:56:10 INFO - Downloaded 54792951 bytes. 03:56:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 03:56:10 INFO - mkdir: /builds/slave/test/properties 03:56:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:56:10 INFO - Writing to file /builds/slave/test/properties/build_url 03:56:10 INFO - Contents: 03:56:10 INFO - build_url:https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 03:56:11 INFO - Running post-action listener: _resource_record_post_action 03:56:11 INFO - Running post-action listener: set_extra_try_arguments 03:56:11 INFO - ##### 03:56:11 INFO - ##### Running populate-webroot step. 03:56:11 INFO - ##### 03:56:11 INFO - Running pre-action listener: _resource_record_pre_action 03:56:11 INFO - Running main action method: populate_webroot 03:56:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:56:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:56:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:56:11 INFO - '--tppagecycles', 03:56:11 INFO - '7'], 03:56:11 INFO - 'tests': ['tsvgm']}}, 03:56:11 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:56:11 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:56:11 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:56:11 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:56:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:56:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'tests': ['damp', 'tps']}, 03:56:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['damp', 'tps']}, 03:56:11 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:56:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:56:11 INFO - 'other': {'tests': ['a11yr', 03:56:11 INFO - 'ts_paint', 03:56:11 INFO - 'tpaint', 03:56:11 INFO - 'sessionrestore', 03:56:11 INFO - 'sessionrestore_no_auto_restore']}, 03:56:11 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['a11yr', 03:56:11 INFO - 'ts_paint', 03:56:11 INFO - 'tpaint', 03:56:11 INFO - 'sessionrestore', 03:56:11 INFO - 'sessionrestore_no_auto_restore']}, 03:56:11 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['a11yr', 03:56:11 INFO - 'ts_paint', 03:56:11 INFO - 'tpaint', 03:56:11 INFO - 'sessionrestore', 03:56:11 INFO - 'sessionrestore_no_auto_restore']}, 03:56:11 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['a11yr', 03:56:11 INFO - 'ts_paint', 03:56:11 INFO - 'tpaint', 03:56:11 INFO - 'sessionrestore', 03:56:11 INFO - 'sessionrestore_no_auto_restore']}, 03:56:11 INFO - 'other_l64': {'tests': ['a11yr', 03:56:11 INFO - 'ts_paint', 03:56:11 INFO - 'tpaint', 03:56:11 INFO - 'sessionrestore', 03:56:11 INFO - 'sessionrestore_no_auto_restore']}, 03:56:11 INFO - 'other_nol64': {'tests': ['a11yr', 03:56:11 INFO - 'ts_paint', 03:56:11 INFO - 'tpaint', 03:56:11 INFO - 'sessionrestore', 03:56:11 INFO - 'sessionrestore_no_auto_restore']}, 03:56:11 INFO - 'svgr': {'tests': ['tsvgx', 03:56:11 INFO - 'tsvgr_opacity', 03:56:11 INFO - 'tart', 03:56:11 INFO - 'tscrollx', 03:56:11 INFO - 'cart']}, 03:56:11 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['tsvgx', 03:56:11 INFO - 'tsvgr_opacity', 03:56:11 INFO - 'tart', 03:56:11 INFO - 'tscrollx', 03:56:11 INFO - 'cart']}, 03:56:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'tests': ['tp5o']}, 03:56:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'talos_options': ['--e10s'], 03:56:11 INFO - 'tests': ['tp5o']}, 03:56:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'talos_options': ['--xperf_path', 03:56:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:11 INFO - 'tests': ['tp5n']}, 03:56:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:56:11 INFO - 'talos_options': ['--e10s', 03:56:11 INFO - '--xperf_path', 03:56:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:56:11 INFO - 'tests': ['tp5n']}}, 03:56:11 INFO - 'talos.zip': {'path': '', 03:56:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:56:11 INFO - Running post-action listener: _resource_record_post_action 03:56:11 INFO - ##### 03:56:11 INFO - ##### Running create-virtualenv step. 03:56:11 INFO - ##### 03:56:11 INFO - Running pre-action listener: _resource_record_pre_action 03:56:11 INFO - Running main action method: create_virtualenv 03:56:11 INFO - Creating virtualenv /builds/slave/test/build/venv 03:56:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:56:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:56:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:56:13 INFO - Using real prefix '/usr' 03:56:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:56:13 INFO - Installing distribute.............................................................................................................................................................................................done. 03:56:16 INFO - Installing pip.................done. 03:56:16 INFO - Return code: 0 03:56:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:56:16 INFO - Using env: {'DISPLAY': ':0', 03:56:16 INFO - 'HOME': '/home/cltbld', 03:56:16 INFO - 'LANG': 'en_US.UTF-8', 03:56:16 INFO - 'LANGUAGE': 'en_US:en', 03:56:16 INFO - 'LOGNAME': 'cltbld', 03:56:16 INFO - 'MAIL': '/var/mail/cltbld', 03:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:16 INFO - 'MOZ_NO_REMOTE': '1', 03:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:16 INFO - 'NO_EM_RESTART': '1', 03:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:16 INFO - 'PWD': '/builds/slave/test', 03:56:16 INFO - 'SHELL': '/bin/bash', 03:56:16 INFO - 'SHLVL': '1', 03:56:16 INFO - 'TERM': 'linux', 03:56:16 INFO - 'TMOUT': '86400', 03:56:16 INFO - 'USER': 'cltbld', 03:56:16 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:16 INFO - '_': '/tools/buildbot/bin/python'} 03:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:56:16 INFO - Cleaning up... 03:56:16 INFO - Return code: 0 03:56:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:56:16 INFO - Using env: {'DISPLAY': ':0', 03:56:16 INFO - 'HOME': '/home/cltbld', 03:56:16 INFO - 'LANG': 'en_US.UTF-8', 03:56:16 INFO - 'LANGUAGE': 'en_US:en', 03:56:16 INFO - 'LOGNAME': 'cltbld', 03:56:16 INFO - 'MAIL': '/var/mail/cltbld', 03:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:16 INFO - 'MOZ_NO_REMOTE': '1', 03:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:16 INFO - 'NO_EM_RESTART': '1', 03:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:16 INFO - 'PWD': '/builds/slave/test', 03:56:16 INFO - 'SHELL': '/bin/bash', 03:56:16 INFO - 'SHLVL': '1', 03:56:16 INFO - 'TERM': 'linux', 03:56:16 INFO - 'TMOUT': '86400', 03:56:16 INFO - 'USER': 'cltbld', 03:56:16 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:16 INFO - '_': '/tools/buildbot/bin/python'} 03:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:16 INFO - Downloading/unpacking psutil>=0.7.1 03:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:56:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:18 INFO - Installing collected packages: psutil 03:56:18 INFO - Running setup.py install for psutil 03:56:18 INFO - building 'psutil._psutil_linux' extension 03:56:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 03:56:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 03:56:18 INFO - building 'psutil._psutil_posix' extension 03:56:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 03:56:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 03:56:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:56:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:56:18 INFO - Successfully installed psutil 03:56:18 INFO - Cleaning up... 03:56:20 INFO - Return code: 0 03:56:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03: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')]} 03:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03: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')]} 03:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:56:20 INFO - Using env: {'DISPLAY': ':0', 03:56:20 INFO - 'HOME': '/home/cltbld', 03:56:20 INFO - 'LANG': 'en_US.UTF-8', 03:56:20 INFO - 'LANGUAGE': 'en_US:en', 03:56:20 INFO - 'LOGNAME': 'cltbld', 03:56:20 INFO - 'MAIL': '/var/mail/cltbld', 03:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:20 INFO - 'MOZ_NO_REMOTE': '1', 03:56:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:20 INFO - 'NO_EM_RESTART': '1', 03:56:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:20 INFO - 'PWD': '/builds/slave/test', 03:56:20 INFO - 'SHELL': '/bin/bash', 03:56:20 INFO - 'SHLVL': '1', 03:56:20 INFO - 'TERM': 'linux', 03:56:20 INFO - 'TMOUT': '86400', 03:56:20 INFO - 'USER': 'cltbld', 03:56:20 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:20 INFO - '_': '/tools/buildbot/bin/python'} 03:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:22 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:56:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:56:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:56:22 INFO - Installing collected packages: mozsystemmonitor 03:56:22 INFO - Running setup.py install for mozsystemmonitor 03:56:27 INFO - Successfully installed mozsystemmonitor 03:56:27 INFO - Cleaning up... 03:56:27 INFO - Return code: 0 03:56:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:27 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 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:56:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:56:27 INFO - Using env: {'DISPLAY': ':0', 03:56:27 INFO - 'HOME': '/home/cltbld', 03:56:27 INFO - 'LANG': 'en_US.UTF-8', 03:56:27 INFO - 'LANGUAGE': 'en_US:en', 03:56:27 INFO - 'LOGNAME': 'cltbld', 03:56:27 INFO - 'MAIL': '/var/mail/cltbld', 03:56:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:27 INFO - 'MOZ_NO_REMOTE': '1', 03:56:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:27 INFO - 'NO_EM_RESTART': '1', 03:56:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:27 INFO - 'PWD': '/builds/slave/test', 03:56:27 INFO - 'SHELL': '/bin/bash', 03:56:27 INFO - 'SHLVL': '1', 03:56:27 INFO - 'TERM': 'linux', 03:56:27 INFO - 'TMOUT': '86400', 03:56:27 INFO - 'USER': 'cltbld', 03:56:27 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:27 INFO - '_': '/tools/buildbot/bin/python'} 03:56:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:27 INFO - Downloading/unpacking blobuploader==1.2.4 03:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:29 INFO - Downloading blobuploader-1.2.4.tar.gz 03:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:56:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03: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 03: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 03: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 03: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 03: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 03: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 03:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:56:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:30 INFO - Downloading docopt-0.6.1.tar.gz 03:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:56:30 INFO - Installing collected packages: blobuploader, requests, docopt 03:56:30 INFO - Running setup.py install for blobuploader 03:56:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:56:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:56:30 INFO - Running setup.py install for requests 03:56:31 INFO - Running setup.py install for docopt 03:56:32 INFO - Successfully installed blobuploader requests docopt 03:56:32 INFO - Cleaning up... 03:56:39 INFO - Return code: 0 03:56:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:39 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 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:56:39 INFO - Using env: {'DISPLAY': ':0', 03:56:39 INFO - 'HOME': '/home/cltbld', 03:56:39 INFO - 'LANG': 'en_US.UTF-8', 03:56:39 INFO - 'LANGUAGE': 'en_US:en', 03:56:39 INFO - 'LOGNAME': 'cltbld', 03:56:39 INFO - 'MAIL': '/var/mail/cltbld', 03:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:39 INFO - 'MOZ_NO_REMOTE': '1', 03:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:39 INFO - 'NO_EM_RESTART': '1', 03:56:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:39 INFO - 'PWD': '/builds/slave/test', 03:56:39 INFO - 'SHELL': '/bin/bash', 03:56:39 INFO - 'SHLVL': '1', 03:56:39 INFO - 'TERM': 'linux', 03:56:39 INFO - 'TMOUT': '86400', 03:56:39 INFO - 'USER': 'cltbld', 03:56:39 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:39 INFO - '_': '/tools/buildbot/bin/python'} 03:56:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:45 INFO - Running setup.py (path:/tmp/pip-1IU0_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:45 INFO - Running setup.py (path:/tmp/pip-jxvsvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:45 INFO - Running setup.py (path:/tmp/pip-s7Cbum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:45 INFO - Running setup.py (path:/tmp/pip-6Grpu4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:45 INFO - Running setup.py (path:/tmp/pip-tKyyHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:45 INFO - Running setup.py (path:/tmp/pip-6zZEZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:45 INFO - Running setup.py (path:/tmp/pip-MI_wQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:45 INFO - Running setup.py (path:/tmp/pip-ihB6bu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:46 INFO - Running setup.py (path:/tmp/pip-xxO63X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:46 INFO - Running setup.py (path:/tmp/pip-0dDbGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:46 INFO - Running setup.py (path:/tmp/pip-92VX9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:46 INFO - Running setup.py (path:/tmp/pip-4WPpp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:46 INFO - Running setup.py (path:/tmp/pip-uEfeMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:46 INFO - Running setup.py (path:/tmp/pip-SsJG98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:46 INFO - Running setup.py (path:/tmp/pip-cFr4Tl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:46 INFO - Running setup.py (path:/tmp/pip-H9DF1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:46 INFO - Running setup.py (path:/tmp/pip-q2tZ4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:56:46 INFO - Running setup.py install for manifestparser 03:56:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:56:46 INFO - Running setup.py install for mozcrash 03:56:46 INFO - Running setup.py install for mozdebug 03:56:46 INFO - Running setup.py install for mozdevice 03:56:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Running setup.py install for mozfile 03:56:47 INFO - Running setup.py install for mozhttpd 03:56:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Running setup.py install for mozinfo 03:56:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Running setup.py install for mozInstall 03:56:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:56:47 INFO - Running setup.py install for mozleak 03:56:47 INFO - Running setup.py install for mozlog 03:56:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Running setup.py install for moznetwork 03:56:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Running setup.py install for mozprocess 03:56:49 INFO - Running setup.py install for mozprofile 03:56:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Running setup.py install for mozrunner 03:56:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Running setup.py install for mozscreenshot 03:56:49 INFO - Running setup.py install for moztest 03:56:49 INFO - Running setup.py install for mozversion 03:56:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:56:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:56:49 INFO - Cleaning up... 03:56:49 INFO - Return code: 0 03:56:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:49 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 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:56:49 INFO - Using env: {'DISPLAY': ':0', 03:56:49 INFO - 'HOME': '/home/cltbld', 03:56:49 INFO - 'LANG': 'en_US.UTF-8', 03:56:49 INFO - 'LANGUAGE': 'en_US:en', 03:56:49 INFO - 'LOGNAME': 'cltbld', 03:56:49 INFO - 'MAIL': '/var/mail/cltbld', 03:56:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:49 INFO - 'MOZ_NO_REMOTE': '1', 03:56:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:49 INFO - 'NO_EM_RESTART': '1', 03:56:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:49 INFO - 'PWD': '/builds/slave/test', 03:56:49 INFO - 'SHELL': '/bin/bash', 03:56:49 INFO - 'SHLVL': '1', 03:56:49 INFO - 'TERM': 'linux', 03:56:49 INFO - 'TMOUT': '86400', 03:56:49 INFO - 'USER': 'cltbld', 03:56:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:49 INFO - '_': '/tools/buildbot/bin/python'} 03:56:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:56:50 INFO - Running setup.py (path:/tmp/pip-TmZpJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:56:50 INFO - Running setup.py (path:/tmp/pip-c6YKky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:56:50 INFO - Running setup.py (path:/tmp/pip-GCOSlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:56:50 INFO - Running setup.py (path:/tmp/pip-1icz4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:56:50 INFO - Running setup.py (path:/tmp/pip-7Veajz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:56:50 INFO - Running setup.py (path:/tmp/pip-F2P5VK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:56:50 INFO - Running setup.py (path:/tmp/pip-MC4bZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:56:50 INFO - Running setup.py (path:/tmp/pip-TCmpxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:56:50 INFO - Running setup.py (path:/tmp/pip-97gIhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:56:50 INFO - Running setup.py (path:/tmp/pip-j0vneN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:56:50 INFO - Running setup.py (path:/tmp/pip-uuF1MA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:56:50 INFO - Running setup.py (path:/tmp/pip-wwnqap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:56:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:56:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:56:50 INFO - Running setup.py (path:/tmp/pip-1wjAa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:56:51 INFO - Running setup.py (path:/tmp/pip-6hGVrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:51 INFO - Running setup.py (path:/tmp/pip-P6sKer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:56:51 INFO - Running setup.py (path:/tmp/pip-Vgr6Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:56:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:56:51 INFO - Running setup.py (path:/tmp/pip-gFGsv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:56:51 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:56:51 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.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:56:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:53 INFO - Downloading blessings-1.6.tar.gz 03:56:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:56:53 INFO - Installing collected packages: blessings 03:56:53 INFO - Running setup.py install for blessings 03:56:53 INFO - Successfully installed blessings 03:56:53 INFO - Cleaning up... 03:56:53 INFO - Return code: 0 03:56:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:56:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:53 INFO - Reading from file tmpfile_stdout 03:56:53 INFO - Current package versions: 03:56:53 INFO - argparse == 1.2.1 03:56:53 INFO - blessings == 1.6 03:56:53 INFO - blobuploader == 1.2.4 03:56:53 INFO - docopt == 0.6.1 03:56:53 INFO - manifestparser == 1.1 03:56:53 INFO - mozInstall == 1.12 03:56:53 INFO - mozcrash == 0.16 03:56:53 INFO - mozdebug == 0.1 03:56:53 INFO - mozdevice == 0.48 03:56:53 INFO - mozfile == 1.2 03:56:53 INFO - mozhttpd == 0.7 03:56:53 INFO - mozinfo == 0.9 03:56:53 INFO - mozleak == 0.1 03:56:53 INFO - mozlog == 3.1 03:56:53 INFO - moznetwork == 0.27 03:56:53 INFO - mozprocess == 0.22 03:56:53 INFO - mozprofile == 0.28 03:56:53 INFO - mozrunner == 6.11 03:56:53 INFO - mozscreenshot == 0.1 03:56:53 INFO - mozsystemmonitor == 0.0 03:56:53 INFO - moztest == 0.7 03:56:53 INFO - mozversion == 1.4 03:56:53 INFO - psutil == 3.1.1 03:56:53 INFO - requests == 1.2.3 03:56:53 INFO - wsgiref == 0.1.2 03:56:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 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 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:56:53 INFO - Using env: {'DISPLAY': ':0', 03:56:53 INFO - 'HOME': '/home/cltbld', 03:56:53 INFO - 'LANG': 'en_US.UTF-8', 03:56:53 INFO - 'LANGUAGE': 'en_US:en', 03:56:53 INFO - 'LOGNAME': 'cltbld', 03:56:53 INFO - 'MAIL': '/var/mail/cltbld', 03:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:53 INFO - 'MOZ_NO_REMOTE': '1', 03:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:53 INFO - 'NO_EM_RESTART': '1', 03:56:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:53 INFO - 'PWD': '/builds/slave/test', 03:56:53 INFO - 'SHELL': '/bin/bash', 03:56:53 INFO - 'SHLVL': '1', 03:56:53 INFO - 'TERM': 'linux', 03:56:53 INFO - 'TMOUT': '86400', 03:56:53 INFO - 'USER': 'cltbld', 03:56:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:53 INFO - '_': '/tools/buildbot/bin/python'} 03:56:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:56:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:56:53 INFO - Cleaning up... 03:56:53 INFO - Return code: 0 03:56:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:53 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 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:56:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:56:53 INFO - Using env: {'DISPLAY': ':0', 03:56:53 INFO - 'HOME': '/home/cltbld', 03:56:53 INFO - 'LANG': 'en_US.UTF-8', 03:56:53 INFO - 'LANGUAGE': 'en_US:en', 03:56:53 INFO - 'LOGNAME': 'cltbld', 03:56:53 INFO - 'MAIL': '/var/mail/cltbld', 03:56:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:53 INFO - 'MOZ_NO_REMOTE': '1', 03:56:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:53 INFO - 'NO_EM_RESTART': '1', 03:56:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:53 INFO - 'PWD': '/builds/slave/test', 03:56:53 INFO - 'SHELL': '/bin/bash', 03:56:53 INFO - 'SHLVL': '1', 03:56:53 INFO - 'TERM': 'linux', 03:56:53 INFO - 'TMOUT': '86400', 03:56:53 INFO - 'USER': 'cltbld', 03:56:53 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:53 INFO - '_': '/tools/buildbot/bin/python'} 03:56:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:54 INFO - Downloading/unpacking jsonschema 03:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:56:55 INFO - Downloading/unpacking functools32 (from jsonschema) 03:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:56:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:56:56 INFO - warning: no files found matching '*.txt' 03:56:56 INFO - no previously-included directories found matching 'build' 03:56:56 INFO - no previously-included directories found matching 'dist' 03:56:56 INFO - no previously-included directories found matching '.git*' 03:56:56 INFO - Installing collected packages: jsonschema, functools32 03:56:56 INFO - Running setup.py install for functools32 03:56:56 INFO - warning: no files found matching '*.txt' 03:56:56 INFO - no previously-included directories found matching 'build' 03:56:56 INFO - no previously-included directories found matching 'dist' 03:56:56 INFO - no previously-included directories found matching '.git*' 03:56:56 INFO - Successfully installed jsonschema functools32 03:56:56 INFO - Cleaning up... 03:56:56 INFO - Return code: 0 03:56:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:56:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:56:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:56:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:56:56 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f104287bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c923b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 03:56:56 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', 'flake8'] in /builds/slave/test/build 03:56:56 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 flake8 03:56:56 INFO - Using env: {'DISPLAY': ':0', 03:56:56 INFO - 'HOME': '/home/cltbld', 03:56:56 INFO - 'LANG': 'en_US.UTF-8', 03:56:56 INFO - 'LANGUAGE': 'en_US:en', 03:56:56 INFO - 'LOGNAME': 'cltbld', 03:56:56 INFO - 'MAIL': '/var/mail/cltbld', 03:56:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:56:56 INFO - 'MOZ_NO_REMOTE': '1', 03:56:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:56:56 INFO - 'NO_EM_RESTART': '1', 03:56:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:56:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:56:56 INFO - 'PWD': '/builds/slave/test', 03:56:56 INFO - 'SHELL': '/bin/bash', 03:56:56 INFO - 'SHLVL': '1', 03:56:56 INFO - 'TERM': 'linux', 03:56:56 INFO - 'TMOUT': '86400', 03:56:56 INFO - 'USER': 'cltbld', 03:56:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:56:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:56:56 INFO - '_': '/tools/buildbot/bin/python'} 03:56:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:56:56 INFO - Downloading/unpacking flake8 03:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:56:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:56:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:56:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:56:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:56:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:56:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:56:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:56:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:56:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:56:58 INFO - no previously-included directories found matching 'docs/_build' 03:56:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:56:59 INFO - Running setup.py install for pep8 03:56:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:56:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:56:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:56:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:56:59 INFO - no previously-included directories found matching 'docs/_build' 03:56:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:56:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:56:59 INFO - Cleaning up... 03:56:59 INFO - Return code: 0 03:56:59 INFO - Running post-action listener: _resource_record_post_action 03:56:59 INFO - Running post-action listener: _start_resource_monitoring 03:56:59 INFO - Starting resource monitoring. 03:56:59 INFO - ##### 03:56:59 INFO - ##### Running install step. 03:56:59 INFO - ##### 03:56:59 INFO - Running pre-action listener: _resource_record_pre_action 03:56:59 INFO - Running main action method: install 03:56:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:56:59 INFO - Reading from file tmpfile_stdout 03:56:59 INFO - Detecting whether we're running mozinstall >=1.0... 03:56:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:56:59 INFO - Reading from file tmpfile_stdout 03:56:59 INFO - Output received: 03:56:59 INFO - Usage: mozinstall [options] installer 03:56:59 INFO - Options: 03:56:59 INFO - -h, --help show this help message and exit 03:56:59 INFO - -d DEST, --destination=DEST 03:56:59 INFO - Directory to install application into. [default: 03:56:59 INFO - "/builds/slave/test"] 03:56:59 INFO - --app=APP Application being installed. [default: firefox] 03:56:59 INFO - mkdir: /builds/slave/test/build/application 03:56:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 03:56:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 03:57:15 INFO - Reading from file tmpfile_stdout 03:57:15 INFO - Output received: 03:57:15 INFO - /builds/slave/test/build/application/firefox/firefox 03:57:15 INFO - Running post-action listener: _resource_record_post_action 03:57:15 INFO - ##### 03:57:15 INFO - ##### Running run-tests step. 03:57:15 INFO - ##### 03:57:15 INFO - Running pre-action listener: _resource_record_pre_action 03:57:15 INFO - Running main action method: run_tests 03:57:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:57:15 INFO - Python 2.7.3 03:57:15 INFO - Return code: 0 03:57:15 INFO - grabbing minidump binary from tooltool 03:57:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:57:15 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d21100>, '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 0x1d22000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d22490>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:57:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:57:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 03:57:15 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:57:15 INFO - Return code: 0 03:57:15 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 03:57:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:57:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:57:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 03:57:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:57:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:57:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:57:16 INFO - Return code: 0 03:57:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:57:16 INFO - Using env: {'DISPLAY': ':0', 03:57:16 INFO - 'HOME': '/home/cltbld', 03:57:16 INFO - 'LANG': 'en_US.UTF-8', 03:57:16 INFO - 'LANGUAGE': 'en_US:en', 03:57:16 INFO - 'LOGNAME': 'cltbld', 03:57:16 INFO - 'MAIL': '/var/mail/cltbld', 03:57:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:57:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 03:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:16 INFO - 'MOZ_NO_REMOTE': '1', 03:57:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:57:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 03:57:16 INFO - 'NO_EM_RESTART': '1', 03:57:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 03:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:16 INFO - 'PWD': '/builds/slave/test', 03:57:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:57:16 INFO - 'SHELL': '/bin/bash', 03:57:16 INFO - 'SHLVL': '1', 03:57:16 INFO - 'TERM': 'linux', 03:57:16 INFO - 'TMOUT': '86400', 03:57:16 INFO - 'USER': 'cltbld', 03:57:16 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867', 03:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:16 INFO - '_': '/tools/buildbot/bin/python'} 03:57:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:57:16 INFO - mozversion application_buildid: 20160219025400 03:57:16 INFO - mozversion application_changeset: 55991a2134a089f328988760ed79601dad8a4cd6 03:57:16 INFO - mozversion application_display_name: Nightly 03:57:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:57:16 INFO - mozversion application_name: Firefox 03:57:16 INFO - mozversion application_remotingname: firefox 03:57:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:57:16 INFO - mozversion application_vendor: Mozilla 03:57:16 INFO - mozversion application_version: 47.0a1 03:57:16 INFO - mozversion platform_buildid: 20160219025400 03:57:16 INFO - mozversion platform_changeset: 55991a2134a089f328988760ed79601dad8a4cd6 03:57:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:57:16 INFO - mozversion platform_version: 47.0a1 03:57:16 INFO - using testdate: 1455883036 03:57:16 INFO - actual date: 1455883036 03:57:16 INFO - starting webserver on 'localhost:42012' 03:57:16 INFO - SUITE-START | Running 2 tests 03:57:16 INFO - TEST-START | dromaeo_css 03:57:16 INFO - operating with platform_type : linux_ 03:57:16 INFO - Initialising browser for dromaeo_css test... 03:57:16 INFO - TEST-INFO | started process 6403 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv4Q2Si/profile http://localhost:42012/getInfo.html) 03:57:31 INFO - PROCESS | 6403 | 03:57:31 INFO - PROCESS | 6403 | (process:6485): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:57:35 INFO - PROCESS | 6403 | __metrics Screen width/height:1600/1200 03:57:35 INFO - PROCESS | 6403 | colorDepth:24 03:57:35 INFO - PROCESS | 6403 | Browser inner width/height: 1024/697 03:57:35 INFO - PROCESS | 6403 | __metrics 03:57:36 ERROR - PROCESS | 6403 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 03:57:38 INFO - TEST-INFO | 6403: exit 0 03:57:38 INFO - Browser initialized. 03:57:38 INFO - Running cycle 1/1 for dromaeo_css test... 03:57:38 INFO - TEST-INFO | started process 6535 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv4Q2Si/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:57:39 INFO - PROCESS | 6535 | 03:57:39 INFO - PROCESS | 6535 | (process:6593): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 03:57:50 INFO - PROCESS | 6535 | RSS: Main: 156798976 03:57:50 INFO - PROCESS | 6535 | 03:58:40 INFO - PROCESS | 6535 | 1455883120023 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] 04:00:53 INFO - PROCESS | 6535 | Cycle 1(1): loaded http://localhost:42012/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42012/tests/dromaeo/cssquery-ext.html) 04:00:54 INFO - PROCESS | 6535 | RSS: Main: 163098624 04:00:54 INFO - PROCESS | 6535 | 04:03:56 INFO - PROCESS | 6535 | Cycle 1(1): loaded http://localhost:42012/tests/dromaeo/cssquery-ext.html (next: http://localhost:42012/tests/dromaeo/cssquery-jquery.html) 04:03:57 INFO - PROCESS | 6535 | RSS: Main: 161878016 04:03:57 INFO - PROCESS | 6535 | 04:06:59 INFO - PROCESS | 6535 | Cycle 1(1): loaded http://localhost:42012/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42012/tests/dromaeo/cssquery-mootools.html) 04:06:59 INFO - PROCESS | 6535 | RSS: Main: 161832960 04:06:59 INFO - PROCESS | 6535 | 04:10:01 INFO - PROCESS | 6535 | Cycle 1(1): loaded http://localhost:42012/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42012/tests/dromaeo/cssquery-prototype.html) 04:10:01 INFO - PROCESS | 6535 | RSS: Main: 160976896 04:10:01 INFO - PROCESS | 6535 | 04:13:04 INFO - PROCESS | 6535 | Cycle 1(1): loaded http://localhost:42012/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42012/tests/dromaeo/cssquery-yui.html) 04:13:05 INFO - PROCESS | 6535 | RSS: Main: 159412224 04:13:05 INFO - PROCESS | 6535 | 04:16:07 INFO - PROCESS | 6535 | Cycle 1(1): loaded http://localhost:42012/tests/dromaeo/cssquery-yui.html (next: http://localhost:42012/tests/dromaeo/cssquery-dojo.html) 04:16:08 INFO - PROCESS | 6535 | RSS: Main: 164237312 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | __start_tp_report 04:16:08 INFO - PROCESS | 6535 | _x_x_mozilla_page_load 04:16:08 INFO - PROCESS | 6535 | _x_x_mozilla_page_load_details 04:16:08 INFO - PROCESS | 6535 | |i|pagename|runs| 04:16:08 INFO - PROCESS | 6535 | |0;dojo.html;2597.3117123962165;2601.1936299747067;2628.7896968242544;2633.4074833162554;2645.1006657736366;4287.3568964655315;4381.036172042153;4406.162829062484;4421.6683748718815;4443.733375997443;4376.452943382069;4439.689221754478;4474.552544745524;4536.569025942546;4544.681872268942;3899.473571067912;4104.179164167163;4215.199112168678;4219.472565929259;4221.746695198298;4038.495188101502;4102.958970410287;4170.645495132917;4184.811683474251;4236.661067114624;4052.696047796427;4066.085130845566;4085.632293093606;4086.2031903778775;4130.752154870717;4081.9387709184384;4101.99501122224;4102.118044620391;4122.814473348706;4136.9793151034055;4002.639762421367;4078.632923036941;4102.097538541516;4146.688998325108;4149.232392007489;4395.252807022782;4395.890102747425;4400;4429.13631841791;4437.4896886858105;4649.116667833101;4711.552402521755;4735.431748190204;4738.1708201064675;4759.357486739281;4757.1437141314545;4776.593989510861;4780.928286075712;4783;4804.231322988305;4391.912161756747;4400.053988392511;4416.933745993812;4443;4476.127155204704;4700.905981880412;4772.045590881838;4788.04239152171;4813.181759100961;4911.066897289504;4640.489546149921;4655.767211639472;4658.697184682985;4661.091087238024;4707.176244157259;4702.388689470347;4721.409823772028;4732.621390288768;4748.976255118771;4769.42766867978;4586.770661466913;4694.483606803249;4741.217699079613;4747.667663263538;4792.161371759928;4616.95383046172;4623.098495793355;4633.235516139866;4635.281531362644;4676.18166820805;3879.1465877506903;3927.626875446828;3952.1700442906736;3966.167104907944;3994.500687414073;3506.526618906415;3519.04985653872;3528.329617372742;3544.7341449390883;3545.4859045438784;4040.1717647882115;4060.8578699745367;4078.408630748525;4083.0813067059676;4109.91780164395;4636.188666983332;4731.2903064539905;4757.857264282077;4796.112719146969;4799;4075.694322925792;4077.7145599807727;4079.673626109904;4100.774457404871;4111.157212771434;4150.730202536825;4164.312888373445;4205.306124489486;4228.70399072062;4249.171411574714;4456.17560751262;4460.531644177315;4485.304777759453;4518.051209246095;4557.886052848706;4702.76486175683;4725.716457012591;4729.077829823152;4745.596624286836;4753.073150735573;4354.172707185625;4481.014176881081;4481.350204220435;4481.641468682563;4515.525869783757;4647.604953578983;4673.158831410243;4674.252119660838;4677.953220467888;4719.504452032487;4796.8081276748535;4805.879853003703;4810.63920205979;4815.061063092663;4851.32081508582;4461.263891458003;4526.751028693454;4535.206338890615;4615.561521655437;4626.467956184947;4521.2087884619405;4563.269876819693;4590.0590378971565;4621.907561848677;4627.981843994316;4466.620337271368;4476.171908196994;4506.278995360726;4543.182227199136;4561.794719237709;4513.796879140512;4625.560571745679;4630.490646028867;4644.489106198383;4645.512221216723;4521.321801729767;4572.794224259983;4580.335851301609;4596.517365676689;4605.838795642137 04:16:08 INFO - PROCESS | 6535 | |1;ext.html;506.26591442408494;508.16153346977484;531.9867003324916;537.1405750798723;546.3089192171901;32081.197970050733;32546.511802322955;32613.67386326136;32646.510302345425;32648;33714.32571348571;34007.65992340076;34024.829875850584;34129.82935085327;34228;7580.772576822704;7704.152543220242;7731.53610783352;7751.22487751226;7763.534187948713;7612.200718924516;7826.686932522693;7881.881771773428;7890.171531989145;7915.762527124196;7838.843223135534;7912.090109637379;7912.881306780403;7929.326007289358;7931.881021784678;7806.297433231037;7812.10160831506;7877.212278772135;7890.763277101696;7901.012373453318;7633;7741.922580774177;7766.6505007274245;7787.805304867367;7796.4932279401655;33349.833250833835;33709.83145084284;33890.644374225005;34114.317713645585;34123.31753364944;2126.26643807886;2654.349684327352;2861.914142575726;2914.6793852676187;3099.7520198384077;24975.875120624463;25015.249542513753;26833.46333073347;27480.312992175157;28410.874803771567;3219.3239419721885;3243.464828303327;3253.4631785755323;3297.3734990351995;3380.678835510623;170862;172211;172986.99999999875;173124;307006.9999999978;55657.44342556522;56275.437245627836;56775;56780.43219567834;56917;50323.24515132276;50404.24393634099;50430.49569504258;50521;50703.49296507062;14150.575482735534;14226;14362.856371436152;14402.279886005657;14410.855891440951;10845.728856778644;11051.778964420666;11099.944500277446;11117.499712512956;11126.054285385671;19042.61914761697;19083.332083377016;19106.71339929902;19252;19364;7681.61591920413;7824.530528168328;7882.487638303492;7926.643301051467;7989.880151797728;803.9839203215903;815.3151352863505;889.2974550105473;907.5734404829719;922.9307801914883;28274.434511309657;28751.281217969718;29479.410411791643;30281;31346.21634459111;932.883389576303;967.385710074108;968.138356862393;970.6408628807245;980.2550061952825;13288.468461261442;13603.455861765418;13729.107608005648;13818.585442436743;13893.722125557431;302596;303897;305204;305779;305959;44931.55068449274;45304;45485;45697.54302456933;45697.54302457;1211.8606360268507;1221.2123180548497;1223.247702662856;1230.9507619695112;1247.0460098024837;25930.48139037171;26487.33781655474;26567.33581660474;26571.601425979014;26781;39110.413343800436;39408.40887386749;39756;39828.2034359317;39956.80021599874;233655;234167;235276;236110;237239;29322.41355172842;39211.6078839208;39359.2128157441;39431.408528872664;40094;35016.474752879236;36294.8185259072;36424.63575364213;38000.557835597705;38661;32905.835470822494;33003.8349808259;33682.3263534723;34140.658593414744;34309.656903430645;36613.26773464462;40062.79968600138;41607.58392416037;42035;42856.142877142905;33744.662553375136;34672.70609292113;34814.303713926085;34820.30359392849;38126.23747525089;34802.651973479944;35988.56045758141;36098.45852312165;36225;36725.44911826271;28700.712992869805;29275.41449171047;29828.403431931674;31661;32343.191420214684 04:16:08 INFO - PROCESS | 6535 | |2;jquery.html;336.20487546951466;338.28115255082946;338.7391708384544;341.81541967337637;346.9358168738782;15645.921770391147;15665.92167039166;15786.684266314667;16318.91840540797;16372.426965056196;10609.681709548706;10930.945345273263;14980.176090315028;15515;16036.518904432885;4592.334111553822;4661.953380466202;4739.573438390544;4744.881377965544;4800.183968725308;4718.127146477896;4761.571458568728;4780.904381912377;4784.688995215317;4785.019071090418;4601.240795268794;4798.4961579034225;4958.380202474691;5162.070827251093;5168.2764412982215;5036.244563315496;5045.0666626674;5083.669561478492;5117.385913690362;5435.728213589305;5248.763805628756;5257.474252574751;5257.737113144366;5263.947360526384;5268.157094864841;12126.454309556091;18523.90738046301;18760.874347539193;18965.62068758631;19168.520786980298;1821.5537193387704;1890.1588792987127;1891.6973284274588;1897.6584214841323;2056.5578400643935;8192.590370481512;8893.377463577552;9065.773355666095;9115.817683646355;9148.725538233864;1792.2562136713248;1840.5030641726792;1861.6835138026433;1872.372755127034;1883.5667796406767;53146.73426632881;54209;55957.44042559563;56184.438155618736;56352.43647563513;9365.531723413802;9718.902810971942;9744.658936937172;9773.12041916231;9780.657676981415;12074.094442916816;12155.574554890536;12181.208221465577;12262.632121036384;12276.38618069093;7039.507234493536;7051.1186101737285;7101.396381307647;7180.8922866157045;7221.458390620619;6827;6925.203601585781;6932.0988271524375;6965.965170174117;7032.226455089936;4231.746095234295;4258;4263;4286.828526858953;4339.934900976488;1629.8614617757387;1729.2996336483743;1805.7766886108495;1812.1664034544258;1815.6913324734562;395.36543847125523;408.3792635194488;850.8766228896899;940.0975063938696;949.1457688080644;9810.607575697035;10359.63741269052;10404.167666586654;15667.608309792118;16654.75017874733;816.979575510617;853.620139038122;861.646724842824;913.4291068082448;966.4346357381011;8810.163034511712;8917.866232006525;9221.631134754534;9314.44113353201;9532.761680958032;277492;282389.94025149534;282751;284341;400620;62993.37006629966;68058.31941680619;68487.65756171287;68512;69714;934.7756538430864;938.957746901391;981.0483830684225;1005.0401866217744;1013.9391636501833;13916.234607096707;14213.573592792232;14269.286535672967;14422.567322980327;14566.34451449666;30599.08202753832;30719.248263107733;31152.688473115886;31591.526127108573;32269.35461290808;38800.611993880826;39162.60837391589;39941.80029099836;40361.59638403579;40446.59553404428;6559.048937904073;6708.496862735216;6711.46308295345;6716.429103526059;6765.8985115224295;8578.699745508877;9877.50612469388;9906.950465247915;10031.949840250752;10202.183825294107;8228.21831925966;8399.832003359776;8534.573271336532;8561.61472733716;8662.047174810892;8251.83496330082;8447.915520844714;8616.439931404439;8883.422577532698;8933.553322333988;8409.2431681149;8483.44857584255;8733.388662793797;8739.650413983369;8840.027596964457;6440.645764482999;6561.934380656132;6584.4732421405215;6630.403263706096;6685.598864068172;8345.457545259538;8514.74455766328;8560.657573697232;8603;8649.956750216208 04:16:08 INFO - PROCESS | 6535 | |3;mootools.html;1353.991276499008;1365.1126767601058;1473.38117990444;1674.2214870085422;1703.6422351306217;5773.624714393572;6644.368784965421;6774.627395493252;6892.896606550899;6896.34484723951;6658.067870498134;6769.559978601387;6787;6793.728250869959;6806.217285012225;2048.5288383671764;2068.2244158440585;2072.647649899513;2073.2743539761113;2074.605824893273;2034.8473864460225;2049.1086377425795;2061.80412860778;2094.0890712540017;2095.863768855027;2080.1679328268738;2082.937511874646;2084.801943815343;2097.5595125023765;2098.695689125076;2076.709260703503;2079.365793433002;2080.95838083239;2081.105124796337;2086.6244076066378;2034.7151398804178;2038.1337931379253;2067.669172932324;2069.627467055929;2075.7820428854902;6860.108185935846;6906.827329316757;6918.688659010357;6965.233824279325;7083.82290442743;4113.547509773923;4178.7283826551175;4190.832366705328;4199.370094485821;4200.936985945214;2655.7742591879714;2656.296081538394;2708.8103832731717;2733.671959364869;2735.4529094181044;862.2713806833278;867.9262262707678;873.3057219510505;897.8114595934861;901.4951627088785;9197.402168859002;9234.722958311262;9238.445693258425;9256.72229833106;9292.860607090832;3815.9236815264094;3828.157805282833;3894.357431023849;3909.58949310324;3916.2559113768293;7948.6820527179425;7971.800704982422;7974.920250797418;8004.799880003046;8045.356371490384;4206.684498662613;4216.683748718859;4231.788410579458;4233.280342341809;4243.27864263076;3208.9358212835614;3726.832292546842;3788.6400791924725;3846.5576458707046;3881.6894648428056;3285.8849940251976;3290.555774970396;3327.5341452196712;3340.4154273002127;3351.3632409842053;1006.9798604028025;1010.161565900301;1013.0730381700662;1014.8579198912158;1051.8948105189572;1112.2881355932209;1137.2380505061628;1145.1811954452605;1159.9826002609968;1165.5396118533301;6400.55196136266;6408.903866442007;6462.1276127723095;6493.253275873335;6511.218653761484;1014.7260239735231;1021.9795604088025;1036.259074761549;1038.1072277841051;1048.7535429174138;5123.846284611468;5196.246544251138;5223.112070947948;5237.711925844115;5240.449752775904;8136.593170341577;8223.506589604644;8225.300849427866;8321.66713331472;8349.45728527656;8170.264676179172;8279.337652987746;8306.958465207636;8320.126386729306;8328.792325112825;6528.445082168069;6599.1091202687985;6664.933350666432;6691.866162676719;6696.732130714814;6286.654234017173;6303.086052522451;6308.463780578702;6326;6348.206474190726;7238.131424229126;7381.704731810667;7415.036045314075;7470.327670509463;7477.813054673677;7555.584442855476;7624.809379765328;7628.275313845176;7635.083789945242;7756.185600511865;5140.3831540215415;5704.572157088318;6006.399360063959;6055.969720151371;6073.665948372882;6294.748210071546;6368.840778980377;6392.744290228339;6398.296187419287;6419.2296924369375;5571.331440227036;5616.157576363578;5681.119426488735;5720.199172115824;5758.942410575841;5688.914666279927;5781.537477001746;5859.472647461749;5868.119782032729;5904.793332233351;6096.177016102769;6188.040853667689;6264.498840092872;6291.150694656163;6297.118403423616;5746.109353050284;5766.163906233656;5802.82591522255;5806.82579522615;5813.447722466359;5745.827625171251;5771.769129234952;5779.942200577941;5788.681622510634;5794.739236734441 04:16:08 INFO - PROCESS | 6535 | |4;prototype.html;301.1072171012946;308.05580894558176;309.21305278067314;309.71506214282874;312.0187011847739;5443.700596467193;5515.255440515529;5528.834134975947;5659.547236221108;5697.088465845465;5479.6164268501225;5508;5546.805861794838;5573.804916827912;5576.7211639417965;4071.246819338432;4168.645665118469;4206.4321316622245;4209.536950935395;4210.242156411845;4142.647874930634;4143.585641435848;4144.875653730378;4162.750234985895;4165.895852603679;4111.835526578933;4121.835126594932;4132.173565286955;4145;4181.3309870420735;4149.128682976563;4163.83344666216;4170.749755014709;4173.791310434482;4179.6029377209115;3452.861885524576;3608.4948107265004;3617.602063772983;3618.3125206210734;3629.927401451956;5144;5226.451222621608;5239.476052394768;5243.711595862226;5254.3169387979315;4692.906141877177;4697.2484402495775;4698.412698412699;4763.190257656207;4773.76131193439;5747.310322761254;5782.277215348082;5782.710864456761;5787;5795.82612521625;3780.7920564368947;3855.9807200964096;3856.865009724674;3861.7876016819064;3868.845246190149;6023.277206735176;6071.969640151771;6087.726052327655;6101.328853826076;6111.724972376253;4022.054817117975;4041.6160464755803;4041.777702226347;4048.0487085534874;4052.918941621151;4090.3864420337186;4094.651954583834;4099.098198396348;4102.897427564335;4103.938440923389;4548.135854187693;4749.216379297446;4768.380110585602;4772.737499437564;4776.02091571234;5630.746616402272;5718.942810571841;5785.768569257267;5802.187693722883;5805.593608447368;1425.6008317670862;1453.883689304853;1455.3087283540233;1456.3446449097949;1459.0880699562774;951.1487218939011;955.8422860228123;965.5896244096231;985.3595163144014;986.363795351994;5032.270320803536;5033.395992480926;5044.672096313728;5055.418626857959;5067.366579177603;6054.061620448838;6072.119542666377;6130.356312587203;6135.631862088289;6136.447719705248;5015.22264049073;5025.748712564357;5036.345275114285;5038.168702164102;5058.46886076964;3199.7120259176786;3238.3847069056806;3282.671732826698;3283.326917981808;3283.359744849779;5701;5719.4852463277675;5725.370209276976;5730.77076916927;5737.282839645044;6282.403171698681;6300.495960323163;6300.558960872787;6301;6317.4946004319545;5571.498565129158;5696.686682232434;5705.286839145107;5733.455321744427;5746.281714785652;6123.203983482119;6123.775244951116;6124.050772130327;6126.632402055712;6129.785457508966;5883.176355310175;5925.4370834770625;5951.047832346804;5965.105234214902;5965.761369545343;5679.488846003879;5702.116171993348;5755.395683453345;5760.135979603093;5775.6823374714795;3926.1166237596312;4058.675305975456;4065.1463192729857;4065.837366505307;4113.609207125318;5787.334456537384;5909.409059094056;5965.254343207099;5985.730642121202;5986.880262394815;5758.963386590286;5760.452756988079;5775.306963164448;5782.624129431573;5789.8263052108505;5750.7987220447085;5752.942470575241;5753.971230143822;5758.8848223034465;5760.884782304247;5454.863628409163;5630.943690563206;5655.406182350794;5658.7736490541565;5658.85852853682;5761.769529218785;5779.537636988946;5806.82579522615;5830.4461076197695;5837.153612726216;5671;5695.943040569707;5696.914546281726;5714.3714191438075;5715.771369145187 04:16:08 INFO - PROCESS | 6535 | |5;yui.html;530.5728888244963;577.0766773162937;643.8680070585527;646;653.7352372289224;11567.132465065122;11689.766204675912;11747.706307342321;11779.764404711901;11821.17251792375;10803.513841877115;13092.47630094795;13288.335583220849;13316;13327.733445331087;738.4904415952989;749.6251874062968;750.2797314578012;752.732779863149;767.5394763142126;771.1286246541429;771.656612807301;771.7530390275115;771.7839005078587;775.685847231872;805.3718099882072;809.7408829174666;812.8780682897553;815.5473712089788;816.2572059425923;794.940379471542;799.3684988858795;800.4556901307109;802.9036515618105;803.5018288661067;590.397794249867;590.8257064166038;593.5933885288591;596.150485558078;597.4145337569163;12171.878281217252;12237.265764054186;12273.38633068352;12274.938625306906;12323.075769317336;10553.366797992068;10758.462076896123;10931.18016148792;10954.945225273903;11000.22998390113;8176.3458923286;8360.26352461679;8383.496990180609;8443.408961372706;8543.786405339853;3216.292415668549;3230.1117192772176;3239.303549736794;3242.91892702682;3250.983745081283;43595.12809743851;43654.563454365365;43676.344854827206;43807.561924380985;44167.77916110431;8360.247577718035;8605.526696031653;8646.697365592174;8716.564171791386;8752.037275899645;16092.517224483285;16152.273147708382;16174.272157752694;16177.352905883869;16205.351785928431;6058.636481811105;6143.938560614426;6210.347913469111;6291.55959082869;6416.486681065504;3497.3529896969144;3605.405108157177;3615.060084378081;3616.4213725803747;3674.228412033503;2764.3227409284855;2797.2587264374956;2804.7195280471788;2807.396409771909;2809.115128734442;467.5581575411272;469.2445163287105;470.28751441566055;474.5254745254745;475.2491064118684;471.14016919122736;546.8277492589822;565.1945976982784;566.1818672018893;578.6961845031308;7479.326860582573;7770.261825126604;7831.177726338767;7866.410019248579;7962.601869906482;437.8664507325233;472.3858983321668;548.9670619762827;601.3925934805877;605.6547767772328;3449.775764575345;3611.8916432507067;3917.8236979335998;3943.428202910562;4050.2911990401562;48361.5163848364;49452.01095978132;49586.50413495891;53455.73272133614;54669;43714;44743.10513789706;44835.77582112069;44865;46246.306305405444;41889.790551047656;42282;42901.57098429038;42928.57071429246;43051.56948430538;8256.711015114442;8384.664613415394;8492.872606911025;8502.574871256536;8567.4002819802;32860.67139328576;33458;34204.14489637779;34856.65143348534;35389.292214156085;41275.793621032906;41448.58551414447;41927.37108943307;42690;42711.57288427076;10157.847632285373;10229.386236825814;10269.640562580274;10297.737633377676;10468.371897686164;10501.684949451528;10675.626353077605;10748.355098694103;10800;11281.774364512828;9893.604255829687;10548.947255263982;10594.735131621772;10838.891611083789;11180.329180249211;10052.944440833608;10143.797124057624;10282.892007138667;10591.205659575717;10612.681619551426;10573.841392379274;10689;10714.678559643224;10916.56333746673;11097.833532496858;9070.410423322463;9239.39943903671;9344.579493922925;9456.858147127936;9611.471369074769;8870.73387798367;9193.908060919573;9278.675246366345;9450.763730906783;9502 04:16:08 INFO - PROCESS | 6535 | __end_tp_report 04:16:08 INFO - PROCESS | 6535 | __start_cc_report 04:16:08 INFO - PROCESS | 6535 | _x_x_mozilla_cycle_collect,336 04:16:08 INFO - PROCESS | 6535 | __end_cc_report 04:16:08 INFO - PROCESS | 6535 | __startTimestamp1455884168058__endTimestamp 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | ------- Summary: start ------- 04:16:08 INFO - PROCESS | 6535 | Number of tests: 6 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | [#0] dojo.html Cycles:165 Average:4379.82 Median:4485.30 stddev:435.67 (9.7%) stddev-sans-first:413.95 04:16:08 INFO - PROCESS | 6535 | Values: 2597.3 2601.2 2628.8 2633.4 2645.1 4287.4 4381.0 4406.2 4421.7 4443.7 4376.5 4439.7 4474.6 4536.6 4544.7 3899.5 4104.2 4215.2 4219.5 4221.7 4038.5 4103.0 4170.6 4184.8 4236.7 4052.7 4066.1 4085.6 4086.2 4130.8 4081.9 4102.0 4102.1 4122.8 4137.0 4002.6 4078.6 4102.1 4146.7 4149.2 4395.3 4395.9 4400.0 4429.1 4437.5 4649.1 4711.6 4735.4 4738.2 4759.4 4757.1 4776.6 4780.9 4783.0 4804.2 4391.9 4400.1 4416.9 4443.0 4476.1 4700.9 4772.0 4788.0 4813.2 4911.1 4640.5 4655.8 4658.7 4661.1 4707.2 4702.4 4721.4 4732.6 4749.0 4769.4 4586.8 4694.5 4741.2 4747.7 4792.2 4617.0 4623.1 4633.2 4635.3 4676.2 3879.1 3927.6 3952.2 3966.2 3994.5 3506.5 3519.0 3528.3 3544.7 3545.5 4040.2 4060.9 4078.4 4083.1 4109.9 4636.2 4731.3 4757.9 4796.1 4799.0 4075.7 4077.7 4079.7 4100.8 4111.2 4150.7 4164.3 4205.3 4228.7 4249.2 4456.2 4460.5 4485.3 4518.1 4557.9 4702.8 4725.7 4729.1 4745.6 4753.1 4354.2 4481.0 4481.4 4481.6 4515.5 4647.6 4673.2 4674.3 4678.0 4719.5 4796.8 4805.9 4810.6 4815.1 4851.3 4461.3 4526.8 4535.2 4615.6 4626.5 4521.2 4563.3 4590.1 4621.9 4628.0 4466.6 4476.2 4506.3 4543.2 4561.8 4513.8 4625.6 4630.5 4644.5 4645.5 4521.3 4572.8 4580.3 4596.5 4605.8 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | [#1] ext.html Cycles:180 Average:41085.34 Median:34268.83 stddev:65873.42 (192.2%) stddev-sans-first:65987.75 04:16:08 INFO - PROCESS | 6535 | Values: 506.3 508.2 532.0 537.1 546.3 32081.2 32546.5 32613.7 32646.5 32648.0 33714.3 34007.7 34024.8 34129.8 34228.0 7580.8 7704.2 7731.5 7751.2 7763.5 7612.2 7826.7 7881.9 7890.2 7915.8 7838.8 7912.1 7912.9 7929.3 7931.9 7806.3 7812.1 7877.2 7890.8 7901.0 7633.0 7741.9 7766.7 7787.8 7796.5 33349.8 33709.8 33890.6 34114.3 34123.3 2126.3 2654.3 2861.9 2914.7 3099.8 24975.9 25015.2 26833.5 27480.3 28410.9 3219.3 3243.5 3253.5 3297.4 3380.7 170862.0 172211.0 172987.0 173124.0 307007.0 55657.4 56275.4 56775.0 56780.4 56917.0 50323.2 50404.2 50430.5 50521.0 50703.5 14150.6 14226.0 14362.9 14402.3 14410.9 10845.7 11051.8 11099.9 11117.5 11126.1 19042.6 19083.3 19106.7 19252.0 19364.0 7681.6 7824.5 7882.5 7926.6 7989.9 804.0 815.3 889.3 907.6 922.9 28274.4 28751.3 29479.4 30281.0 31346.2 932.9 967.4 968.1 970.6 980.3 13288.5 13603.5 13729.1 13818.6 13893.7 302596.0 303897.0 305204.0 305779.0 305959.0 44931.6 45304.0 45485.0 45697.5 45697.5 1211.9 1221.2 1223.2 1231.0 1247.0 25930.5 26487.3 26567.3 26571.6 26781.0 39110.4 39408.4 39756.0 39828.2 39956.8 233655.0 234167.0 235276.0 236110.0 237239.0 29322.4 39211.6 39359.2 39431.4 40094.0 35016.5 36294.8 36424.6 38000.6 38661.0 32905.8 33003.8 33682.3 34140.7 34309.7 36613.3 40062.8 41607.6 42035.0 42856.1 33744.7 34672.7 34814.3 34820.3 38126.2 34802.7 35988.6 36098.5 36225.0 36725.4 28700.7 29275.4 29828.4 31661.0 32343.2 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | [#2] jquery.html Cycles:180 Average:20053.97 Median:5100.53 stddev:51123.66 (1002.3%) stddev-sans-first:51245.64 04:16:08 INFO - PROCESS | 6535 | Values: 336.2 338.3 338.7 341.8 346.9 15645.9 15665.9 15786.7 16318.9 16372.4 10609.7 10930.9 14980.2 15515.0 16036.5 4592.3 4662.0 4739.6 4744.9 4800.2 4718.1 4761.6 4780.9 4784.7 4785.0 4601.2 4798.5 4958.4 5162.1 5168.3 5036.2 5045.1 5083.7 5117.4 5435.7 5248.8 5257.5 5257.7 5263.9 5268.2 12126.5 18523.9 18760.9 18965.6 19168.5 1821.6 1890.2 1891.7 1897.7 2056.6 8192.6 8893.4 9065.8 9115.8 9148.7 1792.3 1840.5 1861.7 1872.4 1883.6 53146.7 54209.0 55957.4 56184.4 56352.4 9365.5 9718.9 9744.7 9773.1 9780.7 12074.1 12155.6 12181.2 12262.6 12276.4 7039.5 7051.1 7101.4 7180.9 7221.5 6827.0 6925.2 6932.1 6966.0 7032.2 4231.7 4258.0 4263.0 4286.8 4339.9 1629.9 1729.3 1805.8 1812.2 1815.7 395.4 408.4 850.9 940.1 949.1 9810.6 10359.6 10404.2 15667.6 16654.8 817.0 853.6 861.6 913.4 966.4 8810.2 8917.9 9221.6 9314.4 9532.8 277492.0 282389.9 282751.0 284341.0 400620.0 62993.4 68058.3 68487.7 68512.0 69714.0 934.8 939.0 981.0 1005.0 1013.9 13916.2 14213.6 14269.3 14422.6 14566.3 30599.1 30719.2 31152.7 31591.5 32269.4 38800.6 39162.6 39941.8 40361.6 40446.6 6559.0 6708.5 6711.5 6716.4 6765.9 8578.7 9877.5 9907.0 10031.9 10202.2 8228.2 8399.8 8534.6 8561.6 8662.0 8251.8 8447.9 8616.4 8883.4 8933.6 8409.2 8483.4 8733.4 8739.7 8840.0 6440.6 6561.9 6584.5 6630.4 6685.6 8345.5 8514.7 8560.7 8603.0 8650.0 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | [#3] mootools.html Cycles:180 Average:4808.49 Median:5768.97 stddev:2441.14 (42.3%) stddev-sans-first:2434.18 04:16:08 INFO - PROCESS | 6535 | Values: 1354.0 1365.1 1473.4 1674.2 1703.6 5773.6 6644.4 6774.6 6892.9 6896.3 6658.1 6769.6 6787.0 6793.7 6806.2 2048.5 2068.2 2072.6 2073.3 2074.6 2034.8 2049.1 2061.8 2094.1 2095.9 2080.2 2082.9 2084.8 2097.6 2098.7 2076.7 2079.4 2081.0 2081.1 2086.6 2034.7 2038.1 2067.7 2069.6 2075.8 6860.1 6906.8 6918.7 6965.2 7083.8 4113.5 4178.7 4190.8 4199.4 4200.9 2655.8 2656.3 2708.8 2733.7 2735.5 862.3 867.9 873.3 897.8 901.5 9197.4 9234.7 9238.4 9256.7 9292.9 3815.9 3828.2 3894.4 3909.6 3916.3 7948.7 7971.8 7974.9 8004.8 8045.4 4206.7 4216.7 4231.8 4233.3 4243.3 3208.9 3726.8 3788.6 3846.6 3881.7 3285.9 3290.6 3327.5 3340.4 3351.4 1007.0 1010.2 1013.1 1014.9 1051.9 1112.3 1137.2 1145.2 1160.0 1165.5 6400.6 6408.9 6462.1 6493.3 6511.2 1014.7 1022.0 1036.3 1038.1 1048.8 5123.8 5196.2 5223.1 5237.7 5240.4 8136.6 8223.5 8225.3 8321.7 8349.5 8170.3 8279.3 8307.0 8320.1 8328.8 6528.4 6599.1 6664.9 6691.9 6696.7 6286.7 6303.1 6308.5 6326.0 6348.2 7238.1 7381.7 7415.0 7470.3 7477.8 7555.6 7624.8 7628.3 7635.1 7756.2 5140.4 5704.6 6006.4 6056.0 6073.7 6294.7 6368.8 6392.7 6398.3 6419.2 5571.3 5616.2 5681.1 5720.2 5758.9 5688.9 5781.5 5859.5 5868.1 5904.8 6096.2 6188.0 6264.5 6291.2 6297.1 5746.1 5766.2 5802.8 5806.8 5813.4 5745.8 5771.8 5779.9 5788.7 5794.7 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | [#4] prototype.html Cycles:180 Average:4774.50 Median:5537.82 stddev:1440.01 (26.0%) stddev-sans-first:1404.36 04:16:08 INFO - PROCESS | 6535 | Values: 301.1 308.1 309.2 309.7 312.0 5443.7 5515.3 5528.8 5659.5 5697.1 5479.6 5508.0 5546.8 5573.8 5576.7 4071.2 4168.6 4206.4 4209.5 4210.2 4142.6 4143.6 4144.9 4162.8 4165.9 4111.8 4121.8 4132.2 4145.0 4181.3 4149.1 4163.8 4170.7 4173.8 4179.6 3452.9 3608.5 3617.6 3618.3 3629.9 5144.0 5226.5 5239.5 5243.7 5254.3 4692.9 4697.2 4698.4 4763.2 4773.8 5747.3 5782.3 5782.7 5787.0 5795.8 3780.8 3856.0 3856.9 3861.8 3868.8 6023.3 6072.0 6087.7 6101.3 6111.7 4022.1 4041.6 4041.8 4048.0 4052.9 4090.4 4094.7 4099.1 4102.9 4103.9 4548.1 4749.2 4768.4 4772.7 4776.0 5630.7 5718.9 5785.8 5802.2 5805.6 1425.6 1453.9 1455.3 1456.3 1459.1 951.1 955.8 965.6 985.4 986.4 5032.3 5033.4 5044.7 5055.4 5067.4 6054.1 6072.1 6130.4 6135.6 6136.4 5015.2 5025.7 5036.3 5038.2 5058.5 3199.7 3238.4 3282.7 3283.3 3283.4 5701.0 5719.5 5725.4 5730.8 5737.3 6282.4 6300.5 6300.6 6301.0 6317.5 5571.5 5696.7 5705.3 5733.5 5746.3 6123.2 6123.8 6124.1 6126.6 6129.8 5883.2 5925.4 5951.0 5965.1 5965.8 5679.5 5702.1 5755.4 5760.1 5775.7 3926.1 4058.7 4065.1 4065.8 4113.6 5787.3 5909.4 5965.3 5985.7 5986.9 5759.0 5760.5 5775.3 5782.6 5789.8 5750.8 5752.9 5754.0 5758.9 5760.9 5454.9 5630.9 5655.4 5658.8 5658.9 5761.8 5779.5 5806.8 5830.4 5837.2 5671.0 5695.9 5696.9 5714.4 5715.8 04:16:08 INFO - PROCESS | 6535 | 04:16:08 INFO - PROCESS | 6535 | [#5] yui.html Cycles:180 Average:12569.01 Median:44455.44 stddev:14510.43 (32.6%) stddev-sans-first:14522.97 04:16:08 INFO - PROCESS | 6535 | Values: 530.6 577.1 643.9 646.0 653.7 11567.1 11689.8 11747.7 11779.8 11821.2 10803.5 13092.5 13288.3 13316.0 13327.7 738.5 749.6 750.3 752.7 767.5 771.1 771.7 771.8 771.8 775.7 805.4 809.7 812.9 815.5 816.3 794.9 799.4 800.5 802.9 803.5 590.4 590.8 593.6 596.2 597.4 12171.9 12237.3 12273.4 12274.9 12323.1 10553.4 10758.5 10931.2 10954.9 11000.2 8176.3 8360.3 8383.5 8443.4 8543.8 3216.3 3230.1 3239.3 3242.9 3251.0 43595.1 43654.6 43676.3 43807.6 44167.8 8360.2 8605.5 8646.7 8716.6 8752.0 16092.5 16152.3 16174.3 16177.4 16205.4 6058.6 6143.9 6210.3 6291.6 6416.5 3497.4 3605.4 3615.1 3616.4 3674.2 2764.3 2797.3 2804.7 2807.4 2809.1 467.6 469.2 470.3 474.5 475.2 471.1 546.8 565.2 566.2 578.7 7479.3 7770.3 7831.2 7866.4 7962.6 437.9 472.4 549.0 601.4 605.7 3449.8 3611.9 3917.8 3943.4 4050.3 48361.5 49452.0 49586.5 53455.7 54669.0 43714.0 44743.1 44835.8 44865.0 46246.3 41889.8 42282.0 42901.6 42928.6 43051.6 8256.7 8384.7 8492.9 8502.6 8567.4 32860.7 33458.0 34204.1 34856.7 35389.3 41275.8 41448.6 41927.4 42690.0 42711.6 10157.8 10229.4 10269.6 10297.7 10468.4 10501.7 10675.6 10748.4 10800.0 11281.8 9893.6 10548.9 10594.7 10838.9 11180.3 10052.9 10143.8 10282.9 10591.2 10612.7 10573.8 10689.0 10714.7 10916.6 11097.8 9070.4 9239.4 9344.6 9456.9 9611.5 8870.7 9193.9 9278.7 9450.8 9502.0 04:16:08 INFO - PROCESS | 6535 | -------- Summary: end -------- 04:16:08 INFO - PROCESS | 6535 | 04:16:09 INFO - TEST-INFO | 6535: exit 0 04:16:09 INFO - TEST-OK | dromaeo_css | took 1132252ms 04:16:09 INFO - TEST-START | kraken 04:16:09 INFO - operating with platform_type : linux_ 04:16:09 INFO - Initialising browser for kraken test... 04:16:09 INFO - TEST-INFO | started process 6772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkSKLt9/profile http://localhost:42012/getInfo.html) 04:16:18 INFO - PROCESS | 6772 | 04:16:18 INFO - PROCESS | 6772 | (process:6842): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:16:21 INFO - PROCESS | 6772 | __metrics Screen width/height:1600/1200 04:16:21 INFO - PROCESS | 6772 | colorDepth:24 04:16:21 INFO - PROCESS | 6772 | Browser inner width/height: 1024/697 04:16:21 INFO - PROCESS | 6772 | __metrics 04:16:22 ERROR - PROCESS | 6772 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2844: TypeError: this.tabs is undefined 04:16:24 INFO - TEST-INFO | 6772: exit 0 04:16:24 INFO - Browser initialized. 04:16:24 INFO - Running cycle 1/1 for kraken test... 04:16:24 INFO - TEST-INFO | started process 6893 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkSKLt9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:16:26 INFO - PROCESS | 6893 | 04:16:26 INFO - PROCESS | 6893 | (process:6952): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:16:36 INFO - PROCESS | 6893 | RSS: Main: 155287552 04:16:36 INFO - PROCESS | 6893 | 04:16:39 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42012/tests/kraken/driver.html?testName=audio-beat-detection) 04:16:39 INFO - PROCESS | 6893 | RSS: Main: 161566720 04:16:39 INFO - PROCESS | 6893 | 04:16:42 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42012/tests/kraken/driver.html?testName=audio-dft) 04:16:43 INFO - PROCESS | 6893 | RSS: Main: 164847616 04:16:43 INFO - PROCESS | 6893 | 04:16:46 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42012/tests/kraken/driver.html?testName=audio-fft) 04:16:46 INFO - PROCESS | 6893 | RSS: Main: 159858688 04:16:46 INFO - PROCESS | 6893 | 04:16:48 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42012/tests/kraken/driver.html?testName=audio-oscillator) 04:16:49 INFO - PROCESS | 6893 | RSS: Main: 157171712 04:16:49 INFO - PROCESS | 6893 | 04:16:51 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42012/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:16:52 INFO - PROCESS | 6893 | RSS: Main: 157220864 04:16:52 INFO - PROCESS | 6893 | 04:16:55 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42012/tests/kraken/driver.html?testName=imaging-darkroom) 04:16:56 INFO - PROCESS | 6893 | RSS: Main: 159436800 04:16:56 INFO - PROCESS | 6893 | 04:16:59 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42012/tests/kraken/driver.html?testName=imaging-desaturate) 04:17:00 INFO - PROCESS | 6893 | RSS: Main: 159879168 04:17:00 INFO - PROCESS | 6893 | 04:17:03 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42012/tests/kraken/driver.html?testName=json-parse-financial) 04:17:03 INFO - PROCESS | 6893 | RSS: Main: 157728768 04:17:03 INFO - PROCESS | 6893 | 04:17:05 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42012/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:17:06 INFO - PROCESS | 6893 | RSS: Main: 159895552 04:17:06 INFO - PROCESS | 6893 | 04:17:07 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:17:08 INFO - PROCESS | 6893 | RSS: Main: 158326784 04:17:08 INFO - PROCESS | 6893 | 04:17:10 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:17:11 INFO - PROCESS | 6893 | RSS: Main: 160395264 04:17:11 INFO - PROCESS | 6893 | 04:17:13 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:17:14 INFO - PROCESS | 6893 | RSS: Main: 161366016 04:17:14 INFO - PROCESS | 6893 | 04:17:18 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:17:18 INFO - PROCESS | 6893 | RSS: Main: 162172928 04:17:18 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | Cycle 1(1): loaded http://localhost:42012/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42012/tests/kraken/driver.html?testName=ai-astar) 04:17:20 INFO - PROCESS | 6893 | RSS: Main: 161447936 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | __start_tp_report 04:17:20 INFO - PROCESS | 6893 | _x_x_mozilla_page_load 04:17:20 INFO - PROCESS | 6893 | _x_x_mozilla_page_load_details 04:17:20 INFO - PROCESS | 6893 | |i|pagename|runs| 04:17:20 INFO - PROCESS | 6893 | |0;ai-astar;115;111;119;114;117;127;115;115;115;117 04:17:20 INFO - PROCESS | 6893 | |1;audio-beat-detection;163;131;154;173;135;173;154;142;167;131 04:17:20 INFO - PROCESS | 6893 | |2;audio-dft;164;163;163;161;162;164;174;163;163;163 04:17:20 INFO - PROCESS | 6893 | |3;audio-fft;74;74;76;73;148;76;74;74;75;89 04:17:20 INFO - PROCESS | 6893 | |4;audio-oscillator;99;98;140;104;99;98;144;99;98;134 04:17:20 INFO - PROCESS | 6893 | |5;imaging-gaussian-blur;120;117;117;117;116;116;115;116;116;116 04:17:20 INFO - PROCESS | 6893 | |6;imaging-darkroom;148;148;148;148;148;149;148;148;147;148 04:17:20 INFO - PROCESS | 6893 | |7;imaging-desaturate;99;97;98;100;101;98;96;96;97;98 04:17:20 INFO - PROCESS | 6893 | |8;json-parse-financial;67;67;68;67;67;69;67;73;65;65 04:17:20 INFO - PROCESS | 6893 | |9;json-stringify-tinderbox;71;69;70;71;69;70;71;71;71;71 04:17:20 INFO - PROCESS | 6893 | |10;stanford-crypto-aes;84;95;86;96;88;87;87;84;95;89 04:17:20 INFO - PROCESS | 6893 | |11;stanford-crypto-ccm;127;126;124;124;138;124;127;130;124;126 04:17:20 INFO - PROCESS | 6893 | |12;stanford-crypto-pbkdf2;145;153;150;152;165;156;153;153;152;138 04:17:20 INFO - PROCESS | 6893 | |13;stanford-crypto-sha256-iterative;75;74;72;71;74;73;71;73;71;71 04:17:20 INFO - PROCESS | 6893 | __end_tp_report 04:17:20 INFO - PROCESS | 6893 | __start_cc_report 04:17:20 INFO - PROCESS | 6893 | _x_x_mozilla_cycle_collect,823 04:17:20 INFO - PROCESS | 6893 | __end_cc_report 04:17:20 INFO - PROCESS | 6893 | __startTimestamp1455884240331__endTimestamp 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | ------- Summary: start ------- 04:17:20 INFO - PROCESS | 6893 | Number of tests: 14 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#0] ai-astar Cycles:10 Average:116.50 Median:116.00 stddev:4.25 (3.7%) stddev-sans-first:4.47 04:17:20 INFO - PROCESS | 6893 | Values: 115.0 111.0 119.0 114.0 117.0 127.0 115.0 115.0 115.0 117.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#1] audio-beat-detection Cycles:10 Average:152.30 Median:158.50 stddev:16.69 (10.5%) stddev-sans-first:17.24 04:17:20 INFO - PROCESS | 6893 | Values: 163.0 131.0 154.0 173.0 135.0 173.0 154.0 142.0 167.0 131.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#2] audio-dft Cycles:10 Average:164.00 Median:163.00 stddev:3.62 (2.2%) stddev-sans-first:3.84 04:17:20 INFO - PROCESS | 6893 | Values: 164.0 163.0 163.0 161.0 162.0 164.0 174.0 163.0 163.0 163.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#3] audio-fft Cycles:10 Average:83.30 Median:74.50 stddev:23.20 (31.1%) stddev-sans-first:24.37 04:17:20 INFO - PROCESS | 6893 | Values: 74.0 74.0 76.0 73.0 148.0 76.0 74.0 74.0 75.0 89.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#4] audio-oscillator Cycles:10 Average:111.30 Median:98.00 stddev:19.57 (20.0%) stddev-sans-first:20.24 04:17:20 INFO - PROCESS | 6893 | Values: 99.0 98.0 140.0 104.0 99.0 98.0 144.0 99.0 98.0 134.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:116.50 stddev:1.35 (1.2%) stddev-sans-first:0.67 04:17:20 INFO - PROCESS | 6893 | Values: 120.0 117.0 117.0 117.0 116.0 116.0 115.0 116.0 116.0 116.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#6] imaging-darkroom Cycles:10 Average:148.00 Median:148.00 stddev:0.47 (0.3%) stddev-sans-first:0.50 04:17:20 INFO - PROCESS | 6893 | Values: 148.0 148.0 148.0 148.0 148.0 149.0 148.0 148.0 147.0 148.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#7] imaging-desaturate Cycles:10 Average:98.00 Median:97.50 stddev:1.63 (1.7%) stddev-sans-first:1.69 04:17:20 INFO - PROCESS | 6893 | Values: 99.0 97.0 98.0 100.0 101.0 98.0 96.0 96.0 97.0 98.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#8] json-parse-financial Cycles:10 Average:67.50 Median:67.00 stddev:2.27 (3.4%) stddev-sans-first:2.40 04:17:20 INFO - PROCESS | 6893 | Values: 67.0 67.0 68.0 67.0 67.0 69.0 67.0 73.0 65.0 65.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:0.84 (1.2%) stddev-sans-first:0.87 04:17:20 INFO - PROCESS | 6893 | Values: 71.0 69.0 70.0 71.0 69.0 70.0 71.0 71.0 71.0 71.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:88.50 stddev:4.58 (5.2%) stddev-sans-first:4.47 04:17:20 INFO - PROCESS | 6893 | Values: 84.0 95.0 86.0 96.0 88.0 87.0 87.0 84.0 95.0 89.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#11] stanford-crypto-ccm Cycles:10 Average:127.00 Median:126.50 stddev:4.32 (3.4%) stddev-sans-first:4.58 04:17:20 INFO - PROCESS | 6893 | Values: 127.0 126.0 124.0 124.0 138.0 124.0 127.0 130.0 124.0 126.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.70 Median:153.00 stddev:6.96 (4.5%) stddev-sans-first:6.95 04:17:20 INFO - PROCESS | 6893 | Values: 145.0 153.0 150.0 152.0 165.0 156.0 153.0 153.0 152.0 138.0 04:17:20 INFO - PROCESS | 6893 | 04:17:20 INFO - PROCESS | 6893 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:73.00 stddev:1.51 (2.1%) stddev-sans-first:1.30 04:17:20 INFO - PROCESS | 6893 | Values: 75.0 74.0 72.0 71.0 74.0 73.0 71.0 73.0 71.0 71.0 04:17:20 INFO - PROCESS | 6893 | -------- Summary: end -------- 04:17:20 INFO - PROCESS | 6893 | 04:17:28 INFO - Browser shutdown timed out after 5 seconds, terminating process. 04:17:28 INFO - Terminating psutil.Process(pid=6893, name='firefox') 04:17:46 INFO - PROCESS | 6893 | [Child 6952] ###!!! ABORT: Aborting on channel error.: file /builds/slave/fx-team-l64-000000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 1824 04:17:46 INFO - PROCESS | 6893 | [Child 6952] ###!!! ABORT: Aborting on channel error.: file /builds/slave/fx-team-l64-000000000000000000/build/src/ipc/glue/MessageChannel.cpp, line 1824 04:18:13 INFO - TEST-UNEXPECTED-ERROR | kraken | psutil.TimeoutExpired timeout after 3 seconds (pid=6893, name='firefox') 04:18:13 ERROR - Traceback (most recent call last): 04:18:13 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 196, in run_tests 04:18:13 INFO - talos_results.add(mytest.runTest(browser_config, test)) 04:18:13 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 64, in runTest 04:18:13 INFO - return self._runTest(browser_config, test_config, setup) 04:18:13 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 167, in _runTest 04:18:13 INFO - if counter_management else None), 04:18:13 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 132, in run_browser 04:18:13 INFO - return_code = context.kill_process() 04:18:13 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 43, in kill_process 04:18:13 INFO - return self.process.wait(3) 04:18:13 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/__init__.py", line 1102, in wait 04:18:13 INFO - return self._proc.wait(timeout) 04:18:13 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 709, in wrapper 04:18:13 INFO - return fun(self, *args, **kwargs) 04:18:13 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 851, in wait 04:18:13 INFO - raise TimeoutExpired(timeout, self.pid, self._name) 04:18:13 INFO - TimeoutExpired: psutil.TimeoutExpired timeout after 3 seconds (pid=6893, name='firefox') 04:18:13 INFO - TEST-INFO took 124590ms 04:18:13 INFO - SUITE-END | took 1256s 04:18:14 ERROR - Return code: 2 04:18:14 WARNING - setting return code to 2 04:18:14 ERROR - # TBPL FAILURE # 04:18:14 INFO - Running post-action listener: _resource_record_post_action 04:18:14 INFO - Running post-run listener: _resource_record_post_run 04:18:15 INFO - Total resource usage - Wall time: 1274s; CPU: 14.0%; Read bytes: 4096; Write bytes: 452960256; Read time: 20; Write time: 5473840 04:18:15 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 2904064; Read time: 0; Write time: 1860 04:18:15 INFO - run-tests - Wall time: 1259s; CPU: 14.0%; Read bytes: 4096; Write bytes: 450056192; Read time: 20; Write time: 5471980 04:18:15 INFO - Running post-run listener: _upload_blobber_files 04:18:15 INFO - Blob upload gear active. 04:18:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:15 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 04:18:15 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', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:18:15 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 Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:18:15 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:18:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:18:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:16 INFO - (blobuploader) - INFO - Done attempting. 04:18:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:18:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:18:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:17 INFO - (blobuploader) - INFO - Done attempting. 04:18:17 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:17 INFO - Return code: 0 04:18:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58bee41b15c6cfaa003c86d023c7a3c6c6ebdd38bb42cc006510f4dddb0e6635f03975ae3d4021573aba35ee389703a24fa92cd8bbf3ffaadb60527de09912db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2fdcc3cba237116d96c14c3bfe9b3e08ac30368aa04583568f218fd20d9c29a67ffdaad45349a7d7781cac3a7489cebe5fefb73f6f62ae64be896b41b232a64b"} 04:18:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:17 INFO - Contents: 04:18:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58bee41b15c6cfaa003c86d023c7a3c6c6ebdd38bb42cc006510f4dddb0e6635f03975ae3d4021573aba35ee389703a24fa92cd8bbf3ffaadb60527de09912db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2fdcc3cba237116d96c14c3bfe9b3e08ac30368aa04583568f218fd20d9c29a67ffdaad45349a7d7781cac3a7489cebe5fefb73f6f62ae64be896b41b232a64b"} 04:18:17 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:17 INFO - Copying logs to upload dir... 04:18:17 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:17 INFO - Copying logs to upload dir... 04:18:17 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1335.766695 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 22 mins, 15 secs) (at 2016-02-19 04:18:17.312712) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-19 04:18:17.318504) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58bee41b15c6cfaa003c86d023c7a3c6c6ebdd38bb42cc006510f4dddb0e6635f03975ae3d4021573aba35ee389703a24fa92cd8bbf3ffaadb60527de09912db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2fdcc3cba237116d96c14c3bfe9b3e08ac30368aa04583568f218fd20d9c29a67ffdaad45349a7d7781cac3a7489cebe5fefb73f6f62ae64be896b41b232a64b"} build_url:https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010067 build_url: 'https://queue.taskcluster.net/v1/task/NL7JElkdSF-sr2p7KDsS3w/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/58bee41b15c6cfaa003c86d023c7a3c6c6ebdd38bb42cc006510f4dddb0e6635f03975ae3d4021573aba35ee389703a24fa92cd8bbf3ffaadb60527de09912db", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2fdcc3cba237116d96c14c3bfe9b3e08ac30368aa04583568f218fd20d9c29a67ffdaad45349a7d7781cac3a7489cebe5fefb73f6f62ae64be896b41b232a64b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-02-19 04:18:17.340901) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 04:18:17.341218) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1455882959.539239-79905867 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004259 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-02-19 04:18:17.424131) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-19 04:18:17.424447) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-02-19 04:18:17.424731) ========= ========= Total master_lag: 0.30 =========