builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-038 starttime: 1487433574.62 results: retry (5) revision: 698de2db1b16a5ef3c6a39f0f72885e69aee4022 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.622909) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.623311) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.623592) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.642050) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.642330) ========= 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=8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-18 07:59:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2017-02-18 07:59:35 (221 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140374 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.867216) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.867489) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016713 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.895874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:34.896202) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 698de2db1b16a5ef3c6a39f0f72885e69aee4022 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 698de2db1b16a5ef3c6a39f0f72885e69aee4022 --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=8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-18 07:59:35,214 truncating revision to first 12 chars 2017-02-18 07:59:35,214 Setting DEBUG logging. 2017-02-18 07:59:35,214 attempt 1/10 2017-02-18 07:59:35,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/698de2db1b16?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-18 07:59:35,736 unpacking tar archive at: mozilla-central-698de2db1b16/testing/mozharness/ program finished with exit code 0 elapsedTime=0.829371 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:35.739448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:35.739805) ========= script_repo_revision: 698de2db1b16a5ef3c6a39f0f72885e69aee4022 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:35.740185) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:35.740451) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-18 07:59:35.752633) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 59 secs) (at 2017-02-18 07:59:35.752977) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:59:36 INFO - MultiFileLogger online at 20170218 07:59:36 in /builds/slave/test 07:59:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 07:59:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:59:36 INFO - {'append_to_log': False, 07:59:36 INFO - 'base_work_dir': '/builds/slave/test', 07:59:36 INFO - 'blob_upload_branch': 'Firefox', 07:59:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:59:36 INFO - 'branch': 'Firefox', 07:59:36 INFO - 'buildbot_json_path': 'buildprops.json', 07:59:36 INFO - 'config_files': ('talos/linux_config.py',), 07:59:36 INFO - 'default_actions': ('clobber', 07:59:36 INFO - 'read-buildbot-config', 07:59:36 INFO - 'download-and-extract', 07:59:36 INFO - 'populate-webroot', 07:59:36 INFO - 'create-virtualenv', 07:59:36 INFO - 'install', 07:59:36 INFO - 'run-tests'), 07:59:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:59:36 INFO - 'download_minidump_stackwalk': True, 07:59:36 INFO - 'download_symbols': 'ondemand', 07:59:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:59:36 INFO - 'tooltool.py': '/tools/tooltool.py', 07:59:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:59:36 INFO - '/tools/misc-python/virtualenv.py')}, 07:59:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:59:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:59:36 INFO - 'installer_path': 'installer.exe', 07:59:36 INFO - 'log_level': 'info', 07:59:36 INFO - 'log_name': 'talos', 07:59:36 INFO - 'log_to_console': True, 07:59:36 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:59:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:59:36 INFO - 'opt_config_files': (), 07:59:36 INFO - 'pip_index': False, 07:59:36 INFO - 'sps_profile': False, 07:59:36 INFO - 'sps_profile_interval': 0, 07:59:36 INFO - 'suite': 'dromaeojs', 07:59:36 INFO - 'system_bits': '32', 07:59:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:59:36 INFO - 'title': 'talos-linux64-ix-038', 07:59:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:59:36 INFO - 'use_talos_json': True, 07:59:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:59:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:59:36 INFO - 'work_dir': 'build'} 07:59:36 INFO - [mozharness: 2017-02-18 15:59:36.168982Z] Running clobber step. 07:59:36 INFO - Running pre-action listener: _resource_record_pre_action 07:59:36 INFO - Running main action method: clobber 07:59:36 INFO - rmtree: /builds/slave/test/build 07:59:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:59:37 INFO - Running post-action listener: _resource_record_post_action 07:59:37 INFO - [mozharness: 2017-02-18 15:59:37.095462Z] Finished clobber step (success) 07:59:37 INFO - [mozharness: 2017-02-18 15:59:37.095562Z] Running read-buildbot-config step. 07:59:37 INFO - Running pre-action listener: _resource_record_pre_action 07:59:37 INFO - Running main action method: read_buildbot_config 07:59:37 INFO - Using buildbot properties: 07:59:37 INFO - { 07:59:37 INFO - "project": "", 07:59:37 INFO - "product": "firefox", 07:59:37 INFO - "who": "ffxbld@noreply.mozilla.org", 07:59:37 INFO - "installer_path": "public/build/target.tar.bz2", 07:59:37 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 07:59:37 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 07:59:37 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 07:59:37 INFO - "stage_platform": "linux64-pgo", 07:59:37 INFO - "basedir": "/builds/slave/test", 07:59:37 INFO - "buildnumber": 41, 07:59:37 INFO - "platform": "ubuntu64_hw", 07:59:37 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 07:59:37 INFO - "slavebuilddir": "test", 07:59:37 INFO - "taskId": "fwYbY-cmTlq_bm7LxrdDJg", 07:59:37 INFO - "branch": "mozilla-central", 07:59:37 INFO - "script_repo_revision": "production", 07:59:37 INFO - "revision": "698de2db1b16a5ef3c6a39f0f72885e69aee4022", 07:59:37 INFO - "slavename": "talos-linux64-ix-038", 07:59:37 INFO - "repo_path": "mozilla-central" 07:59:37 INFO - } 07:59:37 INFO - Finding installer, test and symbols from parent task. 07:59:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fwYbY-cmTlq_bm7LxrdDJg'}, attempt #1 07:59:37 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fwYbY-cmTlq_bm7LxrdDJg'}, attempt #1 07:59:38 INFO - Task dependencies: UjI8cfnjT7qAQ2vu4slNwA 07:59:38 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA'}, attempt #1 07:59:38 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2 07:59:38 INFO - Set test_url: https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/test_packages.json 07:59:38 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.crashreporter-symbols.zip 07:59:38 INFO - Running post-action listener: _resource_record_post_action 07:59:38 INFO - [mozharness: 2017-02-18 15:59:38.492013Z] Finished read-buildbot-config step (success) 07:59:38 INFO - [mozharness: 2017-02-18 15:59:38.492230Z] Running download-and-extract step. 07:59:38 INFO - Running pre-action listener: _resource_record_pre_action 07:59:38 INFO - Running main action method: download_and_extract 07:59:38 INFO - mkdir: /builds/slave/test/build/tests 07:59:38 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:59:38 INFO - trying https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.test_packages.json 07:59:38 INFO - Downloading https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:59:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:59:39 INFO - Downloaded 896 bytes. 07:59:39 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:59:39 INFO - Using the following test package requirements: 07:59:39 INFO - {u'common': [u'target.common.tests.zip'], 07:59:39 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:59:39 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:59:39 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:59:39 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:59:39 INFO - u'mozbase': [u'target.common.tests.zip'], 07:59:39 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:59:39 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:59:39 INFO - u'web-platform': [u'target.common.tests.zip', 07:59:39 INFO - u'target.web-platform.tests.zip'], 07:59:39 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:59:39 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:59:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.common.tests.zip 07:59:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:59:39 INFO - Fetch https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.common.tests.zip into memory 07:59:40 INFO - Expected file size: 59220453 07:59:40 INFO - Obtained file size: 59220453 07:59:49 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:59:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.common.tests.zip 07:59:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:59:49 INFO - Fetch https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.common.tests.zip into memory 07:59:51 INFO - Expected file size: 59220453 07:59:51 INFO - Obtained file size: 59220453 08:00:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.talos.tests.zip 08:00:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.talos.tests.zip'}, attempt #1 08:00:01 INFO - Fetch https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.talos.tests.zip into memory 08:00:02 INFO - Expected file size: 14403647 08:00:02 INFO - Obtained file size: 14403647 08:00: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:02 INFO - trying https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2 08:00:02 INFO - Downloading https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 08:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 08:00:04 INFO - Downloaded 66729210 bytes. 08:00:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2 08:00:04 INFO - mkdir: /builds/slave/test/properties 08:00:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:00:04 INFO - Writing to file /builds/slave/test/properties/build_url 08:00:04 INFO - Contents: 08:00:04 INFO - build_url:https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2 08:00:04 INFO - Running post-action listener: _resource_record_post_action 08:00:04 INFO - Running post-action listener: set_extra_try_arguments 08:00:04 INFO - [mozharness: 2017-02-18 16:00:04.109824Z] Finished download-and-extract step (success) 08:00:04 INFO - [mozharness: 2017-02-18 16:00:04.109921Z] Running populate-webroot step. 08:00:04 INFO - Running pre-action listener: _resource_record_pre_action 08:00:04 INFO - Running main action method: populate_webroot 08:00:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:00:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:00:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:00:04 INFO - '--tppagecycles', 08:00:04 INFO - '7'], 08:00:04 INFO - 'tests': ['tsvgm']}}, 08:00:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:00:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:00:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:00:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:00:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:00:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['damp', 'tps']}, 08:00:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'tests': ['damp', 'tps']}, 08:00:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['dromaeo_dom']}, 08:00:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:00:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['basic_compositor_video']}, 08:00:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:00:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['a11yr', 08:00:04 INFO - 'ts_paint', 08:00:04 INFO - 'tpaint', 08:00:04 INFO - 'sessionrestore', 08:00:04 INFO - 'sessionrestore_no_auto_restore', 08:00:04 INFO - 'tabpaint']}, 08:00:04 INFO - 'other-e10s': {'tests': ['a11yr', 08:00:04 INFO - 'ts_paint', 08:00:04 INFO - 'tpaint', 08:00:04 INFO - 'sessionrestore', 08:00:04 INFO - 'sessionrestore_no_auto_restore', 08:00:04 INFO - 'tabpaint']}, 08:00:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:00:04 INFO - 'ts_paint', 08:00:04 INFO - 'tpaint', 08:00:04 INFO - 'sessionrestore', 08:00:04 INFO - 'sessionrestore_no_auto_restore', 08:00:04 INFO - 'tabpaint']}, 08:00:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:00:04 INFO - 'ts_paint', 08:00:04 INFO - 'tpaint', 08:00:04 INFO - 'sessionrestore', 08:00:04 INFO - 'sessionrestore_no_auto_restore', 08:00:04 INFO - 'tabpaint']}, 08:00:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['a11yr', 08:00:04 INFO - 'ts_paint', 08:00:04 INFO - 'tpaint', 08:00:04 INFO - 'sessionrestore', 08:00:04 INFO - 'sessionrestore_no_auto_restore', 08:00:04 INFO - 'tabpaint']}, 08:00:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['a11yr', 08:00:04 INFO - 'ts_paint', 08:00:04 INFO - 'tpaint', 08:00:04 INFO - 'sessionrestore', 08:00:04 INFO - 'sessionrestore_no_auto_restore', 08:00:04 INFO - 'tabpaint']}, 08:00:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['tsvgx', 08:00:04 INFO - 'tsvgr_opacity', 08:00:04 INFO - 'tart', 08:00:04 INFO - 'tscrollx', 08:00:04 INFO - 'cart', 08:00:04 INFO - 'tsvg_static']}, 08:00:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:00:04 INFO - 'tsvgr_opacity', 08:00:04 INFO - 'tart', 08:00:04 INFO - 'tscrollx', 08:00:04 INFO - 'cart', 08:00:04 INFO - 'tsvg_static']}, 08:00:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'talos_options': ['--disable-e10s'], 08:00:04 INFO - 'tests': ['tp5o']}, 08:00:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:00:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'talos_options': ['--disable-e10s', 08:00:04 INFO - '--xperf_path', 08:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:04 INFO - 'tests': ['tp5n']}, 08:00:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:00:04 INFO - 'talos_options': ['--xperf_path', 08:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:00:04 INFO - 'tests': ['tp5n']}}, 08:00:04 INFO - 'talos.zip': {'path': '', 08:00:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:00:04 INFO - Running post-action listener: _resource_record_post_action 08:00:04 INFO - [mozharness: 2017-02-18 16:00:04.122880Z] Finished populate-webroot step (success) 08:00:04 INFO - [mozharness: 2017-02-18 16:00:04.122972Z] Running create-virtualenv step. 08:00:04 INFO - Running pre-action listener: _resource_record_pre_action 08:00:04 INFO - Running main action method: create_virtualenv 08:00:04 INFO - Creating virtualenv /builds/slave/test/build/venv 08:00:04 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 08:00:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:00:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:00:04 INFO - Using real prefix '/usr' 08:00:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:00:04 INFO - Installing distribute.............................................................................................................................................................................................done. 08:00:06 INFO - Installing pip.................done. 08:00:06 INFO - Return code: 0 08:00:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:00:06 INFO - Reading from file tmpfile_stdout 08:00:06 INFO - Output received: 08:00:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:00:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:06 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 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:06 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 08:00:06 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 08:00:06 INFO - Using env: {'DISPLAY': ':0', 08:00:06 INFO - 'HOME': '/home/cltbld', 08:00:06 INFO - 'LANG': 'en_US.UTF-8', 08:00:06 INFO - 'LANGUAGE': 'en_US:en', 08:00:06 INFO - 'LOGNAME': 'cltbld', 08:00:06 INFO - 'MAIL': '/var/mail/cltbld', 08:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:06 INFO - 'MOZ_NO_REMOTE': '1', 08:00:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:06 INFO - 'NO_EM_RESTART': '1', 08:00:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:06 INFO - 'PWD': '/builds/slave/test', 08:00:06 INFO - 'SHELL': '/bin/bash', 08:00:06 INFO - 'SHLVL': '1', 08:00:06 INFO - 'TERM': 'linux', 08:00:06 INFO - 'TMOUT': '86400', 08:00:06 INFO - 'USER': 'cltbld', 08:00:06 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:06 INFO - '_': '/tools/buildbot/bin/python'} 08:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:07 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 08:00:07 INFO - Cleaning up... 08:00:07 INFO - Return code: 0 08:00:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:00:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:00:07 INFO - Using env: {'DISPLAY': ':0', 08:00:07 INFO - 'HOME': '/home/cltbld', 08:00:07 INFO - 'LANG': 'en_US.UTF-8', 08:00:07 INFO - 'LANGUAGE': 'en_US:en', 08:00:07 INFO - 'LOGNAME': 'cltbld', 08:00:07 INFO - 'MAIL': '/var/mail/cltbld', 08:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:07 INFO - 'MOZ_NO_REMOTE': '1', 08:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:07 INFO - 'NO_EM_RESTART': '1', 08:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:07 INFO - 'PWD': '/builds/slave/test', 08:00:07 INFO - 'SHELL': '/bin/bash', 08:00:07 INFO - 'SHLVL': '1', 08:00:07 INFO - 'TERM': 'linux', 08:00:07 INFO - 'TMOUT': '86400', 08:00:07 INFO - 'USER': 'cltbld', 08:00:07 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:07 INFO - '_': '/tools/buildbot/bin/python'} 08:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:07 INFO - Downloading/unpacking psutil>=3.1.1 08:00:07 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 08:00:07 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 08:00:07 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 08:00:07 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 08:00:07 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 08:00:07 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 08:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:00:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:09 INFO - Installing collected packages: psutil 08:00:09 INFO - Running setup.py install for psutil 08:00:09 INFO - building 'psutil._psutil_linux' extension 08:00:09 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 08:00:09 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 08:00:09 INFO - building 'psutil._psutil_posix' extension 08:00:09 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 08:00:09 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 08:00:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:00:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:00:09 INFO - Successfully installed psutil 08:00:09 INFO - Cleaning up... 08:00:10 INFO - Return code: 0 08:00:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:00:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:10 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:00:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:00:10 INFO - Using env: {'DISPLAY': ':0', 08:00:10 INFO - 'HOME': '/home/cltbld', 08:00:10 INFO - 'LANG': 'en_US.UTF-8', 08:00:10 INFO - 'LANGUAGE': 'en_US:en', 08:00:10 INFO - 'LOGNAME': 'cltbld', 08:00:10 INFO - 'MAIL': '/var/mail/cltbld', 08:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:10 INFO - 'MOZ_NO_REMOTE': '1', 08:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:10 INFO - 'NO_EM_RESTART': '1', 08:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:10 INFO - 'PWD': '/builds/slave/test', 08:00:10 INFO - 'SHELL': '/bin/bash', 08:00:10 INFO - 'SHLVL': '1', 08:00:10 INFO - 'TERM': 'linux', 08:00:10 INFO - 'TMOUT': '86400', 08:00:10 INFO - 'USER': 'cltbld', 08:00:10 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:10 INFO - '_': '/tools/buildbot/bin/python'} 08:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:00:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:00:12 INFO - Installing collected packages: mozsystemmonitor 08:00:12 INFO - Running setup.py install for mozsystemmonitor 08:00:12 INFO - Successfully installed mozsystemmonitor 08:00:12 INFO - Cleaning up... 08:00:12 INFO - Return code: 0 08:00:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:00:12 INFO - Using env: {'DISPLAY': ':0', 08:00:12 INFO - 'HOME': '/home/cltbld', 08:00:12 INFO - 'LANG': 'en_US.UTF-8', 08:00:12 INFO - 'LANGUAGE': 'en_US:en', 08:00:12 INFO - 'LOGNAME': 'cltbld', 08:00:12 INFO - 'MAIL': '/var/mail/cltbld', 08:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:12 INFO - 'MOZ_NO_REMOTE': '1', 08:00:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:12 INFO - 'NO_EM_RESTART': '1', 08:00:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:12 INFO - 'PWD': '/builds/slave/test', 08:00:12 INFO - 'SHELL': '/bin/bash', 08:00:12 INFO - 'SHLVL': '1', 08:00:12 INFO - 'TERM': 'linux', 08:00:12 INFO - 'TMOUT': '86400', 08:00:12 INFO - 'USER': 'cltbld', 08:00:12 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:12 INFO - '_': '/tools/buildbot/bin/python'} 08:00:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:12 INFO - Downloading/unpacking jsonschema==2.5.1 08:00:12 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 08:00:12 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 08:00:12 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 08:00:12 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 08:00:12 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 08:00:12 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 08:00:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:00:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:00:14 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 08:00:14 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 08:00:14 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 08:00:14 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 08:00:14 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 08:00:14 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 08:00:14 INFO - Downloading functools32-3.2.3-2.tar.gz 08:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:00:14 INFO - warning: no files found matching '*.txt' 08:00:14 INFO - no previously-included directories found matching 'build' 08:00:14 INFO - no previously-included directories found matching 'dist' 08:00:14 INFO - no previously-included directories found matching '.git*' 08:00:14 INFO - Installing collected packages: jsonschema, functools32 08:00:15 INFO - Running setup.py install for functools32 08:00:15 INFO - warning: no files found matching '*.txt' 08:00:15 INFO - no previously-included directories found matching 'build' 08:00:15 INFO - no previously-included directories found matching 'dist' 08:00:15 INFO - no previously-included directories found matching '.git*' 08:00:15 INFO - Successfully installed jsonschema functools32 08:00:15 INFO - Cleaning up... 08:00:15 INFO - Return code: 0 08:00:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:00:15 INFO - Using env: {'DISPLAY': ':0', 08:00:15 INFO - 'HOME': '/home/cltbld', 08:00:15 INFO - 'LANG': 'en_US.UTF-8', 08:00:15 INFO - 'LANGUAGE': 'en_US:en', 08:00:15 INFO - 'LOGNAME': 'cltbld', 08:00:15 INFO - 'MAIL': '/var/mail/cltbld', 08:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:15 INFO - 'MOZ_NO_REMOTE': '1', 08:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:15 INFO - 'NO_EM_RESTART': '1', 08:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:15 INFO - 'PWD': '/builds/slave/test', 08:00:15 INFO - 'SHELL': '/bin/bash', 08:00:15 INFO - 'SHLVL': '1', 08:00:15 INFO - 'TERM': 'linux', 08:00:15 INFO - 'TMOUT': '86400', 08:00:15 INFO - 'USER': 'cltbld', 08:00:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:15 INFO - '_': '/tools/buildbot/bin/python'} 08:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:00:15 INFO - Cleaning up... 08:00:15 INFO - Return code: 0 08:00:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:00:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:00:15 INFO - Using env: {'DISPLAY': ':0', 08:00:15 INFO - 'HOME': '/home/cltbld', 08:00:15 INFO - 'LANG': 'en_US.UTF-8', 08:00:15 INFO - 'LANGUAGE': 'en_US:en', 08:00:15 INFO - 'LOGNAME': 'cltbld', 08:00:15 INFO - 'MAIL': '/var/mail/cltbld', 08:00:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:15 INFO - 'MOZ_NO_REMOTE': '1', 08:00:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:15 INFO - 'NO_EM_RESTART': '1', 08:00:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:15 INFO - 'PWD': '/builds/slave/test', 08:00:15 INFO - 'SHELL': '/bin/bash', 08:00:15 INFO - 'SHLVL': '1', 08:00:15 INFO - 'TERM': 'linux', 08:00:15 INFO - 'TMOUT': '86400', 08:00:15 INFO - 'USER': 'cltbld', 08:00:15 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:15 INFO - '_': '/tools/buildbot/bin/python'} 08:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:15 INFO - Downloading/unpacking blobuploader==1.2.4 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:17 INFO - Downloading blobuploader-1.2.4.tar.gz 08:00:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:00:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:00:17 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 08:00:17 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 08:00:17 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 08:00:17 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 08:00:17 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 08:00:17 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 08:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:00:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:00:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:00:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:00:18 INFO - Downloading docopt-0.6.1.tar.gz 08:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:00:18 INFO - Installing collected packages: blobuploader, requests, docopt 08:00:18 INFO - Running setup.py install for blobuploader 08:00:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:00:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:00:18 INFO - Running setup.py install for requests 08:00:19 INFO - Running setup.py install for docopt 08:00:19 INFO - Successfully installed blobuploader requests docopt 08:00:19 INFO - Cleaning up... 08:00:19 INFO - Return code: 0 08:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:19 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 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:19 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 08:00:19 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 08:00:19 INFO - Using env: {'DISPLAY': ':0', 08:00:19 INFO - 'HOME': '/home/cltbld', 08:00:19 INFO - 'LANG': 'en_US.UTF-8', 08:00:19 INFO - 'LANGUAGE': 'en_US:en', 08:00:19 INFO - 'LOGNAME': 'cltbld', 08:00:19 INFO - 'MAIL': '/var/mail/cltbld', 08:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:19 INFO - 'MOZ_NO_REMOTE': '1', 08:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:19 INFO - 'NO_EM_RESTART': '1', 08:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:19 INFO - 'PWD': '/builds/slave/test', 08:00:19 INFO - 'SHELL': '/bin/bash', 08:00:19 INFO - 'SHLVL': '1', 08:00:19 INFO - 'TERM': 'linux', 08:00:19 INFO - 'TMOUT': '86400', 08:00:19 INFO - 'USER': 'cltbld', 08:00:19 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:19 INFO - '_': '/tools/buildbot/bin/python'} 08:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:19 INFO - Running setup.py (path:/tmp/pip-9xXeON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:19 INFO - Running setup.py (path:/tmp/pip-ypnYhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:19 INFO - Running setup.py (path:/tmp/pip-9a2C1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:19 INFO - Running setup.py (path:/tmp/pip-y9sJzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:19 INFO - Running setup.py (path:/tmp/pip-weSXxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:19 INFO - Running setup.py (path:/tmp/pip-lTDor5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:19 INFO - Running setup.py (path:/tmp/pip-c7dlFl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:20 INFO - Running setup.py (path:/tmp/pip-9fD9pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:20 INFO - Running setup.py (path:/tmp/pip-LAA3gY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:20 INFO - Running setup.py (path:/tmp/pip-MZo1DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:20 INFO - Running setup.py (path:/tmp/pip-3JYeyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:20 INFO - Running setup.py (path:/tmp/pip-XQ_cEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:20 INFO - Running setup.py (path:/tmp/pip-ihOEbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:20 INFO - Running setup.py (path:/tmp/pip-o8aH2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:20 INFO - Running setup.py (path:/tmp/pip-EBeC3o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:20 INFO - Running setup.py (path:/tmp/pip-kwQmRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:20 INFO - Running setup.py (path:/tmp/pip-oZ9ROi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:00:20 INFO - Running setup.py install for manifestparser 08:00:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:00:20 INFO - Running setup.py install for mozcrash 08:00:20 INFO - Running setup.py install for mozdebug 08:00:21 INFO - Running setup.py install for mozdevice 08:00:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Running setup.py install for mozfile 08:00:21 INFO - Running setup.py install for mozhttpd 08:00:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Running setup.py install for mozinfo 08:00:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Running setup.py install for mozInstall 08:00:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Running setup.py install for mozleak 08:00:21 INFO - Running setup.py install for mozlog 08:00:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Running setup.py install for moznetwork 08:00:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:00:21 INFO - Running setup.py install for mozprocess 08:00:21 INFO - Running setup.py install for mozprofile 08:00:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:00:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:00:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:00:22 INFO - Running setup.py install for mozrunner 08:00:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:00:22 INFO - Running setup.py install for mozscreenshot 08:00:22 INFO - Running setup.py install for moztest 08:00:22 INFO - Running setup.py install for mozversion 08:00:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:00:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:00:22 INFO - Cleaning up... 08:00:22 INFO - Return code: 0 08:00:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:22 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:22 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:22 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 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:22 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 08:00:22 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 08:00:22 INFO - Using env: {'DISPLAY': ':0', 08:00:22 INFO - 'HOME': '/home/cltbld', 08:00:22 INFO - 'LANG': 'en_US.UTF-8', 08:00:22 INFO - 'LANGUAGE': 'en_US:en', 08:00:22 INFO - 'LOGNAME': 'cltbld', 08:00:22 INFO - 'MAIL': '/var/mail/cltbld', 08:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:22 INFO - 'MOZ_NO_REMOTE': '1', 08:00:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:22 INFO - 'NO_EM_RESTART': '1', 08:00:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:22 INFO - 'PWD': '/builds/slave/test', 08:00:22 INFO - 'SHELL': '/bin/bash', 08:00:22 INFO - 'SHLVL': '1', 08:00:22 INFO - 'TERM': 'linux', 08:00:22 INFO - 'TMOUT': '86400', 08:00:22 INFO - 'USER': 'cltbld', 08:00:22 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:22 INFO - '_': '/tools/buildbot/bin/python'} 08:00:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:00:22 INFO - Running setup.py (path:/tmp/pip-ltqVsw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:00:22 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)) 08:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:00:22 INFO - Running setup.py (path:/tmp/pip-cMBzxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:00:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 08:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:00:22 INFO - Running setup.py (path:/tmp/pip-pUU73c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:00:22 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)) 08:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:00:23 INFO - Running setup.py (path:/tmp/pip-4SvUYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:00:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:00:23 INFO - Running setup.py (path:/tmp/pip-shuMpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:00:23 INFO - Running setup.py (path:/tmp/pip-FhexDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:00:23 INFO - Running setup.py (path:/tmp/pip-OeRyus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:00:23 INFO - Running setup.py (path:/tmp/pip-sQlf6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:00:23 INFO - Running setup.py (path:/tmp/pip-nlOv_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:00:23 INFO - Running setup.py (path:/tmp/pip-QYdurn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:00:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:00:23 INFO - Running setup.py (path:/tmp/pip-9v192b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:00:23 INFO - Running setup.py (path:/tmp/pip-hH2INm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:00:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:00:23 INFO - Running setup.py (path:/tmp/pip-d0D5ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:00:23 INFO - Running setup.py (path:/tmp/pip-XxKsOa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:00:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:23 INFO - Running setup.py (path:/tmp/pip-5LSUhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:00:23 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:00:23 INFO - Running setup.py (path:/tmp/pip-VS17ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:00:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 08:00:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:00:23 INFO - Running setup.py (path:/tmp/pip-6SP1YV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:00:24 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)) 08:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:00:24 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)) 08:00:24 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:24 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:00:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:00:24 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 08:00:24 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 08:00:24 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 08:00:24 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 08:00:24 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 08:00:24 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 08:00:26 INFO - Downloading blessings-1.6.tar.gz 08:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:00:26 INFO - Installing collected packages: blessings 08:00:26 INFO - Running setup.py install for blessings 08:00:26 INFO - Successfully installed blessings 08:00:26 INFO - Cleaning up... 08:00:26 INFO - Return code: 0 08:00:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:00:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:26 INFO - Reading from file tmpfile_stdout 08:00:26 INFO - Current package versions: 08:00:26 INFO - argparse == 1.2.1 08:00:26 INFO - blessings == 1.6 08:00:26 INFO - blobuploader == 1.2.4 08:00:26 INFO - docopt == 0.6.1 08:00:26 INFO - functools32 == 3.2.3-2 08:00:26 INFO - jsonschema == 2.5.1 08:00:26 INFO - manifestparser == 1.1 08:00:26 INFO - mozInstall == 1.12 08:00:26 INFO - mozcrash == 1.0 08:00:26 INFO - mozdebug == 0.1 08:00:26 INFO - mozdevice == 0.49 08:00:26 INFO - mozfile == 1.2 08:00:26 INFO - mozhttpd == 0.7 08:00:26 INFO - mozinfo == 0.9 08:00:26 INFO - mozleak == 0.1 08:00:26 INFO - mozlog == 3.4 08:00:26 INFO - moznetwork == 0.27 08:00:26 INFO - mozprocess == 0.25 08:00:26 INFO - mozprofile == 0.28 08:00:26 INFO - mozrunner == 6.13 08:00:26 INFO - mozscreenshot == 0.1 08:00:26 INFO - mozsystemmonitor == 0.3 08:00:26 INFO - moztest == 0.8 08:00:26 INFO - mozversion == 1.4 08:00:26 INFO - psutil == 3.1.1 08:00:26 INFO - requests == 1.2.3 08:00:26 INFO - wsgiref == 0.1.2 08:00:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:26 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 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:26 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 08:00:26 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 08:00:26 INFO - Using env: {'DISPLAY': ':0', 08:00:26 INFO - 'HOME': '/home/cltbld', 08:00:26 INFO - 'LANG': 'en_US.UTF-8', 08:00:26 INFO - 'LANGUAGE': 'en_US:en', 08:00:26 INFO - 'LOGNAME': 'cltbld', 08:00:26 INFO - 'MAIL': '/var/mail/cltbld', 08:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:26 INFO - 'MOZ_NO_REMOTE': '1', 08:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:26 INFO - 'NO_EM_RESTART': '1', 08:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:26 INFO - 'PWD': '/builds/slave/test', 08:00:26 INFO - 'SHELL': '/bin/bash', 08:00:26 INFO - 'SHLVL': '1', 08:00:26 INFO - 'TERM': 'linux', 08:00:26 INFO - 'TMOUT': '86400', 08:00:26 INFO - 'USER': 'cltbld', 08:00:26 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:26 INFO - '_': '/tools/buildbot/bin/python'} 08:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 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)) 08:00:26 INFO - Cleaning up... 08:00:26 INFO - Return code: 0 08:00:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:00:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x17f71f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18f1a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a97ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, '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': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', '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 08:00:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:00:26 INFO - Using env: {'DISPLAY': ':0', 08:00:26 INFO - 'HOME': '/home/cltbld', 08:00:26 INFO - 'LANG': 'en_US.UTF-8', 08:00:26 INFO - 'LANGUAGE': 'en_US:en', 08:00:26 INFO - 'LOGNAME': 'cltbld', 08:00:26 INFO - 'MAIL': '/var/mail/cltbld', 08:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:26 INFO - 'MOZ_NO_REMOTE': '1', 08:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:26 INFO - 'NO_EM_RESTART': '1', 08:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:26 INFO - 'PWD': '/builds/slave/test', 08:00:26 INFO - 'SHELL': '/bin/bash', 08:00:26 INFO - 'SHLVL': '1', 08:00:26 INFO - 'TERM': 'linux', 08:00:26 INFO - 'TMOUT': '86400', 08:00:26 INFO - 'USER': 'cltbld', 08:00:26 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:26 INFO - '_': '/tools/buildbot/bin/python'} 08:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:00:26 INFO - Cleaning up... 08:00:26 INFO - Return code: 0 08:00:26 INFO - Running post-action listener: _resource_record_post_action 08:00:26 INFO - Running post-action listener: _start_resource_monitoring 08:00:26 INFO - Starting resource monitoring. 08:00:26 INFO - [mozharness: 2017-02-18 16:00:26.989064Z] Finished create-virtualenv step (success) 08:00:26 INFO - [mozharness: 2017-02-18 16:00:26.989574Z] Running install step. 08:00:26 INFO - Running pre-action listener: _resource_record_pre_action 08:00:26 INFO - Running main action method: install 08:00:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:00:27 INFO - Reading from file tmpfile_stdout 08:00:27 INFO - Detecting whether we're running mozinstall >=1.0... 08:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:00:27 INFO - Reading from file tmpfile_stdout 08:00:27 INFO - Output received: 08:00:27 INFO - Usage: mozinstall [options] installer 08:00:27 INFO - Options: 08:00:27 INFO - -h, --help show this help message and exit 08:00:27 INFO - -d DEST, --destination=DEST 08:00:27 INFO - Directory to install application into. [default: 08:00:27 INFO - "/builds/slave/test"] 08:00:27 INFO - --app=APP Application being installed. [default: firefox] 08:00:27 INFO - mkdir: /builds/slave/test/build/application 08:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 08:00:43 INFO - Reading from file tmpfile_stdout 08:00:43 INFO - Output received: 08:00:43 INFO - /builds/slave/test/build/application/firefox/firefox 08:00:43 INFO - Running post-action listener: _resource_record_post_action 08:00:43 INFO - [mozharness: 2017-02-18 16:00:43.916069Z] Finished install step (success) 08:00:43 INFO - [mozharness: 2017-02-18 16:00:43.916345Z] Running run-tests step. 08:00:43 INFO - Running pre-action listener: _resource_record_pre_action 08:00:43 INFO - Running main action method: run_tests 08:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:00:43 INFO - Python 2.7.3 08:00:43 INFO - Return code: 0 08:00:43 INFO - grabbing minidump binary from tooltool 08:00:43 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:00:43 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b21540>, '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 0x1a95f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a96400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:00:43 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 08:00:43 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 08:00:43 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:00:43 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:00:43 INFO - Return code: 0 08:00:43 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:00:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:00:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:00:43 INFO - ENV: RUST_BACKTRACE is now 1 08:00:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:00:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:00:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:00:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-038 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:00:43 INFO - Using env: {'DISPLAY': ':0', 08:00:43 INFO - 'HOME': '/home/cltbld', 08:00:43 INFO - 'LANG': 'en_US.UTF-8', 08:00:43 INFO - 'LANGUAGE': 'en_US:en', 08:00:44 INFO - 'LOGNAME': 'cltbld', 08:00:44 INFO - 'MAIL': '/var/mail/cltbld', 08:00:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:00:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:00:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:00:44 INFO - 'MOZ_NO_REMOTE': '1', 08:00:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:00:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:00:44 INFO - 'NO_EM_RESTART': '1', 08:00:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:00:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:00:44 INFO - 'PWD': '/builds/slave/test', 08:00:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:00:44 INFO - 'RUST_BACKTRACE': '1', 08:00:44 INFO - 'SHELL': '/bin/bash', 08:00:44 INFO - 'SHLVL': '1', 08:00:44 INFO - 'TERM': 'linux', 08:00:44 INFO - 'TMOUT': '86400', 08:00:44 INFO - 'USER': 'cltbld', 08:00:44 INFO - 'XDG_SESSION_COOKIE': '8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022', 08:00:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:00:44 INFO - '_': '/tools/buildbot/bin/python'} 08:00:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-038', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:00:44 INFO - mozversion application_buildid: 20170218150044 08:00:44 INFO - mozversion application_changeset: 698de2db1b16a5ef3c6a39f0f72885e69aee4022 08:00:44 INFO - mozversion application_display_name: Nightly 08:00:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:00:44 INFO - mozversion application_name: Firefox 08:00:44 INFO - mozversion application_remotingname: firefox 08:00:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:00:44 INFO - mozversion application_vendor: Mozilla 08:00:44 INFO - mozversion application_version: 54.0a1 08:00:44 INFO - mozversion platform_buildid: 20170218150044 08:00:44 INFO - mozversion platform_changeset: 698de2db1b16a5ef3c6a39f0f72885e69aee4022 08:00:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:00:44 INFO - mozversion platform_version: 54.0a1 08:00:44 INFO - using testdate: 1487433644 08:00:44 INFO - actual date: 1487433644 08:00:44 INFO - starting webserver on 'localhost:50112' 08:00:44 INFO - SUITE-START | Running 2 tests 08:00:44 INFO - TEST-START | dromaeo_css 08:00:44 INFO - operating with platform_type : linux_ 08:00:44 INFO - Initialising browser for dromaeo_css test... 08:00:44 INFO - TEST-INFO | started process 18419 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzAjlSy/profile http://localhost:50112/getInfo.html) 08:00:47 INFO - PROCESS | 18419 | Iterating over profile /tmp/tmpzAjlSy/profile 08:00:48 INFO - PROCESS | 18419 | __metrics Screen width/height:1600/1200 08:00:48 INFO - PROCESS | 18419 | colorDepth:24 08:00:48 INFO - PROCESS | 18419 | Browser inner width/height: 1024/697 08:00:48 INFO - PROCESS | 18419 | __metrics 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/webappsstore.sqlite = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/extensions.ini = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/safebrowsing = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/cache2 = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/times.json = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/startupCache = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/webappsstore.sqlite-shm = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | Stat failure 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/secmod.db = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/cookies.sqlite = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/places.sqlite-shm = Sat Feb 18 2017 08:00:45 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/addons.json = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/minidumps = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/blocklist.xml = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/thumbnails = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/.parentlock = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/user.js = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/localstore.rdf = Sat Feb 18 2017 08:00:02 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/mimeTypes.rdf = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/content-prefs.sqlite = Sat Feb 18 2017 08:00:46 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/extensions = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/directoryLinks.json = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/pluginreg.dat = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:48 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:48 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/compatibility.ini = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/bookmarkbackups = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/webappsstore.sqlite-wal = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/key3.db = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/prefs.js = Sat Feb 18 2017 08:00:02 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/places.sqlite-wal = Sat Feb 18 2017 08:00:49 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/frequencyCap.json = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/gmp = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/permissions.sqlite = Sat Feb 18 2017 08:00:45 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/extensions.json = Sat Feb 18 2017 08:00:47 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/crashes = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/places.sqlite = Sat Feb 18 2017 08:00:49 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/cert8.db = Sat Feb 18 2017 08:00:44 GMT-0800 (PST) 08:00:49 INFO - PROCESS | 18419 | No birth date. Using mtime. 08:00:49 INFO - PROCESS | 18419 | Using date: /tmp/tmpzAjlSy/profile/sessionCheckpoints.json = Sat Feb 18 2017 08:00:48 GMT-0800 (PST) 08:00:50 INFO - PROCESS | 18419 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:00:50 INFO - PROCESS | 18419 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:00:51 INFO - TEST-INFO | 18419: exit 0 08:00:51 INFO - Browser initialized. 08:00:51 INFO - Running cycle 1/1 for dromaeo_css test... 08:00:51 INFO - TEST-INFO | started process 18510 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzAjlSy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:01:03 INFO - PROCESS | 18510 | RSS: Main: 142680064 08:01:03 INFO - PROCESS | 18510 | 08:04:05 INFO - PROCESS | 18510 | Cycle 1(1): loaded http://localhost:50112/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50112/tests/dromaeo/cssquery-ext.html) 08:04:07 INFO - PROCESS | 18510 | RSS: Main: 315490304 08:04:07 INFO - PROCESS | 18510 | 08:05:52 INFO - PROCESS | 18510 | ************************* 08:05:52 INFO - PROCESS | 18510 | A coding exception was thrown and uncaught in a Task. 08:05:52 INFO - PROCESS | 18510 | 08:05:52 ERROR - PROCESS | 18510 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:05:52 INFO - PROCESS | 18510 | Full stack: 08:05:52 INFO - PROCESS | 18510 | ************************* 08:07:09 INFO - PROCESS | 18510 | Cycle 1(1): loaded http://localhost:50112/tests/dromaeo/cssquery-ext.html (next: http://localhost:50112/tests/dromaeo/cssquery-jquery.html) 08:07:10 INFO - PROCESS | 18510 | RSS: Main: 243802112 08:07:10 INFO - PROCESS | 18510 | 08:10:12 INFO - PROCESS | 18510 | Cycle 1(1): loaded http://localhost:50112/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50112/tests/dromaeo/cssquery-mootools.html) 08:10:12 INFO - PROCESS | 18510 | RSS: Main: 246235136 08:10:12 INFO - PROCESS | 18510 | 08:13:14 INFO - PROCESS | 18510 | Cycle 1(1): loaded http://localhost:50112/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50112/tests/dromaeo/cssquery-prototype.html) 08:13:15 INFO - PROCESS | 18510 | RSS: Main: 194555904 08:13:15 INFO - PROCESS | 18510 | 08:16:18 INFO - PROCESS | 18510 | Cycle 1(1): loaded http://localhost:50112/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50112/tests/dromaeo/cssquery-yui.html) 08:16:19 INFO - PROCESS | 18510 | RSS: Main: 182222848 08:16:19 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | Cycle 1(1): loaded http://localhost:50112/tests/dromaeo/cssquery-yui.html (next: http://localhost:50112/tests/dromaeo/cssquery-dojo.html) 08:19:21 INFO - PROCESS | 18510 | RSS: Main: 279646208 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | __start_tp_report 08:19:21 INFO - PROCESS | 18510 | _x_x_mozilla_page_load 08:19:21 INFO - PROCESS | 18510 | _x_x_mozilla_page_load_details 08:19:21 INFO - PROCESS | 18510 | |i|pagename|runs| 08:19:21 INFO - PROCESS | 18510 | |0;dojo.html;3091.876324947002;3123.8906638267667;3144.937101257975;3156.179393357728;3159.988803582853;5192.273081768551;5230.73846307685;5252.238425428315;5276.155815069595;5303.204519322104;5234.947650523493;5245.4754524547525;5309.150535914255;5328.120860058096;5387;4567.634589232854;4730.550597693214;4878.902421951559;4921.7785199666105;4962.900741985158;4711.128441238365;4736.194846876022;4874.073925954058;4879.902401951958;4939.160342741742;4711.528847115295;4740.383750112481;4772.260299653559;4773.021530586221;4787.329773831649;4744.620430365597;4790.880227994328;4792.257200133985;4794.256890182028;4799.376081109468;4746.738929358884;4835.927461088087;4836.226203807374;4847.854564363075;4858.271259311096;5224.399194092652;5230.660007099526;5231.5291623753665;5263.684178949237;5266.34170728659;5620.156976453523;5634.239377684001;5645.943540564583;5655.4061823508355;5683.488486036235;5421.701806400646;5449.782008719647;5484.287042684426;5544.667319960775;5551.528120109796;5155.639105262596;5193.63644544882;5196.844094677166;5199.53204211619;5203.427622961472;5799.5070419014855;5824.767009319579;5850.268716410449;5924.170616113662;5933.614315069593;5398.568114550826;5457.181422786613;5460.6177567569885;5480.424555421703;5663.065594176915;5574.191742197439;5586.13414920688;5654.886902262014;5692.48767610917;5714;5712.457316554921;5713.914291285634;5716.771329146871;5732.627379220337;5751.338596061507;5672.688002159838;5696.629719068247;5700.258966334433;5712.771489140471;5713.771449142071;4310.568943105727;4344.674149438742;4354.259775838115;4396.230659634552;4438.090191510732;3881.7865017423746;3907.29668659644;3908.081600823804;3923.195744872294;3954.8418063277722;5376.973115134478;5379.435159308295;5383.111786555174;5390.326209223847;5401.270828438111;5480.232767412565;5744.109663002241;5761.654300741885;5764.07774756037;5788.363280039192;5163.715995620202;5200.739963001834;5216.217567364925;5230.529252367305;5232.476752324737;4731.148393289241;4761.40482439695;4772.3557319762085;4775.307580400892;4776.307435421844;5730.025895597759;5774.133879918046;5797.101449275369;5854.765809367577;5859.472647461749;5677.630953987978;5704.885902281848;5714.428557144252;5717.171010203414;5720.399358067343;5918.674472904032;5933.2880054393745;5935.643861368331;5940.198073260054;5949.524038076857;5488.5334746545395;5688.77244910199;5704.657720536781;5708.286464191976;5722.828315150552;5591.8881622368135;5657.434256574475;5668.773249069991;5672.688002159755;5678.176664383559;5545.750441230073;5639.915401269066;5640.238567793295;5694.06048002075;5698.971505142614;5562.2212890196215;5569.582281328998;5575.8884822304135;5579.832605021856;5581.162825576196;5350;5382.542483888913;5383.0041442333295;5530.5022547970875;5627.718614069362;5611;5725.398833122629;5733.397993210653;5736.139579063007;5740.253767010429;5513.117901135799;5532;5534.612577119724;5567.053600887778;5593.944060559343 08:19:21 INFO - PROCESS | 18510 | |1;ext.html;563.4815969308236;582.7494177503672;583.9751236579799;587.2453896742686;604.990925136123;30793.846030769844;42289.78855105724;44605.553944460546;44762.55237447625;49161.75419122908;46178.769106154505;46551;46577.30134047992;46594;46616.30075548861;9076.727698169065;9272.304577156707;9273.675421360254;9278.582463789146;9280.025597312286;7493.550386976772;7671.156172820985;10406.375617462938;10729.624463143791;10736.886302972129;10984.340939543614;11044.668659940215;11045.171612129123;11046.889531104707;11047.447627618627;10935.781284374309;10998.285111467729;11003.724906877313;11009.889901100967;11010.724731881766;10088.798224035478;10152.578638990548;10158.796824063478;10162.949185254027;10165.847512287322;43416.348754768704;43710;43752.56247437549;43763;43766.78116609428;3884.4173373993845;3885.145268041055;3935.822887970048;3951.46655201547;4081.0205550667624;31019.689803101905;31629.841850790828;31669.20826979321;31747.206319841956;33086.6691333086;4117.3206420940505;4284.678649101299;4381.036172042146;4414.713043652152;4419.204543182226;219417.9999999984;220708;221414;222239;222599;53408.7216481446;58272;58464.707676461345;58766.41233587695;59137.70431147903;54577.45422545803;54600;54657.72671136698;54796.17805732917;69375.3062469379;15153.318100685496;15417.460388886559;15884.364625414853;16032.919835400748;16081.758773618405;11129.33224006546;11209.159313051554;11209.159313051554;11220.382878941737;11246.100311975186;17055.147242638068;20427.79572204259;21064.684029739565;21167.576648466944;21232.575348493254;6316.968415157987;7607.771766847003;7730.420218483524;7772.611369431619;7831.334336581341;886.1670030171618;894.4543828264754;899.1188635137602;914.7804526913493;919.4804935211585;30418;31506.36987260242;32013;32055;32201.51697724536;993.2451336983944;1074.940878251688;1078.1536493852288;1081.789051135021;1094.5293523784849;15547.611309717122;16378.836211637969;16568.585785355222;16614.25235864389;16707.49877503677;492092;495533.84856877796;495772;496120;496475;61620.38379616236;61898.69050654718;62189;62327.376726232156;62561.06158407628;1322.636275024376;1353.4180302469836;1366.8018137370225;1371.2800779590596;1372.5950844501097;29653.25866853346;30483.31225600693;30683.693163068085;31004.379912402077;31458.842705786323;51675.483245167066;52287.73856130695;52380.21429678634;52438.47561524488;52506;366111.0333380006;366524;368200;368700;369207.6965761343;41187.794061030705;42017.57982420137;42060;42270.36594451024;42718.359224611035;35379.646203538665;43530.78234608807;45608.31587526256;48985;50312.24531631955;40039.1992160161;40395;40668;41185.38221926733;42532;34890.65109348874;41274.76175714733;41972.16055678808;42179.57820421756;42267.57732422637;36045.27909441849;37662.246755065295;38561.80719096387;38773.61226387813;38980;31719.365612688078;33068.244867763584;34037.82981085079;35228.82385588055;35708.28583428265;25715.35711607225;26042.786363159343;26332.736672633793;26361.472770544864;26570 08:19:21 INFO - PROCESS | 18510 | |2;jquery.html;387.65111399740215;408.7424922298951;429.3688278231001;434.9456317960255;442.23272622000843;13278.402471888765;14487.275636218186;18572.442826715185;19012.239510419568;19690.310839120633;19956.501087472785;20205.696914546294;20482.487937801525;20939.371818845422;21093.57812843746;5784.623999440044;5880.911786323209;5990.460858522732;6080.665563394012;6102.176206212149;3537.416326306169;6194.566380353377;6233.2208473940755;6233.594816336947;6237;3752.9866935927284;3754.0239537720117;3855.3796502246055;6798.762043328485;6800.557963732342;5865;6770;6785.287544807798;6795.38841504267;6807.965960170217;5964.552658550626;6121.9081713773885;6124.142620033154;6130.908036379458;6132.6933653317155;14960.251987400587;15181.468648597356;15246.695066098728;15319.540413787605;15332.080075195412;2617.2802479318148;2643.4845205184997;2644.418227989858;2712.8372297662004;2812.2266376746556;10480.318779279323;10528.42093684847;10761.246712730112;11074.944625276903;11173;1806.042797317424;1860.2234865703356;1862.608852141052;1876.690346092893;1880.6050729346853;79398;86176.99999999937;87259.56370218108;88470.557647212;89746;11500.769984600262;11605.593804216813;11694.47374868133;11719.707007324885;12019.459124339426;17135.400260990806;17137;17137.485875423758;17138.657226855394;17144.571385715208;8147.918520814835;8318.916810831814;8372.958135209406;8392.4544904581;8462.111478294813;7577.318041376192;7598.354139898171;7847.9607601961625;7879.290863822169;7901.60491975399;5101.285819985205;5195.506426889439;5197.7141257231215;5201.245819356172;5290.391604965478;2110.8627939183903;2119.7774233705545;2122.395117391536;2147.3772605944414;2154.2783167638704;831.4886344897965;841.4698739794013;847.0555330806106;847.7326397036347;872.4100327153717;18911.52721181954;19535.609287814164;19541.120649570803;19602.803971960097;19794.307199247956;853.6926706385697;854.3250831842774;860.5654144656908;866.5840396609663;874.1127755328347;10485.161187105014;10727.356358618508;10961.890381096246;11000.889991100146;11076.889231107747;576270;580127;591298;593959;597288;95964.52017740006;97940;99593;102112.48943755234;103120.48439757903;1029.7734498410339;1070.0957690751302;1087.2932593814085;1088.9455527223765;1089.9237053406343;15989.468015079832;16607.50177494677;17006.744898826775;17208.655826883143;17391.913040434716;39003.02492437712;40126;41081.79459102805;42007.789961051225;43061;54890;56248.43751562432;57012;57210.71394643;58484;8867.68963086289;8909.688160914337;8934.553272336487;9029.142231487998;9066.546672666209;11451.885481145082;12814.935925320686;12940.288284144463;12976.610701678965;12988.935055324662;11813.468393922467;11865.881341186823;11916.821247681464;12530.686732831753;12554.246745195318;11151.609693660683;11357.716057098638;11387.145964052563;11698.181127321346;11947.940260298643;11076.612318568812;11076.944615277194;11344.262622929484;11859.16985810985;11900;8766.649334026568;8791.736247912573;8874.733757987271;9073.183413492818;9235.492047937429;9311.115444032806;11263;11459.713507162387;11578.652640420802;11704.239224450383 08:19:21 INFO - PROCESS | 18510 | |3;mootools.html;1414.6711752122008;1444.776059710745;1448.9927550362254;1452.7966084748136;1459.2266098967548;8043.396745244108;8505.829883402328;8519.148085191486;8539.146085391465;8590.570471476432;8114.188581141882;8314.210150035737;8356.2897153742;8384.245417912387;8461.111583283759;2190.550937057907;2223.1107556977177;2225.276785044859;2233;2234.765349638289;2198.736151661803;2228.2758103616306;2230.9776902230933;2237.8209743220586;2248.5615305015526;2186.5408264264524;2199.3841724317135;2211.9225827096056;2221.7111775469166;2228.131028898723;2158.9136434542597;2166.6749987501844;2172.945676358088;2181.8909054547207;2184.6176919039103;2173.174193806359;2193.5503221839645;2216.4126506475795;2217.0909926930044;2221.800037996572;8521.190486903733;8575;8576.271016963565;8583.914160858374;8588.69939552119;4977.552020318189;5088.491150884919;5098.694078355311;5125.615578831603;5130.666506677054;3013.89451369201;3149.2284390324517;3169.0844423130775;3182.6499085100622;3204.7916885402606;1376.3806287170812;1447.323797554211;1450.7823826426015;1465.3845384938352;1467.7945087687626;10001.549930253155;10104.44425556594;10762.085222756075;11519.94240028803;11532.942335288353;4236.733347659049;4380.518143004267;4381.561843815657;4398.274284742982;4412.161689279027;9209.079092090737;9285.25717942563;9291.163795258459;9298.302627302979;9308.813823723487;4231.772785892118;4355.673324500675;4802.927956080662;5124.308218390544;5162.457941916045;4686.437627484724;4736.478987311393;4775.95224047762;4787.688800227974;4791.065742180242;3457.792532448061;3579.6420357964516;3617.0957260684827;3631.963680363215;3667.94498082529;1037.3412882819464;1041.5677493840124;1046.853440518328;1053.9367637941748;1054.5465449856636;715.6064164709389;725.6299287363377;898.5327629632554;904.2359206470522;926.1571969507715;5244.479101060726;5283.181106928432;5440.068775584689;5481.205225242317;5496.7082735557115;962.2638681408728;969.3650658818485;971.3346357744967;972.154225823538;986.62716167617;3330.3339332133187;3442.2599141184764;3453.740969427303;3481.6518348165487;3618.5295911531334;9552.665656702122;9795.167410770164;9844.474106513502;9848.113669769755;9866.210703143732;9562.56968436422;9656.37233579815;9692.22462203022;9703.805923881482;9794.21646268311;7481.418910475986;7767.53394796314;7811.296983271533;7832.334251588565;7886.0536735592095;5275.235090911873;5295.4969277918535;5304.734763261744;5376.408595054462;5445.074337362737;8775.780605484913;8994.640214391351;9254.167124958785;9268.582913768762;9397.436153830518;9166.54167291646;9329.11373419524;9381.296402769683;9394.859077113986;9437;7287.708491660274;7485.812854678677;7541.622918854145;7544.509606875536;7551.320381165721;7743.341815945708;7763.146053934176;7818.765437036898;7826.5695386751995;7834.099078606033;7303.744368947061;7341.265873412616;7380.372668323038;7381.335679788845;7561.7731468056045;6931.6880740367815;7026.683799229149;7031.648417579203;7032.085828842218;7035.437165026888;6135.263768347649;6264.498840092872;6285.874282514415;6287.811365659038;6316.147320111726;4473.373727678063;4568.748718820497;4624.676272661016;4626.375439315784;4627.120847039052;4783.258594917794;4836.081144582518;4840.249761287007;4907.558319751097;5050.469700681376 08:19:21 INFO - PROCESS | 18510 | |4;prototype.html;390.43387088721363;411.91761647670467;412.1591952417066;415.6197079672098;417.04912798818685;4919.778609962551;5121.897562048757;5179.161397703812;5206.713630750307;5266.368035835695;5166.793328266865;5186.7925282988635;5196.168613021917;5203.2016627861685;5210.348706411699;4109.691773117013;4131.566185550519;4146.2329469048245;4170.8540201092965;4189.141226048653;4061.939070913939;4141.875743727693;4194.454720886296;4197.496300443951;4213.410122582841;4095.4403497813864;4097.119119389331;4097.934537020381;4118.023348792582;4134.235166494193;4046.676265898721;4091.0999580092334;4110.958890411088;4111.753294802322;4130.401091841696;3666.2667466506537;3666.7066634669427;3669.7981611011382;3678.44823276508;3684.355237833395;4827.975860120677;5075.619328550337;5097.515736005073;5100.974495127538;5119.641625086246;4711.8350857719815;4720.365318704578;4724;4727.692699974491;4739.905201895977;5482.643628164196;5486.643368181056;5492.642978206404;5497.47773961473;5500.304850623206;3909.257241124177;3986.0832008638176;4003.937971944451;4025.75845449274;4042.696797740152;5427.348718153808;5481.205225242357;5502.449755024506;5658.603897727161;5704.885902281972;4013.337799263147;4040.0003996044293;4042.292598795199;4071.3892916062237;4100.138970816162;3848.4804551385764;3919.627635374635;4112.979435102805;4139.441175441337;4150.398192262105;4448.7108337957925;4474.798634061475;4501.7524036177665;4502.369668246448;4700.2009658358165;5443.265159203536;5453.318335208099;5454.672719636835;5460.535854452337;5485.50630443262;1329.8736620021082;1342.0940864916338;1358.4905660377358;1367.3573420492353;1369.0143096970166;945.8959514453404;957.262907561188;972.5186032913753;972.8540718892223;976.5605477535138;4877.365942427462;5120.385553733501;5153.871153221199;5175.663581867167;5190.2214667800135;5417.864553386118;5429.837104886859;5454.827057301168;5475;5517.917231241535;5038.244263360525;5104.239037532046;5107.463716309809;5122.385313762298;5127.923136141378;2738.3017330580574;2872.8856617157667;2873.3965867167713;2873.8563071846743;2896.029830006929;5493.835184944378;5600.103983362724;5624.465675760796;5632.633878797947;5632.8028519002455;5448.929406825381;5500.844822587222;5512.676957530164;5540.196671482612;5554.611177217636;5364.9076860024625;5553.444655534414;5769.134629805562;5823.534117270693;5831.241938548046;5660.9150862737915;5662.66024038559;5675;5678.261825962599;5682.659040457585;5465.289512363367;5630.718464076698;5632.155176723524;5635.337575415344;5646.35066967304;5520.902057218232;5679;5682.857928551819;5692.203091567267;5772.6536407815665;3984.86052988144;3993.900152496211;4104.281750693676;4109.561653421279;4128.195001974586;5622.971885140548;5624.7187640616985;5626.2967129108865;5629.564461062403;5636.210930469655;5278.208268759563;5416.8104116355735;5483.314585676791;5526.530244929065;5528.557715382679;5420.2140689599;5458.372287187024;5467.917981230365;5469.453054694499;5475.534579560623;5316.122839731401;5487.862803429946;5519.43346343952;5520.889582208413;5561.165825126263;5478.315210598676;5501.614886957875;5501.614886958035;5519.724013799374;5530.585206109638;5372.561560601141;5376.946230537645;5415.106507426388;5415.864603384947;5431.293931788842 08:19:21 INFO - PROCESS | 18510 | |5;yui.html;726.8691635505606;767.5049593012507;845.6828689241535;851.565701492239;863.5897948474474;15845.603859903495;16695.66608667826;16699.415520456787;16742.16289185542;16798.328066877326;14917.32872020759;15061.849381506181;15076.170810605412;15078.849211507883;15087.62280942977;806.2219957740779;808.9959550202256;824.8102936324649;827.7103013945132;839.3746658739217;832.9625166867476;834.9980023971228;835.2482765511028;835.6824406725436;835.8746188071807;879.8064425826308;883.6288758721353;883.6509578716403;884.3102380143466;884.8584226523725;878.1569693094636;878.4290211362602;878.7407714724139;878.9208971192592;878.9428687135315;638.0588631768152;640.2445114764575;640.2860810196644;640.775728495023;640.7981485831948;15929.442469513511;16267.755983660254;16345;16401.261943212583;16653.750193747102;14469.710605787825;14470.493532726305;14655.267236638125;14877.479288224968;14941;11415.258008229439;11566.190366674336;11570.710732231677;11740.706482337924;11937.522499100025;3705.6109108543756;3706.8331925063435;3708.35103856824;3755.267722794056;3757.3612485877466;72937;74055.62972185158;74800.6259968702;75273;76918.23081769167;10132.392056476487;10338.948305258577;10366.585336586451;10510.842337364946;10842.295250808673;22003.11987520513;22078.668819967712;22255.77744222537;22295.331140065824;22549.549009019727;7676.117246516722;7688.730894418669;7733.110692270348;7909.090454597795;7971.40214483916;4208.600182982612;4252.468441444819;4328.588784065509;4337.50118736343;4389.31965545341;3563.0914116900026;3602.1354874830377;3652.36083685354;3656.3784156693428;3656.8171591420323;588.7733222709202;593.6734795862259;603.4418163199111;605.6820169411094;616.4082480818375;574.74136638512;577.96243244189;585.9824205273849;596.2189531713469;616.3220457496718;9115.453072815651;9644.95177524122;9706.90293097074;10472.476376181161;10524.947375263228;748.4611080022376;755.2296657409412;758.2076729817354;760.627292626609;766.8581312457213;4601.3581363903295;4873.763567872807;4950.796895381593;4952.628552858478;5103.693778373309;59403.108953365736;60926;61695;63788;63846.36153638404;62996.68501657462;63096.68451657712;64513;66433.10160080284;66841.33158668448;59373;59749.70125149346;59863;60195;60434;10181.94524171994;10740.785184296114;10746.892531074589;10784.784304313713;10847.13222942147;38181.61818381781;38665.42001870031;39049;39650.60349396584;40253.79873100616;47953.52046479491;49159.50840491549;49467.257991129445;49612;51120.48879511306;12444.626661200178;12492.188007779465;12637.620871373874;12882.742345152858;13404.128731632412;14323.85676143267;14740.336684849422;14748.115113093249;14776.556703298918;15753.44862929792;13227.537036203687;13276.933615331862;13688.93155534216;14111.576652700436;14233.217173055167;12686.492540298654;12704.428300726675;13629.591112266648;14018.579442616738;14066.718665626835;12689.746205076031;13397.306144888578;13718.38267277955;13879.72240555163;14419.927900360432;10518.158547316043;10716.410597417216;10784.946075269574;11085.556577736801;11291;10429.530671119666;10559.52482138321;10690.62582809598;10840.186985976241;10934.343939363664 08:19:21 INFO - PROCESS | 18510 | __end_tp_report 08:19:21 INFO - PROCESS | 18510 | __start_cc_report 08:19:21 INFO - PROCESS | 18510 | _x_x_mozilla_cycle_collect,1807 08:19:21 INFO - PROCESS | 18510 | __end_cc_report 08:19:21 INFO - PROCESS | 18510 | __startTimestamp1487434761921__endTimestamp 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | ------- Summary: start ------- 08:19:21 INFO - PROCESS | 18510 | Number of tests: 6 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | [#0] dojo.html Cycles:165 Average:5264.67 Median:5460.62 stddev:598.90 (11.0%) stddev-sans-first:575.97 08:19:21 INFO - PROCESS | 18510 | Values: 3091.9 3123.9 3144.9 3156.2 3160.0 5192.3 5230.7 5252.2 5276.2 5303.2 5234.9 5245.5 5309.2 5328.1 5387.0 4567.6 4730.6 4878.9 4921.8 4962.9 4711.1 4736.2 4874.1 4879.9 4939.2 4711.5 4740.4 4772.3 4773.0 4787.3 4744.6 4790.9 4792.3 4794.3 4799.4 4746.7 4835.9 4836.2 4847.9 4858.3 5224.4 5230.7 5231.5 5263.7 5266.3 5620.2 5634.2 5645.9 5655.4 5683.5 5421.7 5449.8 5484.3 5544.7 5551.5 5155.6 5193.6 5196.8 5199.5 5203.4 5799.5 5824.8 5850.3 5924.2 5933.6 5398.6 5457.2 5460.6 5480.4 5663.1 5574.2 5586.1 5654.9 5692.5 5714.0 5712.5 5713.9 5716.8 5732.6 5751.3 5672.7 5696.6 5700.3 5712.8 5713.8 4310.6 4344.7 4354.3 4396.2 4438.1 3881.8 3907.3 3908.1 3923.2 3954.8 5377.0 5379.4 5383.1 5390.3 5401.3 5480.2 5744.1 5761.7 5764.1 5788.4 5163.7 5200.7 5216.2 5230.5 5232.5 4731.1 4761.4 4772.4 4775.3 4776.3 5730.0 5774.1 5797.1 5854.8 5859.5 5677.6 5704.9 5714.4 5717.2 5720.4 5918.7 5933.3 5935.6 5940.2 5949.5 5488.5 5688.8 5704.7 5708.3 5722.8 5591.9 5657.4 5668.8 5672.7 5678.2 5545.8 5639.9 5640.2 5694.1 5699.0 5562.2 5569.6 5575.9 5579.8 5581.2 5350.0 5382.5 5383.0 5530.5 5627.7 5611.0 5725.4 5733.4 5736.1 5740.3 5513.1 5532.0 5534.6 5567.1 5593.9 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | [#1] ext.html Cycles:180 Average:53911.61 Median:21432.57 stddev:100139.50 (467.2%) stddev-sans-first:100340.31 08:19:21 INFO - PROCESS | 18510 | Values: 563.5 582.7 584.0 587.2 605.0 30793.8 42289.8 44605.6 44762.6 49161.8 46178.8 46551.0 46577.3 46594.0 46616.3 9076.7 9272.3 9273.7 9278.6 9280.0 7493.6 7671.2 10406.4 10729.6 10736.9 10984.3 11044.7 11045.2 11046.9 11047.4 10935.8 10998.3 11003.7 11009.9 11010.7 10088.8 10152.6 10158.8 10162.9 10165.8 43416.3 43710.0 43752.6 43763.0 43766.8 3884.4 3885.1 3935.8 3951.5 4081.0 31019.7 31629.8 31669.2 31747.2 33086.7 4117.3 4284.7 4381.0 4414.7 4419.2 219418.0 220708.0 221414.0 222239.0 222599.0 53408.7 58272.0 58464.7 58766.4 59137.7 54577.5 54600.0 54657.7 54796.2 69375.3 15153.3 15417.5 15884.4 16032.9 16081.8 11129.3 11209.2 11209.2 11220.4 11246.1 17055.1 20427.8 21064.7 21167.6 21232.6 6317.0 7607.8 7730.4 7772.6 7831.3 886.2 894.5 899.1 914.8 919.5 30418.0 31506.4 32013.0 32055.0 32201.5 993.2 1074.9 1078.2 1081.8 1094.5 15547.6 16378.8 16568.6 16614.3 16707.5 492092.0 495533.8 495772.0 496120.0 496475.0 61620.4 61898.7 62189.0 62327.4 62561.1 1322.6 1353.4 1366.8 1371.3 1372.6 29653.3 30483.3 30683.7 31004.4 31458.8 51675.5 52287.7 52380.2 52438.5 52506.0 366111.0 366524.0 368200.0 368700.0 369207.7 41187.8 42017.6 42060.0 42270.4 42718.4 35379.6 43530.8 45608.3 48985.0 50312.2 40039.2 40395.0 40668.0 41185.4 42532.0 34890.7 41274.8 41972.2 42179.6 42267.6 36045.3 37662.2 38561.8 38773.6 38980.0 31719.4 33068.2 34037.8 35228.8 35708.3 25715.4 26042.8 26332.7 26361.5 26570.0 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | [#2] jquery.html Cycles:180 Average:31899.04 Median:3174.82 stddev:96695.07 (3045.7%) stddev-sans-first:96937.37 08:19:21 INFO - PROCESS | 18510 | Values: 387.7 408.7 429.4 434.9 442.2 13278.4 14487.3 18572.4 19012.2 19690.3 19956.5 20205.7 20482.5 20939.4 21093.6 5784.6 5880.9 5990.5 6080.7 6102.2 3537.4 6194.6 6233.2 6233.6 6237.0 3753.0 3754.0 3855.4 6798.8 6800.6 5865.0 6770.0 6785.3 6795.4 6808.0 5964.6 6121.9 6124.1 6130.9 6132.7 14960.3 15181.5 15246.7 15319.5 15332.1 2617.3 2643.5 2644.4 2712.8 2812.2 10480.3 10528.4 10761.2 11074.9 11173.0 1806.0 1860.2 1862.6 1876.7 1880.6 79398.0 86177.0 87259.6 88470.6 89746.0 11500.8 11605.6 11694.5 11719.7 12019.5 17135.4 17137.0 17137.5 17138.7 17144.6 8147.9 8318.9 8373.0 8392.5 8462.1 7577.3 7598.4 7848.0 7879.3 7901.6 5101.3 5195.5 5197.7 5201.2 5290.4 2110.9 2119.8 2122.4 2147.4 2154.3 831.5 841.5 847.1 847.7 872.4 18911.5 19535.6 19541.1 19602.8 19794.3 853.7 854.3 860.6 866.6 874.1 10485.2 10727.4 10961.9 11000.9 11076.9 576270.0 580127.0 591298.0 593959.0 597288.0 95964.5 97940.0 99593.0 102112.5 103120.5 1029.8 1070.1 1087.3 1088.9 1089.9 15989.5 16607.5 17006.7 17208.7 17391.9 39003.0 40126.0 41081.8 42007.8 43061.0 54890.0 56248.4 57012.0 57210.7 58484.0 8867.7 8909.7 8934.6 9029.1 9066.5 11451.9 12814.9 12940.3 12976.6 12988.9 11813.5 11865.9 11916.8 12530.7 12554.2 11151.6 11357.7 11387.1 11698.2 11947.9 11076.6 11076.9 11344.3 11859.2 11900.0 8766.6 8791.7 8874.7 9073.2 9235.5 9311.1 11263.0 11459.7 11578.7 11704.2 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | [#3] mootools.html Cycles:180 Average:5365.22 Median:5259.86 stddev:2986.94 (56.8%) stddev-sans-first:2980.57 08:19:21 INFO - PROCESS | 18510 | Values: 1414.7 1444.8 1449.0 1452.8 1459.2 8043.4 8505.8 8519.1 8539.1 8590.6 8114.2 8314.2 8356.3 8384.2 8461.1 2190.6 2223.1 2225.3 2233.0 2234.8 2198.7 2228.3 2231.0 2237.8 2248.6 2186.5 2199.4 2211.9 2221.7 2228.1 2158.9 2166.7 2172.9 2181.9 2184.6 2173.2 2193.6 2216.4 2217.1 2221.8 8521.2 8575.0 8576.3 8583.9 8588.7 4977.6 5088.5 5098.7 5125.6 5130.7 3013.9 3149.2 3169.1 3182.6 3204.8 1376.4 1447.3 1450.8 1465.4 1467.8 10001.5 10104.4 10762.1 11519.9 11532.9 4236.7 4380.5 4381.6 4398.3 4412.2 9209.1 9285.3 9291.2 9298.3 9308.8 4231.8 4355.7 4802.9 5124.3 5162.5 4686.4 4736.5 4776.0 4787.7 4791.1 3457.8 3579.6 3617.1 3632.0 3667.9 1037.3 1041.6 1046.9 1053.9 1054.5 715.6 725.6 898.5 904.2 926.2 5244.5 5283.2 5440.1 5481.2 5496.7 962.3 969.4 971.3 972.2 986.6 3330.3 3442.3 3453.7 3481.7 3618.5 9552.7 9795.2 9844.5 9848.1 9866.2 9562.6 9656.4 9692.2 9703.8 9794.2 7481.4 7767.5 7811.3 7832.3 7886.1 5275.2 5295.5 5304.7 5376.4 5445.1 8775.8 8994.6 9254.2 9268.6 9397.4 9166.5 9329.1 9381.3 9394.9 9437.0 7287.7 7485.8 7541.6 7544.5 7551.3 7743.3 7763.1 7818.8 7826.6 7834.1 7303.7 7341.3 7380.4 7381.3 7561.8 6931.7 7026.7 7031.6 7032.1 7035.4 6135.3 6264.5 6285.9 6287.8 6316.1 4473.4 4568.7 4624.7 4626.4 4627.1 4783.3 4836.1 4840.2 4907.6 5050.5 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | [#4] prototype.html Cycles:180 Average:4593.14 Median:5199.69 stddev:1327.77 (25.5%) stddev-sans-first:1293.49 08:19:21 INFO - PROCESS | 18510 | Values: 390.4 411.9 412.2 415.6 417.0 4919.8 5121.9 5179.2 5206.7 5266.4 5166.8 5186.8 5196.2 5203.2 5210.3 4109.7 4131.6 4146.2 4170.9 4189.1 4061.9 4141.9 4194.5 4197.5 4213.4 4095.4 4097.1 4097.9 4118.0 4134.2 4046.7 4091.1 4111.0 4111.8 4130.4 3666.3 3666.7 3669.8 3678.4 3684.4 4828.0 5075.6 5097.5 5101.0 5119.6 4711.8 4720.4 4724.0 4727.7 4739.9 5482.6 5486.6 5492.6 5497.5 5500.3 3909.3 3986.1 4003.9 4025.8 4042.7 5427.3 5481.2 5502.4 5658.6 5704.9 4013.3 4040.0 4042.3 4071.4 4100.1 3848.5 3919.6 4113.0 4139.4 4150.4 4448.7 4474.8 4501.8 4502.4 4700.2 5443.3 5453.3 5454.7 5460.5 5485.5 1329.9 1342.1 1358.5 1367.4 1369.0 945.9 957.3 972.5 972.9 976.6 4877.4 5120.4 5153.9 5175.7 5190.2 5417.9 5429.8 5454.8 5475.0 5517.9 5038.2 5104.2 5107.5 5122.4 5127.9 2738.3 2872.9 2873.4 2873.9 2896.0 5493.8 5600.1 5624.5 5632.6 5632.8 5448.9 5500.8 5512.7 5540.2 5554.6 5364.9 5553.4 5769.1 5823.5 5831.2 5660.9 5662.7 5675.0 5678.3 5682.7 5465.3 5630.7 5632.2 5635.3 5646.4 5520.9 5679.0 5682.9 5692.2 5772.7 3984.9 3993.9 4104.3 4109.6 4128.2 5623.0 5624.7 5626.3 5629.6 5636.2 5278.2 5416.8 5483.3 5526.5 5528.6 5420.2 5458.4 5467.9 5469.5 5475.5 5316.1 5487.9 5519.4 5520.9 5561.2 5478.3 5501.6 5501.6 5519.7 5530.6 5372.6 5376.9 5415.1 5415.9 5431.3 08:19:21 INFO - PROCESS | 18510 | 08:19:21 INFO - PROCESS | 18510 | [#5] yui.html Cycles:180 Average:16629.31 Median:39952.20 stddev:20119.48 (50.4%) stddev-sans-first:20140.48 08:19:21 INFO - PROCESS | 18510 | Values: 726.9 767.5 845.7 851.6 863.6 15845.6 16695.7 16699.4 16742.2 16798.3 14917.3 15061.8 15076.2 15078.8 15087.6 806.2 809.0 824.8 827.7 839.4 833.0 835.0 835.2 835.7 835.9 879.8 883.6 883.7 884.3 884.9 878.2 878.4 878.7 878.9 878.9 638.1 640.2 640.3 640.8 640.8 15929.4 16267.8 16345.0 16401.3 16653.8 14469.7 14470.5 14655.3 14877.5 14941.0 11415.3 11566.2 11570.7 11740.7 11937.5 3705.6 3706.8 3708.4 3755.3 3757.4 72937.0 74055.6 74800.6 75273.0 76918.2 10132.4 10338.9 10366.6 10510.8 10842.3 22003.1 22078.7 22255.8 22295.3 22549.5 7676.1 7688.7 7733.1 7909.1 7971.4 4208.6 4252.5 4328.6 4337.5 4389.3 3563.1 3602.1 3652.4 3656.4 3656.8 588.8 593.7 603.4 605.7 616.4 574.7 578.0 586.0 596.2 616.3 9115.5 9645.0 9706.9 10472.5 10524.9 748.5 755.2 758.2 760.6 766.9 4601.4 4873.8 4950.8 4952.6 5103.7 59403.1 60926.0 61695.0 63788.0 63846.4 62996.7 63096.7 64513.0 66433.1 66841.3 59373.0 59749.7 59863.0 60195.0 60434.0 10181.9 10740.8 10746.9 10784.8 10847.1 38181.6 38665.4 39049.0 39650.6 40253.8 47953.5 49159.5 49467.3 49612.0 51120.5 12444.6 12492.2 12637.6 12882.7 13404.1 14323.9 14740.3 14748.1 14776.6 15753.4 13227.5 13276.9 13688.9 14111.6 14233.2 12686.5 12704.4 13629.6 14018.6 14066.7 12689.7 13397.3 13718.4 13879.7 14419.9 10518.2 10716.4 10784.9 11085.6 11291.0 10429.5 10559.5 10690.6 10840.2 10934.3 08:19:21 INFO - PROCESS | 18510 | -------- Summary: end -------- 08:19:21 INFO - PROCESS | 18510 | 08:19:22 INFO - TEST-INFO | 18510: exit 0 08:19:22 INFO - TEST-OK | dromaeo_css | took 1118642ms 08:19:22 INFO - TEST-START | kraken 08:19:22 INFO - operating with platform_type : linux_ 08:19:22 INFO - Initialising browser for kraken test... 08:19:22 INFO - TEST-INFO | started process 18835 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXfxj9x/profile http://localhost:50112/getInfo.html) 08:19:26 INFO - PROCESS | 18835 | Iterating over profile /tmp/tmpXfxj9x/profile 08:19:27 INFO - PROCESS | 18835 | __metrics Screen width/height:1600/1200 08:19:27 INFO - PROCESS | 18835 | colorDepth:24 08:19:27 INFO - PROCESS | 18835 | Browser inner width/height: 1024/697 08:19:27 INFO - PROCESS | 18835 | __metrics 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/webappsstore.sqlite = Sat Feb 18 2017 08:19:26 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/extensions.ini = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/safebrowsing = Sat Feb 18 2017 08:19:27 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/cache2 = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/startupCache = Sat Feb 18 2017 08:19:22 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | Stat failure 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/secmod.db = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/cookies.sqlite = Sat Feb 18 2017 08:19:26 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/places.sqlite-shm = Sat Feb 18 2017 08:19:24 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/addons.json = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/minidumps = Sat Feb 18 2017 08:19:22 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/blocklist.xml = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/thumbnails = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/.parentlock = Sat Feb 18 2017 08:19:22 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/user.js = Sat Feb 18 2017 08:19:22 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/localstore.rdf = Sat Feb 18 2017 08:00:02 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/mimeTypes.rdf = Sat Feb 18 2017 08:19:26 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/content-prefs.sqlite = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/extensions = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/directoryLinks.json = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | Stat failure 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/pluginreg.dat = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/compatibility.ini = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/bookmarkbackups = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/key3.db = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/prefs.js = Sat Feb 18 2017 08:00:02 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/places.sqlite-wal = Sat Feb 18 2017 08:19:27 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/frequencyCap.json = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/gmp = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/permissions.sqlite = Sat Feb 18 2017 08:19:24 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/extensions.json = Sat Feb 18 2017 08:19:25 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/crashes = Sat Feb 18 2017 08:19:22 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/places.sqlite = Sat Feb 18 2017 08:19:27 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/cert8.db = Sat Feb 18 2017 08:19:23 GMT-0800 (PST) 08:19:27 INFO - PROCESS | 18835 | No birth date. Using mtime. 08:19:27 INFO - PROCESS | 18835 | Using date: /tmp/tmpXfxj9x/profile/sessionCheckpoints.json = Sat Feb 18 2017 08:19:27 GMT-0800 (PST) 08:19:28 INFO - PROCESS | 18835 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:19:28 INFO - PROCESS | 18835 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:19:29 INFO - TEST-INFO | 18835: exit 0 08:19:29 INFO - Browser initialized. 08:19:29 INFO - Running cycle 1/1 for kraken test... 08:19:29 INFO - TEST-INFO | started process 18927 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpXfxj9x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:19:29 CRITICAL - PROCESS | 18927 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 08:19:29 INFO - Terminating psutil.Process(pid=18927, name='firefox') 08:19:29 INFO - TEST-INFO | 18927: exit 127 08:19:29 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 08:19:29 ERROR - Traceback (most recent call last): 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 08:19:29 INFO - talos_results.add(mytest.runTest(browser_config, test)) 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 08:19:29 INFO - return self._runTest(browser_config, test_config, setup) 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 08:19:29 INFO - else None) 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 08:19:29 INFO - global_counters=self.global_counters 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 08:19:29 INFO - self.parse() 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 08:19:29 INFO - % self.report_tokens) 08:19:29 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 08:19:29 INFO - raise utils.TalosError(message) 08:19:29 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 08:19:29 INFO - TEST-INFO took 6971ms 08:19:29 INFO - SUITE-END | took 1125s 08:19:30 ERROR - Return code: 2 08:19:30 WARNING - setting return code to 2 08:19:30 CRITICAL - # TBPL RETRY # 08:19:30 WARNING - setting return code to 4 08:19:30 INFO - Running post-action listener: _resource_record_post_action 08:19:30 INFO - [mozharness: 2017-02-18 16:19:30.130269Z] Finished run-tests step (success) 08:19:30 INFO - Running post-run listener: _resource_record_post_run 08:19:30 INFO - Total resource usage - Wall time: 1143s; CPU: 13.0%; Read bytes: 4096; Write bytes: 316477440; Read time: 12; Write time: 776908 08:19:30 INFO - TinderboxPrint: CPU usage
13.2% 08:19:30 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 08:19:30 INFO - TinderboxPrint: I/O write bytes / time
316,477,440 / 776,908 08:19:30 INFO - TinderboxPrint: CPU idle
7,915.2 (86.8%) 08:19:30 INFO - TinderboxPrint: CPU user
1,162.8 (12.7%) 08:19:30 INFO - TinderboxPrint: Swap in / out
0 / 0 08:19:30 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110542848; Read time: 0; Write time: 285260 08:19:30 INFO - run-tests - Wall time: 1126s; CPU: 13.0%; Read bytes: 4096; Write bytes: 205545472; Read time: 12; Write time: 491648 08:19:30 INFO - Running post-run listener: _upload_blobber_files 08:19:30 INFO - Blob upload gear active. 08:19:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:19:30 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 08:19:30 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:19:30 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:19:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:19:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:19:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:19:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:19:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:19:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:19:31 INFO - (blobuploader) - INFO - Done attempting. 08:19:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:19:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:19:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:19:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:19:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:19:34 INFO - (blobuploader) - INFO - Done attempting. 08:19:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:19:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:19:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:19:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:19:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:19:35 INFO - (blobuploader) - INFO - Done attempting. 08:19:35 INFO - (blobuploader) - INFO - Iteration through files over. 08:19:35 INFO - Return code: 0 08:19:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:19:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:19:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8be33c557f470aaaa21ae78a5f31a018c9402b1cc8d20b37f0de07aef09938e4afcf99fa603396d6b258b348882dc3127d33dfe7531664d2ef19553805631ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751d831de46abaeb6a42fce6f7437fbe3117bb17f9d144e5f33d192e69ecc4d281326bb93669456010cfe8ecb8c9b21fafa61a95b245da441124ebf0b8f29684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f3c92249da46bc627a466a847e13afef8a91031be0ebcfe9becc1d18625d0c5119c8068bcc9b22d8dd85016471ed27cfa8c6ac8e1f3c3cf5e2f2837004e766"} 08:19:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:19:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:19:35 INFO - Contents: 08:19:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8be33c557f470aaaa21ae78a5f31a018c9402b1cc8d20b37f0de07aef09938e4afcf99fa603396d6b258b348882dc3127d33dfe7531664d2ef19553805631ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751d831de46abaeb6a42fce6f7437fbe3117bb17f9d144e5f33d192e69ecc4d281326bb93669456010cfe8ecb8c9b21fafa61a95b245da441124ebf0b8f29684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f3c92249da46bc627a466a847e13afef8a91031be0ebcfe9becc1d18625d0c5119c8068bcc9b22d8dd85016471ed27cfa8c6ac8e1f3c3cf5e2f2837004e766"} 08:19:35 INFO - Running post-run listener: copy_logs_to_upload_dir 08:19:35 INFO - Copying logs to upload dir... 08:19:35 INFO - mkdir: /builds/slave/test/build/upload/logs 08:19:35 INFO - Copying logs to upload dir... 08:19:35 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1199.599441 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 59 secs) (at 2017-02-18 08:19:35.373700) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-18 08:19:35.377099) ========= 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=8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8be33c557f470aaaa21ae78a5f31a018c9402b1cc8d20b37f0de07aef09938e4afcf99fa603396d6b258b348882dc3127d33dfe7531664d2ef19553805631ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751d831de46abaeb6a42fce6f7437fbe3117bb17f9d144e5f33d192e69ecc4d281326bb93669456010cfe8ecb8c9b21fafa61a95b245da441124ebf0b8f29684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f3c92249da46bc627a466a847e13afef8a91031be0ebcfe9becc1d18625d0c5119c8068bcc9b22d8dd85016471ed27cfa8c6ac8e1f3c3cf5e2f2837004e766"} build_url:https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011315 build_url: 'https://queue.taskcluster.net/v1/task/UjI8cfnjT7qAQ2vu4slNwA/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c8be33c557f470aaaa21ae78a5f31a018c9402b1cc8d20b37f0de07aef09938e4afcf99fa603396d6b258b348882dc3127d33dfe7531664d2ef19553805631ba", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/751d831de46abaeb6a42fce6f7437fbe3117bb17f9d144e5f33d192e69ecc4d281326bb93669456010cfe8ecb8c9b21fafa61a95b245da441124ebf0b8f29684", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/40f3c92249da46bc627a466a847e13afef8a91031be0ebcfe9becc1d18625d0c5119c8068bcc9b22d8dd85016471ed27cfa8c6ac8e1f3c3cf5e2f2837004e766"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-18 08:19:35.402683) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 08:19:35.403027) ========= 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=8783db0840cc980e80bb0bd400000236-1487432645.696365-1583001022 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004360 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-18 08:19:35.487826) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-18 08:19:35.488141) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-18 08:19:35.488518) ========= ========= Total master_lag: 0.23 =========