builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-033 starttime: 1456836738.4 results: failure (2) buildid: 20160301035440 builduid: 5e85e88376174906985f69bb8a085699 revision: bb1dbd40af536693f138bcfd4111760aef17723f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.403155) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.403525) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.403810) ========= 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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006380 basedir: '/builds/slave/test' ========= master_lag: 0.05 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.456239) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.456502) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.496372) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.496672) ========= 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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.551734) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.552010) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.552322) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.552595) ========= 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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-03-01 04:52:18-- 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% 97.6M=0s 2016-03-01 04:52:18 (97.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.098296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.666086) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.666375) ========= 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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022425 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:18.716823) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-01 04:52:18.717120) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev bb1dbd40af536693f138bcfd4111760aef17723f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev bb1dbd40af536693f138bcfd4111760aef17723f --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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-03-01 04:52:18,939 truncating revision to first 12 chars 2016-03-01 04:52:18,939 Setting DEBUG logging. 2016-03-01 04:52:18,939 attempt 1/10 2016-03-01 04:52:18,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/bb1dbd40af53?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-03-01 04:52:19,797 unpacking tar archive at: mozilla-central-bb1dbd40af53/testing/mozharness/ program finished with exit code 0 elapsedTime=1.185385 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-03-01 04:52:19.918783) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:19.919507) ========= echo bb1dbd40af536693f138bcfd4111760aef17723f in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['echo', u'bb1dbd40af536693f138bcfd4111760aef17723f'] 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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False bb1dbd40af536693f138bcfd4111760aef17723f program finished with exit code 0 elapsedTime=0.004654 script_repo_revision: 'bb1dbd40af536693f138bcfd4111760aef17723f' ========= master_lag: 0.05 ========= ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:19.975040) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:19.975527) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-03-01 04:52:19.986446) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 22 mins, 33 secs) (at 2016-03-01 04:52:19.986772) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:52:20 INFO - MultiFileLogger online at 20160301 04:52:20 in /builds/slave/test 04:52:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:52:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:52:20 INFO - {'append_to_log': False, 04:52:20 INFO - 'base_work_dir': '/builds/slave/test', 04:52:20 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:52:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:52:20 INFO - 'branch': 'Firefox-Non-PGO', 04:52:20 INFO - 'buildbot_json_path': 'buildprops.json', 04:52:20 INFO - 'config_files': ('talos/linux_config.py',), 04:52:20 INFO - 'default_actions': ('clobber', 04:52:20 INFO - 'read-buildbot-config', 04:52:20 INFO - 'download-and-extract', 04:52:20 INFO - 'populate-webroot', 04:52:20 INFO - 'create-virtualenv', 04:52:20 INFO - 'install', 04:52:20 INFO - 'run-tests'), 04:52:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:52:20 INFO - 'download_minidump_stackwalk': True, 04:52:20 INFO - 'download_symbols': 'ondemand', 04:52:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:52:20 INFO - 'tooltool.py': '/tools/tooltool.py', 04:52:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:52:20 INFO - '/tools/misc-python/virtualenv.py')}, 04:52:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:52:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:52:20 INFO - 'installer_path': 'installer.exe', 04:52:20 INFO - 'log_level': 'info', 04:52:20 INFO - 'log_name': 'talos', 04:52:20 INFO - 'log_to_console': True, 04:52:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:52:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:52:20 INFO - 'opt_config_files': (), 04:52:20 INFO - 'pip_index': False, 04:52:20 INFO - 'sps_profile': False, 04:52:20 INFO - 'sps_profile_interval': 0, 04:52:20 INFO - 'suite': 'dromaeojs-e10s', 04:52:20 INFO - 'system_bits': '32', 04:52:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:52:20 INFO - 'title': 'talos-linux64-ix-033', 04:52:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:52:20 INFO - 'use_talos_json': True, 04:52:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:52:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:52:20 INFO - 'work_dir': 'build'} 04:52:20 INFO - ##### 04:52:20 INFO - ##### Running clobber step. 04:52:20 INFO - ##### 04:52:20 INFO - Running pre-action listener: _resource_record_pre_action 04:52:20 INFO - Running main action method: clobber 04:52:20 INFO - rmtree: /builds/slave/test/build 04:52:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:52:20 INFO - Running post-action listener: _resource_record_post_action 04:52:20 INFO - ##### 04:52:20 INFO - ##### Running read-buildbot-config step. 04:52:20 INFO - ##### 04:52:20 INFO - Running pre-action listener: _resource_record_pre_action 04:52:20 INFO - Running main action method: read_buildbot_config 04:52:20 INFO - Using buildbot properties: 04:52:20 INFO - { 04:52:20 INFO - "project": "", 04:52:20 INFO - "product": "firefox", 04:52:20 INFO - "script_repo_revision": "production", 04:52:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:52:20 INFO - "repository": "", 04:52:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:52:20 INFO - "buildid": "20160301035440", 04:52:20 INFO - "pgo_build": "False", 04:52:20 INFO - "basedir": "/builds/slave/test", 04:52:20 INFO - "buildnumber": 255, 04:52:20 INFO - "slavename": "talos-linux64-ix-033", 04:52:20 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 04:52:20 INFO - "platform": "ubuntu64_hw", 04:52:20 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:52:20 INFO - "branch": "mozilla-central", 04:52:20 INFO - "repo_path": "mozilla-central", 04:52:20 INFO - "revision": "bb1dbd40af536693f138bcfd4111760aef17723f", 04:52:20 INFO - "stage_platform": "linux64", 04:52:20 INFO - "builduid": "5e85e88376174906985f69bb8a085699", 04:52:20 INFO - "slavebuilddir": "test" 04:52:20 INFO - } 04:52:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2. 04:52:20 INFO - Running post-action listener: _resource_record_post_action 04:52:20 INFO - ##### 04:52:20 INFO - ##### Running download-and-extract step. 04:52:20 INFO - ##### 04:52:20 INFO - Running pre-action listener: _resource_record_pre_action 04:52:20 INFO - Running main action method: download_and_extract 04:52:20 INFO - mkdir: /builds/slave/test/build/tests 04:52: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')]} 04:52:20 INFO - https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:52:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:52:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:52:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:52:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:52:25 INFO - Downloaded 1448 bytes. 04:52:25 INFO - Reading from file /builds/slave/test/build/firefox-47.0a1.en-US.linux-x86_64.test_packages.json 04:52:25 INFO - Using the following test package requirements: 04:52:25 INFO - {u'common': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 04:52:25 INFO - u'cppunittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:52:25 INFO - u'gtest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:52:25 INFO - u'jittest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'jsshell-linux-x86_64.zip'], 04:52:25 INFO - u'mochitest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:52:25 INFO - u'mozbase': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 04:52:25 INFO - u'reftest': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:52:25 INFO - u'talos': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:52:25 INFO - u'web-platform': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:52:25 INFO - u'webapprt': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'], 04:52:25 INFO - u'xpcshell': [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip', 04:52:25 INFO - u'firefox-47.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:52:25 INFO - Downloading packages: [u'firefox-47.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:25 INFO - https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:52:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:52:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:52:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:27 INFO - Downloaded 22077416 bytes. 04:52:27 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'] 04:52:27 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 04:52:28 INFO - Return code: 0 04:52:28 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 04:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:28 INFO - https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.common.tests.zip 04:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:30 INFO - Downloaded 22077416 bytes. 04:52:30 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'] 04:52:30 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 04:52:35 INFO - Return code: 0 04:52:35 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')]} 04:52:35 INFO - https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:52:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 04:52:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.talos.tests.zip 04:52:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:36 INFO - Downloaded 10713413 bytes. 04:52:36 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'] 04:52:36 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 04:52:37 INFO - Return code: 0 04:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:37 INFO - https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:52:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:52:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:52:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:52:41 INFO - Downloaded 54836349 bytes. 04:52:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:52:41 INFO - mkdir: /builds/slave/test/properties 04:52:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:52:41 INFO - Writing to file /builds/slave/test/properties/build_url 04:52:41 INFO - Contents: 04:52:41 INFO - build_url:https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 04:52:41 INFO - Running post-action listener: _resource_record_post_action 04:52:41 INFO - Running post-action listener: set_extra_try_arguments 04:52:41 INFO - ##### 04:52:41 INFO - ##### Running populate-webroot step. 04:52:41 INFO - ##### 04:52:41 INFO - Running pre-action listener: _resource_record_pre_action 04:52:41 INFO - Running main action method: populate_webroot 04:52:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:52:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:52:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:52:41 INFO - '--tppagecycles', 04:52:41 INFO - '7'], 04:52:41 INFO - 'tests': ['tsvgm']}}, 04:52:41 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 04:52:41 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:52:41 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 04:52:41 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:52:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:52:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'tests': ['damp', 'tps']}, 04:52:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['damp', 'tps']}, 04:52:41 INFO - 'g3': {'tests': ['dromaeo_dom']}, 04:52:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:52:41 INFO - 'other': {'tests': ['a11yr', 04:52:41 INFO - 'ts_paint', 04:52:41 INFO - 'tpaint', 04:52:41 INFO - 'sessionrestore', 04:52:41 INFO - 'sessionrestore_no_auto_restore']}, 04:52:41 INFO - 'other-e10s': {'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['a11yr', 04:52:41 INFO - 'ts_paint', 04:52:41 INFO - 'tpaint', 04:52:41 INFO - 'sessionrestore', 04:52:41 INFO - 'sessionrestore_no_auto_restore']}, 04:52:41 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['a11yr', 04:52:41 INFO - 'ts_paint', 04:52:41 INFO - 'tpaint', 04:52:41 INFO - 'sessionrestore', 04:52:41 INFO - 'sessionrestore_no_auto_restore']}, 04:52:41 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['a11yr', 04:52:41 INFO - 'ts_paint', 04:52:41 INFO - 'tpaint', 04:52:41 INFO - 'sessionrestore', 04:52:41 INFO - 'sessionrestore_no_auto_restore']}, 04:52:41 INFO - 'other_l64': {'tests': ['a11yr', 04:52:41 INFO - 'ts_paint', 04:52:41 INFO - 'tpaint', 04:52:41 INFO - 'sessionrestore', 04:52:41 INFO - 'sessionrestore_no_auto_restore']}, 04:52:41 INFO - 'other_nol64': {'tests': ['a11yr', 04:52:41 INFO - 'ts_paint', 04:52:41 INFO - 'tpaint', 04:52:41 INFO - 'sessionrestore', 04:52:41 INFO - 'sessionrestore_no_auto_restore']}, 04:52:41 INFO - 'svgr': {'tests': ['tsvgx', 04:52:41 INFO - 'tsvgr_opacity', 04:52:41 INFO - 'tart', 04:52:41 INFO - 'tscrollx', 04:52:41 INFO - 'cart']}, 04:52:41 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['tsvgx', 04:52:41 INFO - 'tsvgr_opacity', 04:52:41 INFO - 'tart', 04:52:41 INFO - 'tscrollx', 04:52:41 INFO - 'cart']}, 04:52:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'tests': ['tp5o']}, 04:52:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'talos_options': ['--e10s'], 04:52:41 INFO - 'tests': ['tp5o']}, 04:52:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'talos_options': ['--xperf_path', 04:52:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:41 INFO - 'tests': ['tp5n']}, 04:52:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:52:41 INFO - 'talos_options': ['--e10s', 04:52:41 INFO - '--xperf_path', 04:52:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:52:41 INFO - 'tests': ['tp5n']}}, 04:52:41 INFO - 'talos.zip': {'path': '', 04:52:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:52:41 INFO - Running post-action listener: _resource_record_post_action 04:52:41 INFO - ##### 04:52:41 INFO - ##### Running create-virtualenv step. 04:52:41 INFO - ##### 04:52:41 INFO - Running pre-action listener: _resource_record_pre_action 04:52:41 INFO - Running main action method: create_virtualenv 04:52:41 INFO - Creating virtualenv /builds/slave/test/build/venv 04:52:41 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 04:52:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:52:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:52:41 INFO - Using real prefix '/usr' 04:52:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:52:42 INFO - Installing distribute.............................................................................................................................................................................................done. 04:52:44 INFO - Installing pip.................done. 04:52:44 INFO - Return code: 0 04:52:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:52:44 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')]} 04:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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')]} 04:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:44 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 04:52:44 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 04:52:44 INFO - Using env: {'DISPLAY': ':0', 04:52:44 INFO - 'HOME': '/home/cltbld', 04:52:44 INFO - 'LANG': 'en_US.UTF-8', 04:52:44 INFO - 'LANGUAGE': 'en_US:en', 04:52:44 INFO - 'LOGNAME': 'cltbld', 04:52:44 INFO - 'MAIL': '/var/mail/cltbld', 04:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:44 INFO - 'MOZ_NO_REMOTE': '1', 04:52:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:44 INFO - 'NO_EM_RESTART': '1', 04:52:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:44 INFO - 'PWD': '/builds/slave/test', 04:52:44 INFO - 'SHELL': '/bin/bash', 04:52:44 INFO - 'SHLVL': '1', 04:52:44 INFO - 'TERM': 'linux', 04:52:44 INFO - 'TMOUT': '86400', 04:52:44 INFO - 'USER': 'cltbld', 04:52:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:44 INFO - '_': '/tools/buildbot/bin/python'} 04:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:44 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 04:52:44 INFO - Cleaning up... 04:52:44 INFO - Return code: 0 04:52:44 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:52:44 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')]} 04:52:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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')]} 04:52:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:44 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:44 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 04:52:44 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 04:52:44 INFO - Using env: {'DISPLAY': ':0', 04:52:44 INFO - 'HOME': '/home/cltbld', 04:52:44 INFO - 'LANG': 'en_US.UTF-8', 04:52:44 INFO - 'LANGUAGE': 'en_US:en', 04:52:44 INFO - 'LOGNAME': 'cltbld', 04:52:44 INFO - 'MAIL': '/var/mail/cltbld', 04:52:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:44 INFO - 'MOZ_NO_REMOTE': '1', 04:52:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:44 INFO - 'NO_EM_RESTART': '1', 04:52:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:44 INFO - 'PWD': '/builds/slave/test', 04:52:44 INFO - 'SHELL': '/bin/bash', 04:52:44 INFO - 'SHLVL': '1', 04:52:44 INFO - 'TERM': 'linux', 04:52:44 INFO - 'TMOUT': '86400', 04:52:44 INFO - 'USER': 'cltbld', 04:52:44 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:44 INFO - '_': '/tools/buildbot/bin/python'} 04:52:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:44 INFO - Downloading/unpacking psutil>=0.7.1 04:52:44 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 04:52:44 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 04:52:44 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 04:52:44 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 04:52:44 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 04:52:44 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 04:52:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:52:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:46 INFO - Installing collected packages: psutil 04:52:46 INFO - Running setup.py install for psutil 04:52:46 INFO - building 'psutil._psutil_linux' extension 04:52:46 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 04:52:46 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 04:52:46 INFO - building 'psutil._psutil_posix' extension 04:52:46 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 04:52:47 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 04:52:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:52:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:52:47 INFO - Successfully installed psutil 04:52:47 INFO - Cleaning up... 04:52:47 INFO - Return code: 0 04:52:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:52:47 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')]} 04:52:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:47 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')]} 04:52:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:47 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:47 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 04:52:47 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 04:52:47 INFO - Using env: {'DISPLAY': ':0', 04:52:47 INFO - 'HOME': '/home/cltbld', 04:52:47 INFO - 'LANG': 'en_US.UTF-8', 04:52:47 INFO - 'LANGUAGE': 'en_US:en', 04:52:47 INFO - 'LOGNAME': 'cltbld', 04:52:47 INFO - 'MAIL': '/var/mail/cltbld', 04:52:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:47 INFO - 'MOZ_NO_REMOTE': '1', 04:52:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:47 INFO - 'NO_EM_RESTART': '1', 04:52:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:47 INFO - 'PWD': '/builds/slave/test', 04:52:47 INFO - 'SHELL': '/bin/bash', 04:52:47 INFO - 'SHLVL': '1', 04:52:47 INFO - 'TERM': 'linux', 04:52:47 INFO - 'TMOUT': '86400', 04:52:47 INFO - 'USER': 'cltbld', 04:52:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:47 INFO - '_': '/tools/buildbot/bin/python'} 04:52:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:47 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:52:47 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 04:52:47 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 04:52:47 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 04:52:47 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 04:52:47 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 04:52:47 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 04:52:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:52:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:52:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:52:49 INFO - Installing collected packages: mozsystemmonitor 04:52:49 INFO - Running setup.py install for mozsystemmonitor 04:52:49 INFO - Successfully installed mozsystemmonitor 04:52:49 INFO - Cleaning up... 04:52:49 INFO - Return code: 0 04:52:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:52: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')]} 04:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52: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')]} 04:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:49 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:49 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 04:52:49 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 04:52:49 INFO - Using env: {'DISPLAY': ':0', 04:52:49 INFO - 'HOME': '/home/cltbld', 04:52:49 INFO - 'LANG': 'en_US.UTF-8', 04:52:49 INFO - 'LANGUAGE': 'en_US:en', 04:52:49 INFO - 'LOGNAME': 'cltbld', 04:52:49 INFO - 'MAIL': '/var/mail/cltbld', 04:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:49 INFO - 'MOZ_NO_REMOTE': '1', 04:52:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:49 INFO - 'NO_EM_RESTART': '1', 04:52:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:49 INFO - 'PWD': '/builds/slave/test', 04:52:49 INFO - 'SHELL': '/bin/bash', 04:52:49 INFO - 'SHLVL': '1', 04:52:49 INFO - 'TERM': 'linux', 04:52:49 INFO - 'TMOUT': '86400', 04:52:49 INFO - 'USER': 'cltbld', 04:52:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:49 INFO - '_': '/tools/buildbot/bin/python'} 04:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:49 INFO - Downloading/unpacking blobuploader==1.2.4 04:52:49 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 04:52:49 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 04:52:49 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 04:52:49 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 04:52:49 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 04:52:49 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 04:52:51 INFO - Downloading blobuploader-1.2.4.tar.gz 04:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:52:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:52: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 04:52: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 04:52: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 04:52: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 04:52: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 04:52: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 04:52:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:52:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:52:52 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 04:52:52 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 04:52:52 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 04:52:52 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 04:52:52 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 04:52:52 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 04:52:52 INFO - Downloading docopt-0.6.1.tar.gz 04:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:52:52 INFO - Installing collected packages: blobuploader, requests, docopt 04:52:52 INFO - Running setup.py install for blobuploader 04:52:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:52:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:52:52 INFO - Running setup.py install for requests 04:52:52 INFO - Running setup.py install for docopt 04:52:52 INFO - Successfully installed blobuploader requests docopt 04:52:52 INFO - Cleaning up... 04:52:52 INFO - Return code: 0 04:52:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:52 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:52 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 04:52:52 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 04:52:52 INFO - Using env: {'DISPLAY': ':0', 04:52:52 INFO - 'HOME': '/home/cltbld', 04:52:52 INFO - 'LANG': 'en_US.UTF-8', 04:52:52 INFO - 'LANGUAGE': 'en_US:en', 04:52:52 INFO - 'LOGNAME': 'cltbld', 04:52:52 INFO - 'MAIL': '/var/mail/cltbld', 04:52:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:52 INFO - 'MOZ_NO_REMOTE': '1', 04:52:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:52 INFO - 'NO_EM_RESTART': '1', 04:52:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:52 INFO - 'PWD': '/builds/slave/test', 04:52:52 INFO - 'SHELL': '/bin/bash', 04:52:52 INFO - 'SHLVL': '1', 04:52:52 INFO - 'TERM': 'linux', 04:52:52 INFO - 'TMOUT': '86400', 04:52:52 INFO - 'USER': 'cltbld', 04:52:52 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:52 INFO - '_': '/tools/buildbot/bin/python'} 04:52:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:53 INFO - Running setup.py (path:/tmp/pip-KdPRAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:53 INFO - Running setup.py (path:/tmp/pip-jaY3Yo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:53 INFO - Running setup.py (path:/tmp/pip-10gro_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:53 INFO - Running setup.py (path:/tmp/pip-DwREhP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:53 INFO - Running setup.py (path:/tmp/pip-7tzIOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:53 INFO - Running setup.py (path:/tmp/pip-zQ6vxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:53 INFO - Running setup.py (path:/tmp/pip-6jy9rp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:53 INFO - Running setup.py (path:/tmp/pip-Azjj_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:53 INFO - Running setup.py (path:/tmp/pip-SAMsq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:53 INFO - Running setup.py (path:/tmp/pip-W_7oyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:53 INFO - Running setup.py (path:/tmp/pip-FmbuNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:53 INFO - Running setup.py (path:/tmp/pip-7ZkhEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:53 INFO - Running setup.py (path:/tmp/pip-8t5TB2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:54 INFO - Running setup.py (path:/tmp/pip-XLUMi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:54 INFO - Running setup.py (path:/tmp/pip-wOr4C5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:54 INFO - Running setup.py (path:/tmp/pip-twMfWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:54 INFO - Running setup.py (path:/tmp/pip-UpZG4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:52:54 INFO - Running setup.py install for manifestparser 04:52:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Running setup.py install for mozcrash 04:52:54 INFO - Running setup.py install for mozdebug 04:52:54 INFO - Running setup.py install for mozdevice 04:52:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Running setup.py install for mozfile 04:52:54 INFO - Running setup.py install for mozhttpd 04:52:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:52:54 INFO - Running setup.py install for mozinfo 04:52:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozInstall 04:52:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozleak 04:52:55 INFO - Running setup.py install for mozlog 04:52:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for moznetwork 04:52:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozprocess 04:52:55 INFO - Running setup.py install for mozprofile 04:52:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozrunner 04:52:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:52:55 INFO - Running setup.py install for mozscreenshot 04:52:55 INFO - Running setup.py install for moztest 04:52:56 INFO - Running setup.py install for mozversion 04:52:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:52:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:52:56 INFO - Cleaning up... 04:52:56 INFO - Return code: 0 04:52:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52: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')]} 04:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52: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')]} 04:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:56 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:56 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 04:52:56 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 04:52:56 INFO - Using env: {'DISPLAY': ':0', 04:52:56 INFO - 'HOME': '/home/cltbld', 04:52:56 INFO - 'LANG': 'en_US.UTF-8', 04:52:56 INFO - 'LANGUAGE': 'en_US:en', 04:52:56 INFO - 'LOGNAME': 'cltbld', 04:52:56 INFO - 'MAIL': '/var/mail/cltbld', 04:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:56 INFO - 'MOZ_NO_REMOTE': '1', 04:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:56 INFO - 'NO_EM_RESTART': '1', 04:52:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:56 INFO - 'PWD': '/builds/slave/test', 04:52:56 INFO - 'SHELL': '/bin/bash', 04:52:56 INFO - 'SHLVL': '1', 04:52:56 INFO - 'TERM': 'linux', 04:52:56 INFO - 'TMOUT': '86400', 04:52:56 INFO - 'USER': 'cltbld', 04:52:56 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:56 INFO - '_': '/tools/buildbot/bin/python'} 04:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:52:56 INFO - Running setup.py (path:/tmp/pip-LUjj3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:52:56 INFO - Running setup.py (path:/tmp/pip-IewWQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:52:56 INFO - Running setup.py (path:/tmp/pip-f8XBHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:52:56 INFO - Running setup.py (path:/tmp/pip-QBE8b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:52:56 INFO - Running setup.py (path:/tmp/pip-dkNM9y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:52:56 INFO - Running setup.py (path:/tmp/pip-9s5ndh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:52:56 INFO - Running setup.py (path:/tmp/pip-NLWeUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:52:56 INFO - Running setup.py (path:/tmp/pip-xWCMMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:52:56 INFO - Running setup.py (path:/tmp/pip-mN8aKg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:52:56 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)) 04:52:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:52:56 INFO - Running setup.py (path:/tmp/pip-RFjrGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:52:57 INFO - Running setup.py (path:/tmp/pip-o_cmvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:52:57 INFO - Running setup.py (path:/tmp/pip-mJXxhX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:52:57 INFO - Running setup.py (path:/tmp/pip-fwkual-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:52:57 INFO - Running setup.py (path:/tmp/pip-EDerGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:57 INFO - Running setup.py (path:/tmp/pip-FrFfK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:52:57 INFO - Running setup.py (path:/tmp/pip-MOHniH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:52:57 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)) 04:52:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:52:57 INFO - Running setup.py (path:/tmp/pip-JddO1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:52:57 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)) 04:52:57 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)) 04:52:57 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)) 04:52:57 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)) 04:52:57 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)) 04:52:57 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)) 04:52:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:52:57 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 04:52:57 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 04:52:57 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 04:52:57 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 04:52:57 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 04:52:57 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 04:52:59 INFO - Downloading blessings-1.6.tar.gz 04:52:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:52:59 INFO - Installing collected packages: blessings 04:52:59 INFO - Running setup.py install for blessings 04:52:59 INFO - Successfully installed blessings 04:52:59 INFO - Cleaning up... 04:52:59 INFO - Return code: 0 04:52:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:52:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:52:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:52:59 INFO - Reading from file tmpfile_stdout 04:52:59 INFO - Current package versions: 04:52:59 INFO - argparse == 1.2.1 04:52:59 INFO - blessings == 1.6 04:52:59 INFO - blobuploader == 1.2.4 04:52:59 INFO - docopt == 0.6.1 04:52:59 INFO - manifestparser == 1.1 04:52:59 INFO - mozInstall == 1.12 04:52:59 INFO - mozcrash == 0.16 04:52:59 INFO - mozdebug == 0.1 04:52:59 INFO - mozdevice == 0.48 04:52:59 INFO - mozfile == 1.2 04:52:59 INFO - mozhttpd == 0.7 04:52:59 INFO - mozinfo == 0.9 04:52:59 INFO - mozleak == 0.1 04:52:59 INFO - mozlog == 3.1 04:52:59 INFO - moznetwork == 0.27 04:52:59 INFO - mozprocess == 0.22 04:52:59 INFO - mozprofile == 0.28 04:52:59 INFO - mozrunner == 6.11 04:52:59 INFO - mozscreenshot == 0.1 04:52:59 INFO - mozsystemmonitor == 0.0 04:52:59 INFO - moztest == 0.7 04:52:59 INFO - mozversion == 1.4 04:52:59 INFO - psutil == 3.1.1 04:52:59 INFO - requests == 1.2.3 04:52:59 INFO - wsgiref == 0.1.2 04:52:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:52:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:52:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:52:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:52:59 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:52:59 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 04:52:59 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 04:52:59 INFO - Using env: {'DISPLAY': ':0', 04:52:59 INFO - 'HOME': '/home/cltbld', 04:52:59 INFO - 'LANG': 'en_US.UTF-8', 04:52:59 INFO - 'LANGUAGE': 'en_US:en', 04:52:59 INFO - 'LOGNAME': 'cltbld', 04:52:59 INFO - 'MAIL': '/var/mail/cltbld', 04:52:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:52:59 INFO - 'MOZ_NO_REMOTE': '1', 04:52:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:52:59 INFO - 'NO_EM_RESTART': '1', 04:52:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:52:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:52:59 INFO - 'PWD': '/builds/slave/test', 04:52:59 INFO - 'SHELL': '/bin/bash', 04:52:59 INFO - 'SHLVL': '1', 04:52:59 INFO - 'TERM': 'linux', 04:52:59 INFO - 'TMOUT': '86400', 04:52:59 INFO - 'USER': 'cltbld', 04:52:59 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:52:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:52:59 INFO - '_': '/tools/buildbot/bin/python'} 04:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 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)) 04:53:00 INFO - Cleaning up... 04:53:00 INFO - Return code: 0 04:53:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:53:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:53:00 INFO - Using env: {'DISPLAY': ':0', 04:53:00 INFO - 'HOME': '/home/cltbld', 04:53:00 INFO - 'LANG': 'en_US.UTF-8', 04:53:00 INFO - 'LANGUAGE': 'en_US:en', 04:53:00 INFO - 'LOGNAME': 'cltbld', 04:53:00 INFO - 'MAIL': '/var/mail/cltbld', 04:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:00 INFO - 'MOZ_NO_REMOTE': '1', 04:53:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:00 INFO - 'NO_EM_RESTART': '1', 04:53:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:00 INFO - 'PWD': '/builds/slave/test', 04:53:00 INFO - 'SHELL': '/bin/bash', 04:53:00 INFO - 'SHLVL': '1', 04:53:00 INFO - 'TERM': 'linux', 04:53:00 INFO - 'TMOUT': '86400', 04:53:00 INFO - 'USER': 'cltbld', 04:53:00 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:00 INFO - '_': '/tools/buildbot/bin/python'} 04:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:00 INFO - Downloading/unpacking jsonschema 04:53:00 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 04:53:00 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 04:53:00 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 04:53:00 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 04:53:00 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 04:53:00 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 04:53:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:53:02 INFO - Downloading/unpacking functools32 (from jsonschema) 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 INFO - Downloading functools32-3.2.3-2.tar.gz 04:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:53:02 INFO - warning: no files found matching '*.txt' 04:53:02 INFO - no previously-included directories found matching 'build' 04:53:02 INFO - no previously-included directories found matching 'dist' 04:53:02 INFO - no previously-included directories found matching '.git*' 04:53:02 INFO - Installing collected packages: jsonschema, functools32 04:53:02 INFO - Running setup.py install for functools32 04:53:02 INFO - warning: no files found matching '*.txt' 04:53:02 INFO - no previously-included directories found matching 'build' 04:53:02 INFO - no previously-included directories found matching 'dist' 04:53:02 INFO - no previously-included directories found matching '.git*' 04:53:02 INFO - Successfully installed jsonschema functools32 04:53:02 INFO - Cleaning up... 04:53:02 INFO - Return code: 0 04:53:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:53: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')]} 04:53:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:53:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53: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')]} 04:53:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:53:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:53:02 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 0x7f9607c7ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf938a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10603b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, '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-1456835893.453836-1902558187', '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 04:53:02 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 04:53:02 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 04:53:02 INFO - Using env: {'DISPLAY': ':0', 04:53:02 INFO - 'HOME': '/home/cltbld', 04:53:02 INFO - 'LANG': 'en_US.UTF-8', 04:53:02 INFO - 'LANGUAGE': 'en_US:en', 04:53:02 INFO - 'LOGNAME': 'cltbld', 04:53:02 INFO - 'MAIL': '/var/mail/cltbld', 04:53:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:02 INFO - 'MOZ_NO_REMOTE': '1', 04:53:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:02 INFO - 'NO_EM_RESTART': '1', 04:53:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:02 INFO - 'PWD': '/builds/slave/test', 04:53:02 INFO - 'SHELL': '/bin/bash', 04:53:02 INFO - 'SHLVL': '1', 04:53:02 INFO - 'TERM': 'linux', 04:53:02 INFO - 'TMOUT': '86400', 04:53:02 INFO - 'USER': 'cltbld', 04:53:02 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:53:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:02 INFO - '_': '/tools/buildbot/bin/python'} 04:53:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:53:02 INFO - Downloading/unpacking flake8 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 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 04:53:02 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 04:53:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:53:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:53:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:04 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 04:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:05 INFO - no previously-included directories found matching 'docs/_build' 04:53:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:53:05 INFO - Running setup.py install for pep8 04:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:53:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:53:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:53:05 INFO - no previously-included directories found matching 'docs/_build' 04:53:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:53:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:53:05 INFO - Cleaning up... 04:53:05 INFO - Return code: 0 04:53:05 INFO - Running post-action listener: _resource_record_post_action 04:53:05 INFO - Running post-action listener: _start_resource_monitoring 04:53:05 INFO - Starting resource monitoring. 04:53:05 INFO - ##### 04:53:05 INFO - ##### Running install step. 04:53:05 INFO - ##### 04:53:05 INFO - Running pre-action listener: _resource_record_pre_action 04:53:05 INFO - Running main action method: install 04:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:53:05 INFO - Reading from file tmpfile_stdout 04:53:05 INFO - Detecting whether we're running mozinstall >=1.0... 04:53:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:53:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:53:05 INFO - Reading from file tmpfile_stdout 04:53:05 INFO - Output received: 04:53:05 INFO - Usage: mozinstall [options] installer 04:53:05 INFO - Options: 04:53:05 INFO - -h, --help show this help message and exit 04:53:05 INFO - -d DEST, --destination=DEST 04:53:05 INFO - Directory to install application into. [default: 04:53:05 INFO - "/builds/slave/test"] 04:53:05 INFO - --app=APP Application being installed. [default: firefox] 04:53:05 INFO - mkdir: /builds/slave/test/build/application 04:53:05 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'] 04:53:05 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 04:53:22 INFO - Reading from file tmpfile_stdout 04:53:22 INFO - Output received: 04:53:22 INFO - /builds/slave/test/build/application/firefox/firefox 04:53:27 INFO - Running post-action listener: _resource_record_post_action 04:53:34 INFO - ##### 04:53:34 INFO - ##### Running run-tests step. 04:53:34 INFO - ##### 04:53:34 INFO - Running pre-action listener: _resource_record_pre_action 04:53:34 INFO - Running main action method: run_tests 04:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:53:34 INFO - Python 2.7.3 04:53:34 INFO - Return code: 0 04:53:34 INFO - grabbing minidump binary from tooltool 04:53:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:53:34 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 0x10ef100>, '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 0x10f0000>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0490>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:53:34 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 04:53:34 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 04:53:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:53:34 INFO - Return code: 0 04:53:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:53:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:53:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:53:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:53:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:53:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:53:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:53:35 INFO - Return code: 0 04:53:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:53:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:53:35 INFO - Using env: {'DISPLAY': ':0', 04:53:35 INFO - 'HOME': '/home/cltbld', 04:53:35 INFO - 'LANG': 'en_US.UTF-8', 04:53:35 INFO - 'LANGUAGE': 'en_US:en', 04:53:35 INFO - 'LOGNAME': 'cltbld', 04:53:35 INFO - 'MAIL': '/var/mail/cltbld', 04:53:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:53:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:53:35 INFO - 'MOZ_NO_REMOTE': '1', 04:53:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:53:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:53:35 INFO - 'NO_EM_RESTART': '1', 04:53:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:53:35 INFO - 'PWD': '/builds/slave/test', 04:53:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:53:35 INFO - 'SHELL': '/bin/bash', 04:53:35 INFO - 'SHLVL': '1', 04:53:35 INFO - 'TERM': 'linux', 04:53:35 INFO - 'TMOUT': '86400', 04:53:35 INFO - 'USER': 'cltbld', 04:53:35 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1456835893.453836-1902558187', 04:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:53:35 INFO - '_': '/tools/buildbot/bin/python'} 04:53:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/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 04:53:36 INFO - mozversion application_buildid: 20160301035440 04:53:36 INFO - mozversion application_changeset: bb1dbd40af536693f138bcfd4111760aef17723f 04:53:36 INFO - mozversion application_display_name: Nightly 04:53:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:53:36 INFO - mozversion application_name: Firefox 04:53:36 INFO - mozversion application_remotingname: firefox 04:53:36 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:53:36 INFO - mozversion application_vendor: Mozilla 04:53:36 INFO - mozversion application_version: 47.0a1 04:53:36 INFO - mozversion platform_buildid: 20160301035440 04:53:36 INFO - mozversion platform_changeset: bb1dbd40af536693f138bcfd4111760aef17723f 04:53:36 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:53:36 INFO - mozversion platform_version: 47.0a1 04:53:36 INFO - using testdate: 1456836816 04:53:36 INFO - actual date: 1456836816 04:53:36 INFO - starting webserver on 'localhost:42765' 04:53:36 INFO - SUITE-START | Running 2 tests 04:53:36 INFO - TEST-START | dromaeo_css 04:53:36 INFO - operating with platform_type : linux_ 04:53:36 INFO - Initialising browser for dromaeo_css test... 04:53:36 INFO - TEST-INFO | started process 12110 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8wDqW9/profile http://localhost:42765/getInfo.html) 04:53:43 INFO - PROCESS | 12110 | 04:53:43 INFO - PROCESS | 12110 | (process:12193): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:53:45 INFO - PROCESS | 12110 | __metrics Screen width/height:1600/1200 04:53:45 INFO - PROCESS | 12110 | colorDepth:24 04:53:45 INFO - PROCESS | 12110 | Browser inner width/height: 1024/697 04:53:45 INFO - PROCESS | 12110 | __metrics 04:53:46 ERROR - PROCESS | 12110 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:46 INFO - PROCESS | 12110 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 04:53:50 INFO - TEST-INFO | 12110: exit 0 04:53:50 INFO - Browser initialized. 04:53:50 INFO - Running cycle 1/1 for dromaeo_css test... 04:53:50 INFO - TEST-INFO | started process 12244 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8wDqW9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:19 INFO - PROCESS | 12244 | 04:54:24 INFO - PROCESS | 12244 | (process:12298): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:54:24 INFO - PROCESS | 12244 | RSS: Main: 161210368 04:54:24 INFO - PROCESS | 12244 | 04:55:08 INFO - PROCESS | 12244 | 1456836908208 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:57:19 INFO - PROCESS | 12244 | Cycle 1(1): loaded http://localhost:42765/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42765/tests/dromaeo/cssquery-ext.html) 04:57:20 INFO - PROCESS | 12244 | RSS: Main: 169340928 04:57:20 INFO - PROCESS | 12244 | 05:00:22 INFO - PROCESS | 12244 | Cycle 1(1): loaded http://localhost:42765/tests/dromaeo/cssquery-ext.html (next: http://localhost:42765/tests/dromaeo/cssquery-jquery.html) 05:00:23 INFO - PROCESS | 12244 | RSS: Main: 165830656 05:00:23 INFO - PROCESS | 12244 | 05:03:25 INFO - PROCESS | 12244 | Cycle 1(1): loaded http://localhost:42765/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42765/tests/dromaeo/cssquery-mootools.html) 05:03:25 INFO - PROCESS | 12244 | RSS: Main: 166363136 05:03:25 INFO - PROCESS | 12244 | 05:06:27 INFO - PROCESS | 12244 | Cycle 1(1): loaded http://localhost:42765/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42765/tests/dromaeo/cssquery-prototype.html) 05:06:28 INFO - PROCESS | 12244 | RSS: Main: 164425728 05:06:28 INFO - PROCESS | 12244 | 05:09:30 INFO - PROCESS | 12244 | Cycle 1(1): loaded http://localhost:42765/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42765/tests/dromaeo/cssquery-yui.html) 05:09:31 INFO - PROCESS | 12244 | RSS: Main: 164253696 05:09:31 INFO - PROCESS | 12244 | 05:12:33 INFO - PROCESS | 12244 | Cycle 1(1): loaded http://localhost:42765/tests/dromaeo/cssquery-yui.html (next: http://localhost:42765/tests/dromaeo/cssquery-dojo.html) 05:12:34 INFO - PROCESS | 12244 | RSS: Main: 168714240 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | __start_tp_report 05:12:34 INFO - PROCESS | 12244 | _x_x_mozilla_page_load 05:12:34 INFO - PROCESS | 12244 | _x_x_mozilla_page_load_details 05:12:34 INFO - PROCESS | 12244 | |i|pagename|runs| 05:12:34 INFO - PROCESS | 12244 | |0;dojo.html;2640;2647.721989191135;2691.448253108113;2706.4045909899824;2713.009751440725;4477.171723231205;4529.977350113245;4531.048479819237;4583.885402864926;4591.311303304506;4513.706609070408;4527.6151527120155;4554.385158003669;4586.266197408415;4610.147122782288;4156.314208155651;4239.10978694473;4242.193983143208;4266.530681625034;4302.741835489881;513.0697897001692;4132.359484279941;4144.336906095025;4144.44050053242;4194;4235.174141042498;4241.597048280409;4249.638780703613;4277.379779931893;4282.015136518618;4215.789210539491;4216.662666986665;4232.682548808852;4255.893602659928;4261.382099595572;4119.8970025749295;4131.710780245384;4201.285781417167;4212;4242.893927651802;4415.66882483815;4446.599806017474;4446.903316486745;4451.287793952984;4481.260592002316;4828.203346447832;4828.203346447832;4838.419389673227;4843.806247750086;4879.902401951941;4971.1797553404;4973.278874563204;4981.27771473138;4998.90002199954;5030.647854650176;4607.861764147081;4639.652026098056;4644.280136578802;4648.349231107648;4648.581627653493;4807.302941073524;4993.400791904995;5029.874253143701;5091.719955402415;5122.692638441706;4808.783604737795;4812.614990800727;4813.15769740301;4833.613310935117;4855.223164293696;4836.3712717347225;4853.854384368475;4859.441164266084;4897.804087836446;4905.9018819624125;4743.383360163157;4825.179719447702;4830.6135509159185;4901.730404827768;4927.162382395001;4810.759462026885;4836.975815120973;4859.829905953275;4866.148424025781;4914.655974081851;4078.163976384834;4095.385692146202;4103.994521342231;4105.240530501807;4124.443200167939;3567.090391950088;3605.4231322988194;3609.5848977367414;3622.710183185339;3631.3100510902846;4171.812268447927;4264.7654379009455;4304.117655880599;4342.869713908524;4374.147041326911;4763.237881938942;4915.557599816034;4918.040982008474;4926.950730492721;4953.900921981613;4230.196262710076;4271.444712187395;4271.700980931367;4289.6139347458875;4311.611954924072;4417.514073451917;4424.3142312941545;4430.667699922518;4443.155800397914;4464.285714285698;4637.443506779208;4710.9764451177525;4713.363695901078;4720.645951553579;4728.40894888139;4911.975440122919;4913.65604407688;4921.581665558468;4934.8766280843265;4938.061768264019;4631.76841157934;4638.791254393493;4648.395708557866;4662.883427914329;4693.671442998957;4770.546798054179;4830.203016502238;4835.202191638481;4908.607311415007;4945.4807245238735;4891.755730505882;4893.363862697969;4903.926441103457;4909.607231421549;4943.950560494349;4733.692309999977;4741.431028276629;4742.502037286035;4742.549457801503;4788.688735232199;4691.906161876812;4716.811327546997;4747.572718455355;4760.809567617395;4792.7603619819565;4629.652776041878;4641.141388843075;4641.976790116028;4644.674872758875;4697.530246975275;4635.466921304093;4768.3085952537385;4780.115678599331;4788.329633851334;4841.636877234292;4598.126355992351;4720.103180395714;4722.52774722525;4737.38414006177;4753.073150735573 05:12:34 INFO - PROCESS | 12244 | |1;ext.html;452.6989551947954;474.20097136325296;474.3240881743517;486.309440594356;515.216870357057;29845;30711.539326910086;30748.538771918382;30750.38499230014;30763.23091922703;30849.22876928078;31279.218019549524;31694;31750.523742143887;31825.204369890766;7240.203577606446;7392.482526223166;7421.0723659542555;7429.925700742978;7459.403247740168;7155;7442.553446793183;7442.8883566746545;7445.962770186142;7455.77632671018;7528.945947567345;7533.3220010199075;7608.847823043536;7618.390528757714;7656.808579785522;7478.850422991537;7599.202083781206;7611;7611.505252158592;7625.084989801259;7625.847483050364;7730.613469326568;7748.535087894745;7775.183605721374;7775.533467991939;31590.210244743834;31674.524882126785;31882;32038.35923281521;32082.839585802154;2153.569286142762;2415.553122672311;2499.9750002499923;2514.974850251511;2526.0527302261653;23571.29286121419;24678.13626523081;25093.62359564608;25101.748982510122;27428;3214.373197226542;3246.675332466758;3246.724028457584;3282.3927573398996;3350.4471762159214;174294;174844;175285;175715;176510.99999999872;36341;45220;45491.54508454939;45575.77212113918;45596;35142.297154056774;35288.29423411569;35417;35458.46812297818;35473.822630887196;11689.824652630217;11715.121365897592;11753.588624398106;11788.70528236801;11957.402129893644;9378.343515953955;9426.340156189139;9534.713958581253;9536.713898583053;9569.521523923915;11935.343556104497;11970.40147992597;12213.511459541518;12321.199122057038;12409.75180496385;4949.802007919643;5023.266972894346;5071.0872043032605;5090.440051594322;5107.310513080761;723.8009547374514;802.3420794948203;978.2320878110648;989.178981445413;1001.7445551384351;23960.64059039116;25143.371415714388;25177.370565736004;25356.873215633805;25371.238862834143;842.1830824100613;864.9870251946226;927.0544045073987;968.864358989742;1005.2912696548915;12239.816402753966;12516.052306785075;12708.364581770875;12806;12813.679658008625;256957;261175;262983;263716;263731;39065.41401878974;40363.79818100949;40549.79725101356;40870.59129408727;40893.18213635711;965.4689920543673;1111.2665640677276;1115.341948250536;1119.0376276402287;1123.2418117770471;24334.756652433956;24350;25339.746602534477;25476.490470190864;25496.490070198863;36736.26527469489;37097.258054839294;37171.81414092912;37178;37282;239525;240404;241593;241593;241684;24773;36566.817165914;36579.817100914326;36724.265514690094;37628.81185594055;32787.34425311528;32863.67136328606;33316.66683333136;33610;33855.322893542485;27919.302017449725;28007;28505.857470713345;28537.571936421387;28841.278968025967;28192.577111342936;28523.572146418235;28549.857250713612;28631.42737145204;29116.272093196993;30296.5455518163;30396.544051839683;30496.542551861297;30500.542491862197;31137.377252455277;26354;27797.30506737267;28141.985634651464;28298.2925426866;28357.43285134327;22690.88146808085;22759.431014224778;22764.658530122393;22896.427589310402;22918.656220157045 05:12:34 INFO - PROCESS | 12244 | |2;jquery.html;306.44992238931115;307.1920848169314;311.85654598884514;312.6857508204254;317.9809211447315;11572.768544629123;14668.926655366722;15141.242937853105;15197.240137993098;15996.680066398665;13349.599512014654;13404.32978351081;13444.462221511129;13563.186208827452;13609.591712248624;4709.411323584561;4901.313816065754;4915.2381380885845;4916.827911023115;4939.357883475143;4673.299005149221;4745.264484004968;4843.854684359475;4863.343448634424;4884.926726099111;4842.394700662418;4869.951300487003;4876.878078048059;4885.462599114095;4890.290907818364;4826.517348265163;4839.274108883678;4864.464908860023;5115.053715062725;5142.07442660321;4921.483244259372;4922.114019476493;4939.135651261051;4948.1093403187415;4971.676841005323;10178.389296642225;10517.421541815198;13850.65373365674;17566.29734810606;17720.55698607545;1801.5867094431621;1898.8765730227628;1924.3745782620565;1965.695317225818;1973.4572992427054;7837.921620783776;8562.828743425096;8586.27016703581;8672.956635216846;8751.693690720795;1685.763993040963;1729.80107287661;1752.1239380309846;1766.2846547148426;1767.8762486625942;53882.73058634721;56278;56920.71539642317;56941.43058569403;58274.12588811171;9243.630254789867;9286.674966376144;9502.62211979273;9572.56923438447;9631.855522167172;11439.656810295704;12132.090093243041;12145.574904878287;12171.93914030442;12175;6051.727672254759;6293.213348331458;6302.905456418157;6433.228012638513;6742;6251.687415629201;6365.936340636535;6388.904166437507;6394.104825324458;6461.418472337512;3978.741381810231;4046.988252936766;4107.219628270619;4129.318662420727;4153.439285696452;1685.8398452147026;1728.5764987578125;1733.479956013191;1743.8142063397013;1765.748670445167;724.4168444402306;724.6159535446222;758.9696412143452;768.1703759939364;804.0552350987566;13846.861531384557;14212.928935355258;14223.288835558387;14367.281635918162;14411.279436028366;700.3871612339203;703.5391818358983;705.2418649951322;731.1920328037442;808.8382323535317;7957.960210198912;7968.482048666818;8347.081820999685;9244.445333280024;9394.342396032212;389006;389430;393497;393655;399077;66632;66935.3306466929;68298;70019.29980700128;70137;869.9304055675531;871.6077765005773;891.7191084808264;897.5018864530249;899.581694512055;13564.660883477598;13815;13938.790918136034;14074.859251407355;14200.502982395567;20517.07673154682;22144.33566992993;24289.51420971606;27667.584986225625;30674.693253068075;34165.316693666486;38123.42814857835;39746.60253397429;39799;40997.97505062397;6869.381755642016;6952.130983627047;7013.509054366145;7016.824579385556;7139;9697.127258546765;10147.898521014695;10364.896351036394;10561.366318020944;10584.364938103738;4516.706414083074;7118.715251390094;9131.041240669634;9162.541872906462;9319.114684105;9223.723288301362;9416.81166376655;9483.430994140374;9591.088846559565;9648.71053868385;8753.212210901049;9315.580798864203;9685.03149685054;9697.418154910727;9802.803943921224;6881.380675739208;7079.823004424725;7093.751718689821;7125.67934442941;7178.820529486596;5904.789518148683;7343.632818358954;7776.805579860549;8794.20852123312;9495.525223738923 05:12:34 INFO - PROCESS | 12244 | |3;mootools.html;1242.111889998651;1247.401247401247;1273.4715093236307;1533.6319283371995;1544.235603376329;5963.194968679227;6607.702653380597;6698.83252918676;6713.194416669994;6780.966095169523;6661.134052573158;6738.124043874302;6748.460123190145;6755.662216889161;6756.459483241341;1952.1020330647918;1976.0218691747605;1987.32430973469;1987.5627361980414;1988.9502762430911;1918.7409699690502;1920.4430715092606;1921.769387673481;1970.9704354434696;1990.0348331059424;1963.5385684364162;1983.1274239334718;1994.341867183833;1998.4204580671587;2006.729091572641;1934.2939826963197;1937.8159074887863;1941.5631482916372;1947.591005888765;1951.3560525026708;1787.8033416324195;1818.2272534173055;1819.7907240667228;1846.5937493751353;1848.9630207395776;6876.896846547305;6878.8624227515165;6884.345987131214;6901.309869013109;6905.447564194852;3889.2804831106337;3985.744490485517;3991.102002049545;3998.7800670963084;4007.3988901664693;2479.4669146133488;2573.1251374532562;2600.0509813917974;2613.0462381230905;2621.2005338371787;821.6836517940576;824.1840577827908;828.8590939540293;836.4270474724772;844.6916875340518;9529.90470095304;9542.618295268112;9542.952285238598;9550;9550.188234000048;3530.1527633367805;3543.7519373644113;3591.94612080819;3606.3508568457405;3608.2061946371755;8153.95923020393;8165.918340816515;8174.7956301092945;8178.018637763506;8179.141190174946;3813.3326667833016;3851.1334949636107;3857.8456861725217;3858.7491813032625;3863.7102217333813;3018.743406810446;3364.596248450153;3425.53755243044;3430.879919202816;3444.7588668793446;3208.614966204024;3217.6299725531394;3258.853351599184;3260.625028121749;3272.4927636216426;992.7815880506278;1014.4674046125843;1019.2508506247901;1021.1218352217087;1025.1491262252312;986.8717066781272;1009.9091081802673;1012.7417508535276;1013.7719013221966;1043.4678314059731;6383.808485745435;6387.872242555123;6396.136521569529;6590.505712071614;6705.731770729116;874.1433395272542;883.0992387764572;900.6487469886749;906.805036917066;923.2706162131841;5135.97432012845;5158.793648254103;5169.7932082717025;5178.145605975047;5192.065428222956;8049.114597394282;8253.215944485384;8309.916900831035;8369.748907532661;8542.572871356531;8198.1391953844;8304.543250121302;8304.8339033219;8313;8385.28725058377;6319.304876463677;6411.230652321751;6437.0988061671405;6475.028745688184;6527;6075.787347442818;6145.324014358506;6168.691565421801;6201.565890387629;6215.502759779117;7269.345758881726;7342.6695798688115;7559.622018899143;7586.127595326608;7662;7630.504017238862;7730.304272615492;7814.101378341564;7848.215178482106;7893.013373328334;4997.85006449807;5470.64440811346;5607.579431542569;5691.800786972436;5743.167240749985;6408.327125651739;6457.644829534421;6464.062710906798;6477.77327793525;6481.643509607017;5775.133729940542;5787.594868359343;5791.131330300489;5791.623544469765;5996.940030599638;5305.575553955751;5321.9467805323;5587.189857470726;5717.885642287048;5800.8259752207505;5969.462748352669;5995.790147344822;6142.078688196806;6154.169187159676;6191.845203869759;5642.181883626933;5650.406707295675;5653.095504719226;5689.260396148474;5705.4579814917515;5027.421846487555;5027.446980832178;5117.30916326306;5124.128898087409;5138.768755405941 05:12:34 INFO - PROCESS | 12244 | |4;prototype.html;278.38476965905346;279.64066174965166;280.7543399525415;282.9052267490392;286.9081893793986;5508.944910550893;5561.33264008319;5565.749541270648;5582.078956972095;5607.551395888329;5441.401445840955;5489.313835770528;5496.862578435541;5499.670019798805;5510.862228444291;3993.520777506689;4120.196561670476;4198.538160802309;4202.495700515935;4221.535631080578;4179.247735407625;4197.748135111903;4212.136512015045;4264.8507302244425;4280.4221430106845;4185.895352616194;4193.727407718488;4202.91594168118;4206.810693518799;4218.6625069994325;4202.453681021463;4206.705530612859;4210.221109094797;4220.6412454941365;4226.978865105685;3680.5399325084363;3682.784681055272;3686.225892562565;3688.0964163780045;3697.5193224880863;5338.91991620126;5343.545798607084;5343.839684809462;5347.732613369355;5351.608581768729;4641.187792136397;4841.273808928654;4843.733594652293;4855.32025516428;4855.781489832931;5730.140478928152;5768.480836724673;5776.249087618583;5796.913046304308;5800.245968024172;3962.841486340543;3970.920581588352;3971.960280397188;3977.9204415911518;3980.7611543307203;5997.190379298782;6083.17877086592;6089.665068421279;6091.42131497507;6094.847628809271;4096.242195193899;4102.794860256975;4105.507339119325;4105.630493255621;4108.993296642282;4173.207090652766;4179.770112643834;4180.101278225196;4189.6438802701505;4189.811458484375;4658.813647454064;4809.5430934061205;4842;4847.587955023862;4852.757362131879;5750.568707346965;5781.624194427349;5795.043817770021;5808.419158084243;5819.5053420459735;1463.6926245488567;1480.2154857925316;1481.3482067890093;1484.7550154224432;1487.5091219897408;980.9062894872167;990.9306348555676;993.5082134343546;998.8801343838786;1008.4050410257836;5060.672090838256;5068.467810879878;5069.5690866276045;5078.669886457368;5090.211017292326;5961.701914904237;5981.880362392728;6010.669413182317;6017.638941663514;6031.7587296507645;4958.330625365529;5035.471275516092;5046.343975283263;5052.9242061369105;5094.490550944876;3240.076578175209;3295.35740530599;3295.6869097435706;3299.2411745298714;3306.371789360062;5763.971180144073;5765.279340082489;5767.596268261265;5770.047942089592;5771.797987070517;6276.497880169575;6286.622802631857;6287.962486189727;6288.8427789304715;6292.622442653455;5744.913826292525;5758.568107391962;5758.596898217084;5759.65442073477;5763.740631671585;5782.710864456844;5918.526517878473;5953;6099.359567245553;6160.630362178284;5944.405559444021;5949.3455719871645;5955.583109182316;5960.701964901651;5962;5740.253767010262;5753.309602847685;5757.568182386254;5758.452946970031;5771.336296325976;3961.2275606256503;4006.2788698034633;4007.859724909614;4008.779517126586;4022.4770779798437;5890.558208134321;6081.361457046946;6083.391660834058;6094.847628809315;6096.146539484387;5653;5803.100519419497;5824.504917082096;5826.796062137805;5833.125031245348;5810.5061069809535;5813.854653633693;5819.6508209507565;5822.330432000204;5823.475887170006;5665.546756259571;5666.178404131296;5686.43135686428;5688.601797874109;5688.914666280092;5854.272989468032;5856.765729370777;5862.560307976833;5864.06175011996;5865.296164460292;5504.80733174337;5664.886702266014;5720.599558030898;5725.112607545837;5729.08334666468 05:12:34 INFO - PROCESS | 12244 | |5;yui.html;537.3283395755305;576.6194311754242;586.9325027621823;589.3252226201;589.5444878579555;10425.113865321448;10631.946840265797;10671.893281067187;10790.892091079088;10797.40614266215;9921.95039024804;9937.552810123543;10150.593976240942;10266.33268837525;10675.78648427031;514.8326793792014;530.6789392417589;547.5537436988861;549.541133153817;550.4000639303159;645.4513663386131;650.0541711809329;650.193759737924;652.6801867085118;655.7639249870043;666.7133132752914;670.4636290967231;675.4461341699808;675.5001299038701;677.2922296200458;663.3797399431522;671.2515545167153;672.6703915081574;674.6727836999052;676.8950812624051;500.1622282676502;501.208091215878;502.95473407393513;507.24167369782043;516.3855012535071;10624.681259562225;10760.085392741626;10766.084882784973;10774.299670521392;10784.73038174044;10227.948860255725;10328.896711032869;10435.895641043644;10655.307405018726;10662.573497060186;8874;8891.822163556757;9016.278697704234;9081.181762339778;9161.450312981176;3128.7809853310278;3159.6840315968448;3177.8252196129206;3232.5474433579316;3237.0346325751;35414;39849;39874.20251594952;40821.18357632861;41456.58543414557;8463.957680211683;8555.786105347292;8942.50816205102;9041.367104302635;9087.136722011399;13981.79027314591;14279.78580321296;14312.06971546846;14500.347484363228;14523.636909077357;5939.10913362999;6134.957057300269;6138.14066030756;6188;6279.811605651837;3286.293446908926;3318.8008719476907;3378.45944648855;3499.1077275295147;3690.9077273068388;2461.0155937625095;2474.665920100799;2484.3664865459555;2486.0925762096704;2500.9124680636455;453.3403897329378;483.9322494850653;499.5229555774227;504.4929845505244;504.74762618690653;414.6848395219595;491.4618492750433;497.93277907482025;521.4498703867456;529.7642549065706;6418.839529011812;6421.8073457796345;6463.095166676669;6514.543981921315;6648.434883034899;418.38497408809;457.3163121133926;462.01590611996903;484.394506866417;484.601223941491;3095.7523398128096;3142.4972004479173;3247.675232476781;3278.459054256021;3298.109510432218;44458.3331250025;47479.5252047482;47609.285860712116;47855.28217076747;48251;43466.34800477995;43618.34572481416;43827;43995.56004439979;44126.33810492845;36277.27445451076;40260.798696006335;42205;44302.33546496805;46558.5344146561;7053.6825842838225;7070.8232294191;7133.714651413885;7143.571385716874;7205.099362579677;30525;30581.54127688131;30881.536776947913;31195.220119497193;31920.680793191772;36739.26521469609;38466;38547;38653;38747.22505549929;8726.869096963424;8912.821743565222;8927.821443571222;8975.237104846161;9017.323700722603;9942.502874856373;10277.640282590073;10286;10442.477876106012;10447.216458765472;8949.463032218087;9103.044180360966;9730.46482443444;10056.748581285527;10530.947345263225;9323.953380233055;9413.294002949942;9606.903930960601;9618.278629102706;9713.46575938302;9546.809063818546;9655.951720241354;9731.951340243253;9846.163076138608;10029.498525073863;8101.230383113595;8489.4481858679;8526.403151779316;8584.347130973545;8737.694180703898;8193.918060819555;8533.615987280464;8692.565371731514;8747.343949203963;8757.386982911135 05:12:34 INFO - PROCESS | 12244 | __end_tp_report 05:12:34 INFO - PROCESS | 12244 | __start_cc_report 05:12:34 INFO - PROCESS | 12244 | _x_x_mozilla_cycle_collect,334 05:12:34 INFO - PROCESS | 12244 | __end_cc_report 05:12:34 INFO - PROCESS | 12244 | __startTimestamp1456837954039__endTimestamp 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | ------- Summary: start ------- 05:12:34 INFO - PROCESS | 12244 | Number of tests: 6 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | [#0] dojo.html Cycles:165 Average:4506.60 Median:4648.40 stddev:554.41 (11.9%) stddev-sans-first:536.42 05:12:34 INFO - PROCESS | 12244 | Values: 2640.0 2647.7 2691.4 2706.4 2713.0 4477.2 4530.0 4531.0 4583.9 4591.3 4513.7 4527.6 4554.4 4586.3 4610.1 4156.3 4239.1 4242.2 4266.5 4302.7 513.1 4132.4 4144.3 4144.4 4194.0 4235.2 4241.6 4249.6 4277.4 4282.0 4215.8 4216.7 4232.7 4255.9 4261.4 4119.9 4131.7 4201.3 4212.0 4242.9 4415.7 4446.6 4446.9 4451.3 4481.3 4828.2 4828.2 4838.4 4843.8 4879.9 4971.2 4973.3 4981.3 4998.9 5030.6 4607.9 4639.7 4644.3 4648.3 4648.6 4807.3 4993.4 5029.9 5091.7 5122.7 4808.8 4812.6 4813.2 4833.6 4855.2 4836.4 4853.9 4859.4 4897.8 4905.9 4743.4 4825.2 4830.6 4901.7 4927.2 4810.8 4837.0 4859.8 4866.1 4914.7 4078.2 4095.4 4104.0 4105.2 4124.4 3567.1 3605.4 3609.6 3622.7 3631.3 4171.8 4264.8 4304.1 4342.9 4374.1 4763.2 4915.6 4918.0 4927.0 4953.9 4230.2 4271.4 4271.7 4289.6 4311.6 4417.5 4424.3 4430.7 4443.2 4464.3 4637.4 4711.0 4713.4 4720.6 4728.4 4912.0 4913.7 4921.6 4934.9 4938.1 4631.8 4638.8 4648.4 4662.9 4693.7 4770.5 4830.2 4835.2 4908.6 4945.5 4891.8 4893.4 4903.9 4909.6 4944.0 4733.7 4741.4 4742.5 4742.5 4788.7 4691.9 4716.8 4747.6 4760.8 4792.8 4629.7 4641.1 4642.0 4644.7 4697.5 4635.5 4768.3 4780.1 4788.3 4841.6 4598.1 4720.1 4722.5 4737.4 4753.1 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | [#1] ext.html Cycles:180 Average:36312.53 Median:31684.26 stddev:59763.71 (188.6%) stddev-sans-first:59870.71 05:12:34 INFO - PROCESS | 12244 | Values: 452.7 474.2 474.3 486.3 515.2 29845.0 30711.5 30748.5 30750.4 30763.2 30849.2 31279.2 31694.0 31750.5 31825.2 7240.2 7392.5 7421.1 7429.9 7459.4 7155.0 7442.6 7442.9 7446.0 7455.8 7528.9 7533.3 7608.8 7618.4 7656.8 7478.9 7599.2 7611.0 7611.5 7625.1 7625.8 7730.6 7748.5 7775.2 7775.5 31590.2 31674.5 31882.0 32038.4 32082.8 2153.6 2415.6 2500.0 2515.0 2526.1 23571.3 24678.1 25093.6 25101.7 27428.0 3214.4 3246.7 3246.7 3282.4 3350.4 174294.0 174844.0 175285.0 175715.0 176511.0 36341.0 45220.0 45491.5 45575.8 45596.0 35142.3 35288.3 35417.0 35458.5 35473.8 11689.8 11715.1 11753.6 11788.7 11957.4 9378.3 9426.3 9534.7 9536.7 9569.5 11935.3 11970.4 12213.5 12321.2 12409.8 4949.8 5023.3 5071.1 5090.4 5107.3 723.8 802.3 978.2 989.2 1001.7 23960.6 25143.4 25177.4 25356.9 25371.2 842.2 865.0 927.1 968.9 1005.3 12239.8 12516.1 12708.4 12806.0 12813.7 256957.0 261175.0 262983.0 263716.0 263731.0 39065.4 40363.8 40549.8 40870.6 40893.2 965.5 1111.3 1115.3 1119.0 1123.2 24334.8 24350.0 25339.7 25476.5 25496.5 36736.3 37097.3 37171.8 37178.0 37282.0 239525.0 240404.0 241593.0 241593.0 241684.0 24773.0 36566.8 36579.8 36724.3 37628.8 32787.3 32863.7 33316.7 33610.0 33855.3 27919.3 28007.0 28505.9 28537.6 28841.3 28192.6 28523.6 28549.9 28631.4 29116.3 30296.5 30396.5 30496.5 30500.5 31137.4 26354.0 27797.3 28142.0 28298.3 28357.4 22690.9 22759.4 22764.7 22896.4 22918.7 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | [#2] jquery.html Cycles:180 Average:22176.37 Median:4916.03 stddev:64476.42 (1311.6%) stddev-sans-first:64636.38 05:12:34 INFO - PROCESS | 12244 | Values: 306.4 307.2 311.9 312.7 318.0 11572.8 14668.9 15141.2 15197.2 15996.7 13349.6 13404.3 13444.5 13563.2 13609.6 4709.4 4901.3 4915.2 4916.8 4939.4 4673.3 4745.3 4843.9 4863.3 4884.9 4842.4 4870.0 4876.9 4885.5 4890.3 4826.5 4839.3 4864.5 5115.1 5142.1 4921.5 4922.1 4939.1 4948.1 4971.7 10178.4 10517.4 13850.7 17566.3 17720.6 1801.6 1898.9 1924.4 1965.7 1973.5 7837.9 8562.8 8586.3 8673.0 8751.7 1685.8 1729.8 1752.1 1766.3 1767.9 53882.7 56278.0 56920.7 56941.4 58274.1 9243.6 9286.7 9502.6 9572.6 9631.9 11439.7 12132.1 12145.6 12171.9 12175.0 6051.7 6293.2 6302.9 6433.2 6742.0 6251.7 6365.9 6388.9 6394.1 6461.4 3978.7 4047.0 4107.2 4129.3 4153.4 1685.8 1728.6 1733.5 1743.8 1765.7 724.4 724.6 759.0 768.2 804.1 13846.9 14212.9 14223.3 14367.3 14411.3 700.4 703.5 705.2 731.2 808.8 7958.0 7968.5 8347.1 9244.4 9394.3 389006.0 389430.0 393497.0 393655.0 399077.0 66632.0 66935.3 68298.0 70019.3 70137.0 869.9 871.6 891.7 897.5 899.6 13564.7 13815.0 13938.8 14074.9 14200.5 20517.1 22144.3 24289.5 27667.6 30674.7 34165.3 38123.4 39746.6 39799.0 40998.0 6869.4 6952.1 7013.5 7016.8 7139.0 9697.1 10147.9 10364.9 10561.4 10584.4 4516.7 7118.7 9131.0 9162.5 9319.1 9223.7 9416.8 9483.4 9591.1 9648.7 8753.2 9315.6 9685.0 9697.4 9802.8 6881.4 7079.8 7093.8 7125.7 7178.8 5904.8 7343.6 7776.8 8794.2 9495.5 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | [#3] mootools.html Cycles:180 Average:4709.95 Median:5730.53 stddev:2510.74 (43.8%) stddev-sans-first:2504.25 05:12:34 INFO - PROCESS | 12244 | Values: 1242.1 1247.4 1273.5 1533.6 1544.2 5963.2 6607.7 6698.8 6713.2 6781.0 6661.1 6738.1 6748.5 6755.7 6756.5 1952.1 1976.0 1987.3 1987.6 1989.0 1918.7 1920.4 1921.8 1971.0 1990.0 1963.5 1983.1 1994.3 1998.4 2006.7 1934.3 1937.8 1941.6 1947.6 1951.4 1787.8 1818.2 1819.8 1846.6 1849.0 6876.9 6878.9 6884.3 6901.3 6905.4 3889.3 3985.7 3991.1 3998.8 4007.4 2479.5 2573.1 2600.1 2613.0 2621.2 821.7 824.2 828.9 836.4 844.7 9529.9 9542.6 9543.0 9550.0 9550.2 3530.2 3543.8 3591.9 3606.4 3608.2 8154.0 8165.9 8174.8 8178.0 8179.1 3813.3 3851.1 3857.8 3858.7 3863.7 3018.7 3364.6 3425.5 3430.9 3444.8 3208.6 3217.6 3258.9 3260.6 3272.5 992.8 1014.5 1019.3 1021.1 1025.1 986.9 1009.9 1012.7 1013.8 1043.5 6383.8 6387.9 6396.1 6590.5 6705.7 874.1 883.1 900.6 906.8 923.3 5136.0 5158.8 5169.8 5178.1 5192.1 8049.1 8253.2 8309.9 8369.7 8542.6 8198.1 8304.5 8304.8 8313.0 8385.3 6319.3 6411.2 6437.1 6475.0 6527.0 6075.8 6145.3 6168.7 6201.6 6215.5 7269.3 7342.7 7559.6 7586.1 7662.0 7630.5 7730.3 7814.1 7848.2 7893.0 4997.9 5470.6 5607.6 5691.8 5743.2 6408.3 6457.6 6464.1 6477.8 6481.6 5775.1 5787.6 5791.1 5791.6 5996.9 5305.6 5321.9 5587.2 5717.9 5800.8 5969.5 5995.8 6142.1 6154.2 6191.8 5642.2 5650.4 5653.1 5689.3 5705.5 5027.4 5027.4 5117.3 5124.1 5138.8 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | [#4] prototype.html Cycles:180 Average:4802.90 Median:5506.88 stddev:1433.54 (26.0%) stddev-sans-first:1396.75 05:12:34 INFO - PROCESS | 12244 | Values: 278.4 279.6 280.8 282.9 286.9 5508.9 5561.3 5565.7 5582.1 5607.6 5441.4 5489.3 5496.9 5499.7 5510.9 3993.5 4120.2 4198.5 4202.5 4221.5 4179.2 4197.7 4212.1 4264.9 4280.4 4185.9 4193.7 4202.9 4206.8 4218.7 4202.5 4206.7 4210.2 4220.6 4227.0 3680.5 3682.8 3686.2 3688.1 3697.5 5338.9 5343.5 5343.8 5347.7 5351.6 4641.2 4841.3 4843.7 4855.3 4855.8 5730.1 5768.5 5776.2 5796.9 5800.2 3962.8 3970.9 3972.0 3977.9 3980.8 5997.2 6083.2 6089.7 6091.4 6094.8 4096.2 4102.8 4105.5 4105.6 4109.0 4173.2 4179.8 4180.1 4189.6 4189.8 4658.8 4809.5 4842.0 4847.6 4852.8 5750.6 5781.6 5795.0 5808.4 5819.5 1463.7 1480.2 1481.3 1484.8 1487.5 980.9 990.9 993.5 998.9 1008.4 5060.7 5068.5 5069.6 5078.7 5090.2 5961.7 5981.9 6010.7 6017.6 6031.8 4958.3 5035.5 5046.3 5052.9 5094.5 3240.1 3295.4 3295.7 3299.2 3306.4 5764.0 5765.3 5767.6 5770.0 5771.8 6276.5 6286.6 6288.0 6288.8 6292.6 5744.9 5758.6 5758.6 5759.7 5763.7 5782.7 5918.5 5953.0 6099.4 6160.6 5944.4 5949.3 5955.6 5960.7 5962.0 5740.3 5753.3 5757.6 5758.5 5771.3 3961.2 4006.3 4007.9 4008.8 4022.5 5890.6 6081.4 6083.4 6094.8 6096.1 5653.0 5803.1 5824.5 5826.8 5833.1 5810.5 5813.9 5819.7 5822.3 5823.5 5665.5 5666.2 5686.4 5688.6 5688.9 5854.3 5856.8 5862.6 5864.1 5865.3 5504.8 5664.9 5720.6 5725.1 5729.1 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | [#5] yui.html Cycles:180 Average:11615.34 Median:499.84 stddev:13611.43 (2723.1%) stddev-sans-first:13624.19 05:12:34 INFO - PROCESS | 12244 | Values: 537.3 576.6 586.9 589.3 589.5 10425.1 10631.9 10671.9 10790.9 10797.4 9922.0 9937.6 10150.6 10266.3 10675.8 514.8 530.7 547.6 549.5 550.4 645.5 650.1 650.2 652.7 655.8 666.7 670.5 675.4 675.5 677.3 663.4 671.3 672.7 674.7 676.9 500.2 501.2 503.0 507.2 516.4 10624.7 10760.1 10766.1 10774.3 10784.7 10227.9 10328.9 10435.9 10655.3 10662.6 8874.0 8891.8 9016.3 9081.2 9161.5 3128.8 3159.7 3177.8 3232.5 3237.0 35414.0 39849.0 39874.2 40821.2 41456.6 8464.0 8555.8 8942.5 9041.4 9087.1 13981.8 14279.8 14312.1 14500.3 14523.6 5939.1 6135.0 6138.1 6188.0 6279.8 3286.3 3318.8 3378.5 3499.1 3690.9 2461.0 2474.7 2484.4 2486.1 2500.9 453.3 483.9 499.5 504.5 504.7 414.7 491.5 497.9 521.4 529.8 6418.8 6421.8 6463.1 6514.5 6648.4 418.4 457.3 462.0 484.4 484.6 3095.8 3142.5 3247.7 3278.5 3298.1 44458.3 47479.5 47609.3 47855.3 48251.0 43466.3 43618.3 43827.0 43995.6 44126.3 36277.3 40260.8 42205.0 44302.3 46558.5 7053.7 7070.8 7133.7 7143.6 7205.1 30525.0 30581.5 30881.5 31195.2 31920.7 36739.3 38466.0 38547.0 38653.0 38747.2 8726.9 8912.8 8927.8 8975.2 9017.3 9942.5 10277.6 10286.0 10442.5 10447.2 8949.5 9103.0 9730.5 10056.7 10530.9 9324.0 9413.3 9606.9 9618.3 9713.5 9546.8 9656.0 9732.0 9846.2 10029.5 8101.2 8489.4 8526.4 8584.3 8737.7 8193.9 8533.6 8692.6 8747.3 8757.4 05:12:34 INFO - PROCESS | 12244 | -------- Summary: end -------- 05:12:34 INFO - PROCESS | 12244 | 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:34 INFO - PROCESS | 12244 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:35 INFO - TEST-INFO | 12244: exit 0 05:12:35 INFO - TEST-OK | dromaeo_css | took 1139064ms 05:12:35 INFO - TEST-START | kraken 05:12:35 INFO - operating with platform_type : linux_ 05:12:35 INFO - Initialising browser for kraken test... 05:12:35 INFO - TEST-INFO | started process 12486 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbXaZYH/profile http://localhost:42765/getInfo.html) 05:12:45 INFO - PROCESS | 12486 | 05:12:45 INFO - PROCESS | 12486 | (process:12555): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:12:49 INFO - PROCESS | 12486 | __metrics Screen width/height:1600/1200 05:12:49 INFO - PROCESS | 12486 | colorDepth:24 05:12:49 INFO - PROCESS | 12486 | Browser inner width/height: 1024/697 05:12:49 INFO - PROCESS | 12486 | __metrics 05:12:51 ERROR - PROCESS | 12486 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:51 INFO - PROCESS | 12486 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:12:52 INFO - TEST-INFO | 12486: exit 0 05:12:52 INFO - Browser initialized. 05:12:52 INFO - Running cycle 1/1 for kraken test... 05:12:52 INFO - TEST-INFO | started process 12607 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbXaZYH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:54 INFO - PROCESS | 12607 | 05:12:54 INFO - PROCESS | 12607 | (process:12663): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:13:04 INFO - PROCESS | 12607 | RSS: Main: 161132544 05:13:04 INFO - PROCESS | 12607 | 05:13:07 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42765/tests/kraken/driver.html?testName=audio-beat-detection) 05:13:07 INFO - PROCESS | 12607 | RSS: Main: 166936576 05:13:07 INFO - PROCESS | 12607 | 05:13:10 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42765/tests/kraken/driver.html?testName=audio-dft) 05:13:11 INFO - PROCESS | 12607 | RSS: Main: 169103360 05:13:11 INFO - PROCESS | 12607 | 05:13:14 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42765/tests/kraken/driver.html?testName=audio-fft) 05:13:14 INFO - PROCESS | 12607 | RSS: Main: 164618240 05:13:14 INFO - PROCESS | 12607 | 05:13:16 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42765/tests/kraken/driver.html?testName=audio-oscillator) 05:13:17 INFO - PROCESS | 12607 | RSS: Main: 164990976 05:13:17 INFO - PROCESS | 12607 | 05:13:19 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42765/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:13:20 INFO - PROCESS | 12607 | RSS: Main: 165253120 05:13:20 INFO - PROCESS | 12607 | 05:13:23 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42765/tests/kraken/driver.html?testName=imaging-darkroom) 05:13:24 INFO - PROCESS | 12607 | RSS: Main: 165318656 05:13:24 INFO - PROCESS | 12607 | 05:13:28 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42765/tests/kraken/driver.html?testName=imaging-desaturate) 05:13:28 INFO - PROCESS | 12607 | RSS: Main: 163446784 05:13:28 INFO - PROCESS | 12607 | 05:13:31 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42765/tests/kraken/driver.html?testName=json-parse-financial) 05:13:32 INFO - PROCESS | 12607 | RSS: Main: 163512320 05:13:32 INFO - PROCESS | 12607 | 05:13:33 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42765/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:13:34 INFO - PROCESS | 12607 | RSS: Main: 165367808 05:13:34 INFO - PROCESS | 12607 | 05:13:36 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:13:37 INFO - PROCESS | 12607 | RSS: Main: 163524608 05:13:37 INFO - PROCESS | 12607 | 05:13:39 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:13:39 INFO - PROCESS | 12607 | RSS: Main: 163659776 05:13:39 INFO - PROCESS | 12607 | 05:13:42 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:13:43 INFO - PROCESS | 12607 | RSS: Main: 165060608 05:13:43 INFO - PROCESS | 12607 | 05:13:45 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:13:46 INFO - PROCESS | 12607 | RSS: Main: 165740544 05:13:46 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | Cycle 1(1): loaded http://localhost:42765/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42765/tests/kraken/driver.html?testName=ai-astar) 05:13:48 INFO - PROCESS | 12607 | RSS: Main: 166653952 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | __start_tp_report 05:13:48 INFO - PROCESS | 12607 | _x_x_mozilla_page_load 05:13:48 INFO - PROCESS | 12607 | _x_x_mozilla_page_load_details 05:13:48 INFO - PROCESS | 12607 | |i|pagename|runs| 05:13:48 INFO - PROCESS | 12607 | |0;ai-astar;115;108;113;121;114;125;115;116;114;116 05:13:48 INFO - PROCESS | 12607 | |1;audio-beat-detection;157;142;158;181;134;163;158;133;173;135 05:13:48 INFO - PROCESS | 12607 | |2;audio-dft;164;164;162;163;165;173;165;164;166;164 05:13:48 INFO - PROCESS | 12607 | |3;audio-fft;75;74;74;153;73;73;74;76;163;78 05:13:48 INFO - PROCESS | 12607 | |4;audio-oscillator;95;95;143;95;96;95;153;94;97;139 05:13:48 INFO - PROCESS | 12607 | |5;imaging-gaussian-blur;117;118;117;117;117;118;118;117;117;118 05:13:48 INFO - PROCESS | 12607 | |6;imaging-darkroom;147;148;147;146;148;147;148;146;149;148 05:13:48 INFO - PROCESS | 12607 | |7;imaging-desaturate;97;96;97;96;98;97;96;97;97;97 05:13:48 INFO - PROCESS | 12607 | |8;json-parse-financial;68;69;67;70;69;67;67;68;68;68 05:13:48 INFO - PROCESS | 12607 | |9;json-stringify-tinderbox;71;70;71;69;70;70;71;71;71;70 05:13:48 INFO - PROCESS | 12607 | |10;stanford-crypto-aes;83;84;84;85;85;86;93;85;100;90 05:13:48 INFO - PROCESS | 12607 | |11;stanford-crypto-ccm;138;127;124;125;128;132;127;130;122;131 05:13:48 INFO - PROCESS | 12607 | |12;stanford-crypto-pbkdf2;141;156;155;157;155;147;153;149;155;156 05:13:48 INFO - PROCESS | 12607 | |13;stanford-crypto-sha256-iterative;70;73;72;77;71;70;70;74;72;73 05:13:48 INFO - PROCESS | 12607 | __end_tp_report 05:13:48 INFO - PROCESS | 12607 | __start_cc_report 05:13:48 INFO - PROCESS | 12607 | _x_x_mozilla_cycle_collect,896 05:13:48 INFO - PROCESS | 12607 | __end_cc_report 05:13:48 INFO - PROCESS | 12607 | __startTimestamp1456838028375__endTimestamp 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | ------- Summary: start ------- 05:13:48 INFO - PROCESS | 12607 | Number of tests: 14 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#0] ai-astar Cycles:10 Average:115.70 Median:115.50 stddev:4.57 (4.0%) stddev-sans-first:4.84 05:13:48 INFO - PROCESS | 12607 | Values: 115.0 108.0 113.0 121.0 114.0 125.0 115.0 116.0 114.0 116.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#1] audio-beat-detection Cycles:10 Average:153.40 Median:158.00 stddev:16.85 (10.7%) stddev-sans-first:17.82 05:13:48 INFO - PROCESS | 12607 | Values: 157.0 142.0 158.0 181.0 134.0 163.0 158.0 133.0 173.0 135.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#2] audio-dft Cycles:10 Average:165.00 Median:164.50 stddev:3.02 (1.8%) stddev-sans-first:3.18 05:13:48 INFO - PROCESS | 12607 | Values: 164.0 164.0 162.0 163.0 165.0 173.0 165.0 164.0 166.0 164.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#3] audio-fft Cycles:10 Average:91.30 Median:74.00 stddev:35.26 (47.7%) stddev-sans-first:36.91 05:13:48 INFO - PROCESS | 12607 | Values: 75.0 74.0 74.0 153.0 73.0 73.0 74.0 76.0 163.0 78.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#4] audio-oscillator Cycles:10 Average:110.20 Median:95.00 stddev:24.27 (25.5%) stddev-sans-first:25.11 05:13:48 INFO - PROCESS | 12607 | Values: 95.0 95.0 143.0 95.0 96.0 95.0 153.0 94.0 97.0 139.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:117.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 05:13:48 INFO - PROCESS | 12607 | Values: 117.0 118.0 117.0 117.0 117.0 118.0 118.0 117.0 117.0 118.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:148.00 stddev:0.97 (0.7%) stddev-sans-first:1.01 05:13:48 INFO - PROCESS | 12607 | Values: 147.0 148.0 147.0 146.0 148.0 147.0 148.0 146.0 149.0 148.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:97.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:13:48 INFO - PROCESS | 12607 | Values: 97.0 96.0 97.0 96.0 98.0 97.0 96.0 97.0 97.0 97.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#8] json-parse-financial Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 05:13:48 INFO - PROCESS | 12607 | Values: 68.0 69.0 67.0 70.0 69.0 67.0 67.0 68.0 68.0 68.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:0.70 (1.0%) stddev-sans-first:0.71 05:13:48 INFO - PROCESS | 12607 | Values: 71.0 70.0 71.0 69.0 70.0 70.0 71.0 71.0 71.0 70.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:85.00 stddev:5.36 (6.3%) stddev-sans-first:5.43 05:13:48 INFO - PROCESS | 12607 | Values: 83.0 84.0 84.0 85.0 85.0 86.0 93.0 85.0 100.0 90.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#11] stanford-crypto-ccm Cycles:10 Average:128.40 Median:129.00 stddev:4.60 (3.6%) stddev-sans-first:3.32 05:13:48 INFO - PROCESS | 12607 | Values: 138.0 127.0 124.0 125.0 128.0 132.0 127.0 130.0 122.0 131.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:155.00 stddev:5.15 (3.3%) stddev-sans-first:3.43 05:13:48 INFO - PROCESS | 12607 | Values: 141.0 156.0 155.0 157.0 155.0 147.0 153.0 149.0 155.0 156.0 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:72.50 stddev:2.20 (3.0%) stddev-sans-first:2.19 05:13:48 INFO - PROCESS | 12607 | Values: 70.0 73.0 72.0 77.0 71.0 70.0 70.0 74.0 72.0 73.0 05:13:48 INFO - PROCESS | 12607 | -------- Summary: end -------- 05:13:48 INFO - PROCESS | 12607 | 05:13:48 INFO - PROCESS | 12607 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:13:48 INFO - PROCESS | 12607 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:13:48 INFO - PROCESS | 12607 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:13:48 INFO - PROCESS | 12607 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:13:48 INFO - PROCESS | 12607 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:13:48 INFO - PROCESS | 12607 | nsHttpConnectionMgr::Shutdown() ProcessNextEvent 05:13:59 INFO - Browser shutdown timed out after 5 seconds, terminating process. 05:14:13 INFO - Terminating psutil.Process(pid=12607, name='firefox') 05:14:49 INFO - TEST-UNEXPECTED-ERROR | kraken | psutil.TimeoutExpired timeout after 3 seconds (pid=12607, name='firefox') 05:14:49 ERROR - Traceback (most recent call last): 05:14:49 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 196, in run_tests 05:14:49 INFO - talos_results.add(mytest.runTest(browser_config, test)) 05:14:49 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 64, in runTest 05:14:49 INFO - return self._runTest(browser_config, test_config, setup) 05:14:49 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 167, in _runTest 05:14:49 INFO - if counter_management else None), 05:14:49 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 132, in run_browser 05:14:49 INFO - return_code = context.kill_process() 05:14:49 INFO - File "/builds/slave/test/build/tests/talos/talos/talos_process.py", line 43, in kill_process 05:14:49 INFO - return self.process.wait(3) 05:14:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/__init__.py", line 1102, in wait 05:14:49 INFO - return self._proc.wait(timeout) 05:14:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 709, in wrapper 05:14:49 INFO - return fun(self, *args, **kwargs) 05:14:49 INFO - File "/builds/slave/test/build/venv/local/lib/python2.7/site-packages/psutil/_pslinux.py", line 851, in wait 05:14:49 INFO - raise TimeoutExpired(timeout, self.pid, self._name) 05:14:49 INFO - TimeoutExpired: psutil.TimeoutExpired timeout after 3 seconds (pid=12607, name='firefox') 05:14:49 INFO - TEST-INFO took 134152ms 05:14:49 INFO - SUITE-END | took 1273s 05:14:49 ERROR - Return code: 2 05:14:49 WARNING - setting return code to 2 05:14:49 ERROR - # TBPL FAILURE # 05:14:49 INFO - Running post-action listener: _resource_record_post_action 05:14:49 INFO - Running post-run listener: _resource_record_post_run 05:14:50 INFO - Total resource usage - Wall time: 1303s; CPU: 14.0%; Read bytes: 0; Write bytes: 657858560; Read time: 0; Write time: 11670248 05:14:50 INFO - install - Wall time: 29s; CPU: 27.0%; Read bytes: 0; Write bytes: 202915840; Read time: 0; Write time: 2644096 05:14:50 INFO - run-tests - Wall time: 1275s; CPU: 14.0%; Read bytes: 0; Write bytes: 445702144; Read time: 0; Write time: 8380780 05:14:50 INFO - Running post-run listener: _upload_blobber_files 05:14:50 INFO - Blob upload gear active. 05:14:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:14:50 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 05:14:50 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:14:50 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:14:51 INFO - (blobuploader) - INFO - Open directory for files ... 05:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:51 INFO - (blobuploader) - INFO - Done attempting. 05:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:14:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:53 INFO - (blobuploader) - INFO - Done attempting. 05:14:53 INFO - (blobuploader) - INFO - Iteration through files over. 05:14:53 INFO - Return code: 0 05:14:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:14:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:14:53 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de3761d837edc36dd7f84e6daad4287e591585440e0d2100713fa709aaa6309d9611bcc08c3694f7ed0378c4478690021f9ef6ecf06d204e9953c457f81c6078", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c1854e388dd7cf1161c04d6c00e641df32ad85a2abfdf2688efb06515db5d21c4a1427eb16c54307bc8f73f63b18d15701b077258b40955ea07433d76a91788"} 05:14:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:14:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:14:53 INFO - Contents: 05:14:53 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/de3761d837edc36dd7f84e6daad4287e591585440e0d2100713fa709aaa6309d9611bcc08c3694f7ed0378c4478690021f9ef6ecf06d204e9953c457f81c6078", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c1854e388dd7cf1161c04d6c00e641df32ad85a2abfdf2688efb06515db5d21c4a1427eb16c54307bc8f73f63b18d15701b077258b40955ea07433d76a91788"} 05:14:53 INFO - Running post-run listener: copy_logs_to_upload_dir 05:14:53 INFO - Copying logs to upload dir... 05:14:53 INFO - mkdir: /builds/slave/test/build/upload/logs 05:14:53 INFO - Copying logs to upload dir... 05:14:53 WARNING - returning nonzero exit status 2 program finished with exit code 2 elapsedTime=1353.133393 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 2, elapsed: 22 mins, 33 secs) (at 2016-03-01 05:14:53.145842) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-01 05:14:53.149108) ========= 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-1456835893.453836-1902558187 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/Firefox-Non-PGO/sha512/de3761d837edc36dd7f84e6daad4287e591585440e0d2100713fa709aaa6309d9611bcc08c3694f7ed0378c4478690021f9ef6ecf06d204e9953c457f81c6078", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c1854e388dd7cf1161c04d6c00e641df32ad85a2abfdf2688efb06515db5d21c4a1427eb16c54307bc8f73f63b18d15701b077258b40955ea07433d76a91788"} build_url:https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/artifacts/public/build/firefox-47.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011499 build_url: 'https://queue.taskcluster.net/v1/task/P7yi610CQyujcMmrKMDQZQ/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/Firefox-Non-PGO/sha512/de3761d837edc36dd7f84e6daad4287e591585440e0d2100713fa709aaa6309d9611bcc08c3694f7ed0378c4478690021f9ef6ecf06d204e9953c457f81c6078", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/3c1854e388dd7cf1161c04d6c00e641df32ad85a2abfdf2688efb06515db5d21c4a1427eb16c54307bc8f73f63b18d15701b077258b40955ea07433d76a91788"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-03-01 05:14:53.181407) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 05:14:53.182054) ========= 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-1456835893.453836-1902558187 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004486 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-03-01 05:14:53.266751) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-01 05:14:53.267256) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-03-01 05:14:53.267761) ========= ========= Total master_lag: 0.33 =========