builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-036 starttime: 1487790432.64 results: retry (5) buildid: 20170222091722 builduid: 95f5430a21df4d6e993c71b9d40613bd revision: 10bce9625147fe39903884dfe20c04eb7f899a98 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.637640) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.638602) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.639236) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.657783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.658641) ========= 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=14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-22 11:07:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-02-22 11:07:12 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106679 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.784106) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.784811) ========= 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=14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020870 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.835243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:12.835620) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 10bce9625147fe39903884dfe20c04eb7f899a98 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 10bce9625147fe39903884dfe20c04eb7f899a98 --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=14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-22 11:07:12,839 truncating revision to first 12 chars 2017-02-22 11:07:12,839 Setting DEBUG logging. 2017-02-22 11:07:12,839 attempt 1/10 2017-02-22 11:07:12,839 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/10bce9625147?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-22 11:07:13,293 unpacking tar archive at: mozilla-beta-10bce9625147/testing/mozharness/ program finished with exit code 0 elapsedTime=0.753617 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:13.602054) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:13.602425) ========= script_repo_revision: 10bce9625147fe39903884dfe20c04eb7f899a98 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:13.602876) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:13.603202) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-22 11:07:13.611300) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 31 secs) (at 2017-02-22 11:07:13.611621) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:07:13 INFO - MultiFileLogger online at 20170222 11:07:13 in /builds/slave/test 11:07:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:07:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:07:13 INFO - {'append_to_log': False, 11:07:13 INFO - 'base_work_dir': '/builds/slave/test', 11:07:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:07:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:07:13 INFO - 'branch': 'Mozilla-Beta', 11:07:13 INFO - 'buildbot_json_path': 'buildprops.json', 11:07:13 INFO - 'config_files': ('talos/linux_config.py',), 11:07:13 INFO - 'default_actions': ('clobber', 11:07:13 INFO - 'read-buildbot-config', 11:07:13 INFO - 'download-and-extract', 11:07:13 INFO - 'populate-webroot', 11:07:13 INFO - 'create-virtualenv', 11:07:13 INFO - 'install', 11:07:13 INFO - 'run-tests'), 11:07:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:07:13 INFO - 'download_minidump_stackwalk': True, 11:07:13 INFO - 'download_symbols': 'ondemand', 11:07:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:07:13 INFO - 'tooltool.py': '/tools/tooltool.py', 11:07:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:07:13 INFO - '/tools/misc-python/virtualenv.py')}, 11:07:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:07:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:07:13 INFO - 'installer_path': 'installer.exe', 11:07:13 INFO - 'log_level': 'info', 11:07:13 INFO - 'log_name': 'talos', 11:07:13 INFO - 'log_to_console': True, 11:07:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:07:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:07:13 INFO - 'opt_config_files': (), 11:07:13 INFO - 'pip_index': False, 11:07:13 INFO - 'sps_profile': False, 11:07:13 INFO - 'sps_profile_interval': 0, 11:07:13 INFO - 'suite': 'dromaeojs-e10s', 11:07:13 INFO - 'system_bits': '32', 11:07:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:07:13 INFO - 'title': 'talos-linux64-ix-036', 11:07:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:07:13 INFO - 'use_talos_json': True, 11:07:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:07:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:07:13 INFO - 'work_dir': 'build'} 11:07:13 INFO - [mozharness: 2017-02-22 19:07:13.711188Z] Running clobber step. 11:07:13 INFO - Running pre-action listener: _resource_record_pre_action 11:07:13 INFO - Running main action method: clobber 11:07:13 INFO - rmtree: /builds/slave/test/build 11:07:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:07:14 INFO - Running post-action listener: _resource_record_post_action 11:07:14 INFO - [mozharness: 2017-02-22 19:07:14.613799Z] Finished clobber step (success) 11:07:14 INFO - [mozharness: 2017-02-22 19:07:14.613898Z] Running read-buildbot-config step. 11:07:14 INFO - Running pre-action listener: _resource_record_pre_action 11:07:14 INFO - Running main action method: read_buildbot_config 11:07:14 INFO - Using buildbot properties: 11:07:14 INFO - { 11:07:14 INFO - "project": "", 11:07:14 INFO - "product": "firefox", 11:07:14 INFO - "script_repo_revision": "production", 11:07:14 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 11:07:14 INFO - "repository": "", 11:07:14 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 11:07:14 INFO - "buildid": "20170222091722", 11:07:14 INFO - "pgo_build": "True", 11:07:14 INFO - "basedir": "/builds/slave/test", 11:07:14 INFO - "buildnumber": 413, 11:07:14 INFO - "slavename": "talos-linux64-ix-036", 11:07:14 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 11:07:14 INFO - "platform": "ubuntu64_hw", 11:07:14 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 11:07:14 INFO - "branch": "mozilla-beta", 11:07:14 INFO - "repo_path": "releases/mozilla-beta", 11:07:14 INFO - "revision": "10bce9625147fe39903884dfe20c04eb7f899a98", 11:07:14 INFO - "stage_platform": "linux64-pgo", 11:07:14 INFO - "builduid": "95f5430a21df4d6e993c71b9d40613bd", 11:07:14 INFO - "slavebuilddir": "test" 11:07:14 INFO - } 11:07:14 INFO - Found installer url https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 11:07:14 INFO - Running post-action listener: _resource_record_post_action 11:07:14 INFO - [mozharness: 2017-02-22 19:07:14.616288Z] Finished read-buildbot-config step (success) 11:07:14 INFO - [mozharness: 2017-02-22 19:07:14.616376Z] Running download-and-extract step. 11:07:14 INFO - Running pre-action listener: _resource_record_pre_action 11:07:14 INFO - Running main action method: download_and_extract 11:07:14 INFO - mkdir: /builds/slave/test/build/tests 11:07:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:14 INFO - trying https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 11:07:14 INFO - Downloading https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 11:07:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:07:15 INFO - Downloaded 1327 bytes. 11:07:15 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 11:07:15 INFO - Using the following test package requirements: 11:07:15 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 11:07:15 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 11:07:15 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 11:07:15 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'jsshell-linux-x86_64.zip'], 11:07:15 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 11:07:15 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 11:07:15 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 11:07:15 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 11:07:15 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 11:07:15 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 11:07:15 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 11:07:15 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:07:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 11:07:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:07:15 INFO - Fetch https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 11:07:15 INFO - Http code: 200 11:07:15 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 11:07:15 INFO - x-amz-cf-id: jHOtMpQWk5l85GgvU2KNWQVYlnj2U0QQy0ID0VnglMT99x75UkvuhA== 11:07:15 INFO - x-amz-version-id: OnZ4jCpezU8.mf30sEaB98c.UfZDU56N 11:07:16 INFO - Expected file size: 21962864 11:07:16 INFO - Obtained file size: 21962864 11:07:20 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:07:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 11:07:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:07:20 INFO - Fetch https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 11:07:20 INFO - Http code: 200 11:07:20 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 11:07:20 INFO - x-amz-cf-id: m9CLlUQV299lD5CVq8w4kMuk90lqBd_aK1FAlHPLe45eGOgJxExuvw== 11:07:20 INFO - x-amz-version-id: OnZ4jCpezU8.mf30sEaB98c.UfZDU56N 11:07:20 INFO - Expected file size: 21962864 11:07:20 INFO - Obtained file size: 21962864 11:07:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 11:07:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:07:25 INFO - Fetch https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 11:07:26 INFO - Http code: 200 11:07:26 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 11:07:26 INFO - x-amz-cf-id: UTY0wZfGHOj81NJ5XPptyeyMmk7tVvRO5We3Mk6_fjKAQi4PNQBIXw== 11:07:26 INFO - x-amz-version-id: gA2tcrTRGV04PqxJWVskilQu11GoHvsD 11:07:26 INFO - Expected file size: 14403005 11:07:26 INFO - Obtained file size: 14403005 11:07: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')]} 11:07:26 INFO - trying https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 11:07:26 INFO - Downloading https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 11:07:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:07:27 INFO - Downloaded 57754763 bytes. 11:07:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 11:07:27 INFO - mkdir: /builds/slave/test/properties 11:07:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:07:27 INFO - Writing to file /builds/slave/test/properties/build_url 11:07:27 INFO - Contents: 11:07:27 INFO - build_url:https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 11:07:28 INFO - Running post-action listener: _resource_record_post_action 11:07:28 INFO - Running post-action listener: set_extra_try_arguments 11:07:28 INFO - [mozharness: 2017-02-22 19:07:28.253780Z] Finished download-and-extract step (success) 11:07:28 INFO - [mozharness: 2017-02-22 19:07:28.254007Z] Running populate-webroot step. 11:07:28 INFO - Running pre-action listener: _resource_record_pre_action 11:07:28 INFO - Running main action method: populate_webroot 11:07:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:07:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:07:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:07:28 INFO - '--tppagecycles', 11:07:28 INFO - '7'], 11:07:28 INFO - 'tests': ['tsvgm']}}, 11:07:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:07:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:07:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:07:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:07:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:07:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['damp', 'tps']}, 11:07:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'tests': ['damp', 'tps']}, 11:07:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['dromaeo_dom']}, 11:07:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:07:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['basic_compositor_video']}, 11:07:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:07:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['a11yr', 11:07:28 INFO - 'ts_paint', 11:07:28 INFO - 'tpaint', 11:07:28 INFO - 'sessionrestore', 11:07:28 INFO - 'sessionrestore_no_auto_restore', 11:07:28 INFO - 'tabpaint']}, 11:07:28 INFO - 'other-e10s': {'tests': ['a11yr', 11:07:28 INFO - 'ts_paint', 11:07:28 INFO - 'tpaint', 11:07:28 INFO - 'sessionrestore', 11:07:28 INFO - 'sessionrestore_no_auto_restore', 11:07:28 INFO - 'tabpaint']}, 11:07:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:07:28 INFO - 'ts_paint', 11:07:28 INFO - 'tpaint', 11:07:28 INFO - 'sessionrestore', 11:07:28 INFO - 'sessionrestore_no_auto_restore', 11:07:28 INFO - 'tabpaint']}, 11:07:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:07:28 INFO - 'ts_paint', 11:07:28 INFO - 'tpaint', 11:07:28 INFO - 'sessionrestore', 11:07:28 INFO - 'sessionrestore_no_auto_restore', 11:07:28 INFO - 'tabpaint']}, 11:07:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['a11yr', 11:07:28 INFO - 'ts_paint', 11:07:28 INFO - 'tpaint', 11:07:28 INFO - 'sessionrestore', 11:07:28 INFO - 'sessionrestore_no_auto_restore', 11:07:28 INFO - 'tabpaint']}, 11:07:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['a11yr', 11:07:28 INFO - 'ts_paint', 11:07:28 INFO - 'tpaint', 11:07:28 INFO - 'sessionrestore', 11:07:28 INFO - 'sessionrestore_no_auto_restore', 11:07:28 INFO - 'tabpaint']}, 11:07:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['tsvgx', 11:07:28 INFO - 'tsvgr_opacity', 11:07:28 INFO - 'tart', 11:07:28 INFO - 'tscrollx', 11:07:28 INFO - 'cart']}, 11:07:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:07:28 INFO - 'tsvgr_opacity', 11:07:28 INFO - 'tart', 11:07:28 INFO - 'tscrollx', 11:07:28 INFO - 'cart']}, 11:07:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'talos_options': ['--disable-e10s'], 11:07:28 INFO - 'tests': ['tp5o']}, 11:07:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'tests': ['tp5o']}, 11:07:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'talos_options': ['--disable-e10s', 11:07:28 INFO - '--xperf_path', 11:07:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:28 INFO - 'tests': ['tp5n']}, 11:07:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:07:28 INFO - 'talos_options': ['--xperf_path', 11:07:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:07:28 INFO - 'tests': ['tp5n']}}, 11:07:28 INFO - 'talos.zip': {'path': '', 11:07:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:07:28 INFO - Running post-action listener: _resource_record_post_action 11:07:28 INFO - [mozharness: 2017-02-22 19:07:28.271665Z] Finished populate-webroot step (success) 11:07:28 INFO - [mozharness: 2017-02-22 19:07:28.271753Z] Running create-virtualenv step. 11:07:28 INFO - Running pre-action listener: _resource_record_pre_action 11:07:28 INFO - Running main action method: create_virtualenv 11:07:28 INFO - Creating virtualenv /builds/slave/test/build/venv 11:07:28 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 11:07:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:07:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:07:28 INFO - Using real prefix '/usr' 11:07:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:07:28 INFO - Installing distribute.............................................................................................................................................................................................done. 11:07:30 INFO - Installing pip.................done. 11:07:30 INFO - Return code: 0 11:07:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:07:31 INFO - Reading from file tmpfile_stdout 11:07:31 INFO - Output received: 11:07:31 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) 11:07:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:07:31 INFO - Using env: {'DISPLAY': ':0', 11:07:31 INFO - 'HOME': '/home/cltbld', 11:07:31 INFO - 'LANG': 'en_US.UTF-8', 11:07:31 INFO - 'LANGUAGE': 'en_US:en', 11:07:31 INFO - 'LOGNAME': 'cltbld', 11:07:31 INFO - 'MAIL': '/var/mail/cltbld', 11:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:31 INFO - 'MOZ_NO_REMOTE': '1', 11:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:31 INFO - 'NO_EM_RESTART': '1', 11:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:31 INFO - 'PWD': '/builds/slave/test', 11:07:31 INFO - 'SHELL': '/bin/bash', 11:07:31 INFO - 'SHLVL': '1', 11:07:31 INFO - 'TERM': 'linux', 11:07:31 INFO - 'TMOUT': '86400', 11:07:31 INFO - 'USER': 'cltbld', 11:07:31 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:31 INFO - '_': '/tools/buildbot/bin/python'} 11:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:31 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 11:07:31 INFO - Cleaning up... 11:07:31 INFO - Return code: 0 11:07:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:07:31 INFO - Using env: {'DISPLAY': ':0', 11:07:31 INFO - 'HOME': '/home/cltbld', 11:07:31 INFO - 'LANG': 'en_US.UTF-8', 11:07:31 INFO - 'LANGUAGE': 'en_US:en', 11:07:31 INFO - 'LOGNAME': 'cltbld', 11:07:31 INFO - 'MAIL': '/var/mail/cltbld', 11:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:31 INFO - 'MOZ_NO_REMOTE': '1', 11:07:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:31 INFO - 'NO_EM_RESTART': '1', 11:07:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:31 INFO - 'PWD': '/builds/slave/test', 11:07:31 INFO - 'SHELL': '/bin/bash', 11:07:31 INFO - 'SHLVL': '1', 11:07:31 INFO - 'TERM': 'linux', 11:07:31 INFO - 'TMOUT': '86400', 11:07:31 INFO - 'USER': 'cltbld', 11:07:31 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:31 INFO - '_': '/tools/buildbot/bin/python'} 11:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:31 INFO - Downloading/unpacking psutil>=3.1.1 11:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:07:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:07:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:07:33 INFO - Installing collected packages: psutil 11:07:33 INFO - Running setup.py install for psutil 11:07:33 INFO - building 'psutil._psutil_linux' extension 11:07:33 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 11:07:33 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 11:07:33 INFO - building 'psutil._psutil_posix' extension 11:07:33 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 11:07:33 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 11:07:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:07:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:07:34 INFO - Successfully installed psutil 11:07:34 INFO - Cleaning up... 11:07:34 INFO - Return code: 0 11:07:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:07:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:07:34 INFO - Using env: {'DISPLAY': ':0', 11:07:34 INFO - 'HOME': '/home/cltbld', 11:07:34 INFO - 'LANG': 'en_US.UTF-8', 11:07:34 INFO - 'LANGUAGE': 'en_US:en', 11:07:34 INFO - 'LOGNAME': 'cltbld', 11:07:34 INFO - 'MAIL': '/var/mail/cltbld', 11:07:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:34 INFO - 'MOZ_NO_REMOTE': '1', 11:07:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:34 INFO - 'NO_EM_RESTART': '1', 11:07:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:34 INFO - 'PWD': '/builds/slave/test', 11:07:34 INFO - 'SHELL': '/bin/bash', 11:07:34 INFO - 'SHLVL': '1', 11:07:34 INFO - 'TERM': 'linux', 11:07:34 INFO - 'TMOUT': '86400', 11:07:34 INFO - 'USER': 'cltbld', 11:07:34 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:34 INFO - '_': '/tools/buildbot/bin/python'} 11:07:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:07:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:07:36 INFO - Installing collected packages: mozsystemmonitor 11:07:36 INFO - Running setup.py install for mozsystemmonitor 11:07:36 INFO - Successfully installed mozsystemmonitor 11:07:36 INFO - Cleaning up... 11:07:36 INFO - Return code: 0 11:07:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:36 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:36 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 11:07:36 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 11:07:36 INFO - Using env: {'DISPLAY': ':0', 11:07:36 INFO - 'HOME': '/home/cltbld', 11:07:36 INFO - 'LANG': 'en_US.UTF-8', 11:07:36 INFO - 'LANGUAGE': 'en_US:en', 11:07:36 INFO - 'LOGNAME': 'cltbld', 11:07:36 INFO - 'MAIL': '/var/mail/cltbld', 11:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:36 INFO - 'MOZ_NO_REMOTE': '1', 11:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:36 INFO - 'NO_EM_RESTART': '1', 11:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:36 INFO - 'PWD': '/builds/slave/test', 11:07:36 INFO - 'SHELL': '/bin/bash', 11:07:36 INFO - 'SHLVL': '1', 11:07:36 INFO - 'TERM': 'linux', 11:07:36 INFO - 'TMOUT': '86400', 11:07:36 INFO - 'USER': 'cltbld', 11:07:36 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:36 INFO - '_': '/tools/buildbot/bin/python'} 11:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:36 INFO - Downloading/unpacking jsonschema==2.5.1 11:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:07:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:07:38 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 11:07:38 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 11:07:38 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 11:07:38 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 11:07:38 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 11:07:38 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 11:07:39 INFO - Downloading functools32-3.2.3-2.tar.gz 11:07:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:07:39 INFO - warning: no files found matching '*.txt' 11:07:39 INFO - no previously-included directories found matching 'build' 11:07:39 INFO - no previously-included directories found matching 'dist' 11:07:39 INFO - no previously-included directories found matching '.git*' 11:07:39 INFO - Installing collected packages: jsonschema, functools32 11:07:39 INFO - Running setup.py install for functools32 11:07:39 INFO - warning: no files found matching '*.txt' 11:07:39 INFO - no previously-included directories found matching 'build' 11:07:39 INFO - no previously-included directories found matching 'dist' 11:07:39 INFO - no previously-included directories found matching '.git*' 11:07:39 INFO - Successfully installed jsonschema functools32 11:07:39 INFO - Cleaning up... 11:07:39 INFO - Return code: 0 11:07:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:39 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:39 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 11:07:39 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 11:07:39 INFO - Using env: {'DISPLAY': ':0', 11:07:39 INFO - 'HOME': '/home/cltbld', 11:07:39 INFO - 'LANG': 'en_US.UTF-8', 11:07:39 INFO - 'LANGUAGE': 'en_US:en', 11:07:39 INFO - 'LOGNAME': 'cltbld', 11:07:39 INFO - 'MAIL': '/var/mail/cltbld', 11:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:39 INFO - 'MOZ_NO_REMOTE': '1', 11:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:39 INFO - 'NO_EM_RESTART': '1', 11:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:39 INFO - 'PWD': '/builds/slave/test', 11:07:39 INFO - 'SHELL': '/bin/bash', 11:07:39 INFO - 'SHLVL': '1', 11:07:39 INFO - 'TERM': 'linux', 11:07:39 INFO - 'TMOUT': '86400', 11:07:39 INFO - 'USER': 'cltbld', 11:07:39 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:39 INFO - '_': '/tools/buildbot/bin/python'} 11:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:07:39 INFO - Cleaning up... 11:07:39 INFO - Return code: 0 11:07:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:39 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:39 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 11:07:39 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 11:07:39 INFO - Using env: {'DISPLAY': ':0', 11:07:39 INFO - 'HOME': '/home/cltbld', 11:07:39 INFO - 'LANG': 'en_US.UTF-8', 11:07:39 INFO - 'LANGUAGE': 'en_US:en', 11:07:39 INFO - 'LOGNAME': 'cltbld', 11:07:39 INFO - 'MAIL': '/var/mail/cltbld', 11:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:39 INFO - 'MOZ_NO_REMOTE': '1', 11:07:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:39 INFO - 'NO_EM_RESTART': '1', 11:07:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:39 INFO - 'PWD': '/builds/slave/test', 11:07:39 INFO - 'SHELL': '/bin/bash', 11:07:39 INFO - 'SHLVL': '1', 11:07:39 INFO - 'TERM': 'linux', 11:07:39 INFO - 'TMOUT': '86400', 11:07:39 INFO - 'USER': 'cltbld', 11:07:39 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:39 INFO - '_': '/tools/buildbot/bin/python'} 11:07:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:39 INFO - Downloading/unpacking blobuploader==1.2.4 11:07:39 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 11:07:39 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 11:07:39 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 11:07:39 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 11:07:39 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 11:07:39 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 11:07:41 INFO - Downloading blobuploader-1.2.4.tar.gz 11:07:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:07:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:07:41 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 11:07:41 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 11:07:41 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 11:07:41 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 11:07:41 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 11:07:41 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 11:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:07:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:42 INFO - Downloading docopt-0.6.1.tar.gz 11:07:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:07:42 INFO - Installing collected packages: blobuploader, requests, docopt 11:07:42 INFO - Running setup.py install for blobuploader 11:07:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:07:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:07:42 INFO - Running setup.py install for requests 11:07:42 INFO - Running setup.py install for docopt 11:07:43 INFO - Successfully installed blobuploader requests docopt 11:07:43 INFO - Cleaning up... 11:07:43 INFO - Return code: 0 11:07:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:07: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')]} 11:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07: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')]} 11:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:43 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:43 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 11:07:43 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 11:07:43 INFO - Using env: {'DISPLAY': ':0', 11:07:43 INFO - 'HOME': '/home/cltbld', 11:07:43 INFO - 'LANG': 'en_US.UTF-8', 11:07:43 INFO - 'LANGUAGE': 'en_US:en', 11:07:43 INFO - 'LOGNAME': 'cltbld', 11:07:43 INFO - 'MAIL': '/var/mail/cltbld', 11:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:43 INFO - 'MOZ_NO_REMOTE': '1', 11:07:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:43 INFO - 'NO_EM_RESTART': '1', 11:07:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:43 INFO - 'PWD': '/builds/slave/test', 11:07:43 INFO - 'SHELL': '/bin/bash', 11:07:43 INFO - 'SHLVL': '1', 11:07:43 INFO - 'TERM': 'linux', 11:07:43 INFO - 'TMOUT': '86400', 11:07:43 INFO - 'USER': 'cltbld', 11:07:43 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:43 INFO - '_': '/tools/buildbot/bin/python'} 11:07:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:07:43 INFO - Running setup.py (path:/tmp/pip-zq190q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:07:43 INFO - Running setup.py (path:/tmp/pip-9RN33P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:07:43 INFO - Running setup.py (path:/tmp/pip-ctoDMd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:07:43 INFO - Running setup.py (path:/tmp/pip-40zOIX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:07:43 INFO - Running setup.py (path:/tmp/pip-bADOIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:07:43 INFO - Running setup.py (path:/tmp/pip-wfzc2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:07:43 INFO - Running setup.py (path:/tmp/pip-0bG5OB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:07:43 INFO - Running setup.py (path:/tmp/pip-yxr85J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:07:43 INFO - Running setup.py (path:/tmp/pip-LGiTO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:07:44 INFO - Running setup.py (path:/tmp/pip-Tz9mlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:07:44 INFO - Running setup.py (path:/tmp/pip-RwSczd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:07:44 INFO - Running setup.py (path:/tmp/pip-GvlavV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:07:44 INFO - Running setup.py (path:/tmp/pip-RHSM0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:07:44 INFO - Running setup.py (path:/tmp/pip-d3gjSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:07:44 INFO - Running setup.py (path:/tmp/pip-pSYyZL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:07:44 INFO - Running setup.py (path:/tmp/pip-cdR9O2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:07:44 INFO - Running setup.py (path:/tmp/pip-aSvEkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:07:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:07:44 INFO - Running setup.py install for manifestparser 11:07:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:07:44 INFO - Running setup.py install for mozcrash 11:07:44 INFO - Running setup.py install for mozdebug 11:07:44 INFO - Running setup.py install for mozdevice 11:07:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:07:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for mozfile 11:07:45 INFO - Running setup.py install for mozhttpd 11:07:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for mozinfo 11:07:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for mozInstall 11:07:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for mozleak 11:07:45 INFO - Running setup.py install for mozlog 11:07:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for moznetwork 11:07:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for mozprocess 11:07:45 INFO - Running setup.py install for mozprofile 11:07:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:07:45 INFO - Running setup.py install for mozrunner 11:07:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:07:46 INFO - Running setup.py install for mozscreenshot 11:07:46 INFO - Running setup.py install for moztest 11:07:46 INFO - Running setup.py install for mozversion 11:07:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:07:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:07:46 INFO - Cleaning up... 11:07:46 INFO - Return code: 0 11:07:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:07:46 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')]} 11:07:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:46 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')]} 11:07:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:46 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:46 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 11:07:46 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 11:07:46 INFO - Using env: {'DISPLAY': ':0', 11:07:46 INFO - 'HOME': '/home/cltbld', 11:07:46 INFO - 'LANG': 'en_US.UTF-8', 11:07:46 INFO - 'LANGUAGE': 'en_US:en', 11:07:46 INFO - 'LOGNAME': 'cltbld', 11:07:46 INFO - 'MAIL': '/var/mail/cltbld', 11:07:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:46 INFO - 'MOZ_NO_REMOTE': '1', 11:07:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:46 INFO - 'NO_EM_RESTART': '1', 11:07:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:46 INFO - 'PWD': '/builds/slave/test', 11:07:46 INFO - 'SHELL': '/bin/bash', 11:07:46 INFO - 'SHLVL': '1', 11:07:46 INFO - 'TERM': 'linux', 11:07:46 INFO - 'TMOUT': '86400', 11:07:46 INFO - 'USER': 'cltbld', 11:07:46 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:46 INFO - '_': '/tools/buildbot/bin/python'} 11:07:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:07:46 INFO - Running setup.py (path:/tmp/pip-h87v3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:07:46 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)) 11:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:07:46 INFO - Running setup.py (path:/tmp/pip-uOnNeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:07:46 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)) 11:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:07:46 INFO - Running setup.py (path:/tmp/pip-jMtUjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:07:46 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)) 11:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:07:46 INFO - Running setup.py (path:/tmp/pip-JCbZk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:07:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:07:46 INFO - Running setup.py (path:/tmp/pip-nPXDz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:07:46 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)) 11:07:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:07:46 INFO - Running setup.py (path:/tmp/pip-dYD4ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:07:47 INFO - Running setup.py (path:/tmp/pip-uQNL2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:07:47 INFO - Running setup.py (path:/tmp/pip-GceFFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:07:47 INFO - Running setup.py (path:/tmp/pip-XbxheC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:07:47 INFO - Running setup.py (path:/tmp/pip-MfIE1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:07:47 INFO - Running setup.py (path:/tmp/pip-Rr0PKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:07:47 INFO - Running setup.py (path:/tmp/pip-FlBv8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:07:47 INFO - Running setup.py (path:/tmp/pip-G8YOp2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:07:47 INFO - Running setup.py (path:/tmp/pip-lNsyZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:07:47 INFO - Running setup.py (path:/tmp/pip-pbZ2Yc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:07:47 INFO - Running setup.py (path:/tmp/pip-HpOiiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:07:47 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)) 11:07:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:07:47 INFO - Running setup.py (path:/tmp/pip-Tz3aMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:07:47 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)) 11:07:47 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)) 11:07:47 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)) 11:07:47 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)) 11:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:07:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:07:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:07:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:07:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:07:49 INFO - Downloading blessings-1.6.tar.gz 11:07:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:07:49 INFO - Installing collected packages: blessings 11:07:49 INFO - Running setup.py install for blessings 11:07:50 INFO - Successfully installed blessings 11:07:50 INFO - Cleaning up... 11:07:50 INFO - Return code: 0 11:07:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:07:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:07:50 INFO - Reading from file tmpfile_stdout 11:07:50 INFO - Current package versions: 11:07:50 INFO - argparse == 1.2.1 11:07:50 INFO - blessings == 1.6 11:07:50 INFO - blobuploader == 1.2.4 11:07:50 INFO - docopt == 0.6.1 11:07:50 INFO - functools32 == 3.2.3-2 11:07:50 INFO - jsonschema == 2.5.1 11:07:50 INFO - manifestparser == 1.1 11:07:50 INFO - mozInstall == 1.12 11:07:50 INFO - mozcrash == 1.0 11:07:50 INFO - mozdebug == 0.1 11:07:50 INFO - mozdevice == 0.48 11:07:50 INFO - mozfile == 1.2 11:07:50 INFO - mozhttpd == 0.7 11:07:50 INFO - mozinfo == 0.9 11:07:50 INFO - mozleak == 0.1 11:07:50 INFO - mozlog == 3.4 11:07:50 INFO - moznetwork == 0.27 11:07:50 INFO - mozprocess == 0.23 11:07:50 INFO - mozprofile == 0.28 11:07:50 INFO - mozrunner == 6.13 11:07:50 INFO - mozscreenshot == 0.1 11:07:50 INFO - mozsystemmonitor == 0.3 11:07:50 INFO - moztest == 0.8 11:07:50 INFO - mozversion == 1.4 11:07:50 INFO - psutil == 3.1.1 11:07:50 INFO - requests == 1.2.3 11:07:50 INFO - wsgiref == 0.1.2 11:07:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:07:50 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')]} 11:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:50 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')]} 11:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:50 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:50 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 11:07:50 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 11:07:50 INFO - Using env: {'DISPLAY': ':0', 11:07:50 INFO - 'HOME': '/home/cltbld', 11:07:50 INFO - 'LANG': 'en_US.UTF-8', 11:07:50 INFO - 'LANGUAGE': 'en_US:en', 11:07:50 INFO - 'LOGNAME': 'cltbld', 11:07:50 INFO - 'MAIL': '/var/mail/cltbld', 11:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:50 INFO - 'MOZ_NO_REMOTE': '1', 11:07:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:50 INFO - 'NO_EM_RESTART': '1', 11:07:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:50 INFO - 'PWD': '/builds/slave/test', 11:07:50 INFO - 'SHELL': '/bin/bash', 11:07:50 INFO - 'SHLVL': '1', 11:07:50 INFO - 'TERM': 'linux', 11:07:50 INFO - 'TMOUT': '86400', 11:07:50 INFO - 'USER': 'cltbld', 11:07:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:50 INFO - '_': '/tools/buildbot/bin/python'} 11:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 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)) 11:07:50 INFO - Cleaning up... 11:07:50 INFO - Return code: 0 11:07:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:07:50 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')]} 11:07:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:07:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:50 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')]} 11:07:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:07:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:07:50 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 0x7fc805ce2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c648a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d31ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, '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': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', '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 11:07:50 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 11:07:50 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 11:07:50 INFO - Using env: {'DISPLAY': ':0', 11:07:50 INFO - 'HOME': '/home/cltbld', 11:07:50 INFO - 'LANG': 'en_US.UTF-8', 11:07:50 INFO - 'LANGUAGE': 'en_US:en', 11:07:50 INFO - 'LOGNAME': 'cltbld', 11:07:50 INFO - 'MAIL': '/var/mail/cltbld', 11:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:07:50 INFO - 'MOZ_NO_REMOTE': '1', 11:07:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:07:50 INFO - 'NO_EM_RESTART': '1', 11:07:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:07:50 INFO - 'PWD': '/builds/slave/test', 11:07:50 INFO - 'SHELL': '/bin/bash', 11:07:50 INFO - 'SHLVL': '1', 11:07:50 INFO - 'TERM': 'linux', 11:07:50 INFO - 'TMOUT': '86400', 11:07:50 INFO - 'USER': 'cltbld', 11:07:50 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:07:50 INFO - '_': '/tools/buildbot/bin/python'} 11:07:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:07:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:07:50 INFO - Cleaning up... 11:07:50 INFO - Return code: 0 11:07:50 INFO - Running post-action listener: _resource_record_post_action 11:07:50 INFO - Running post-action listener: _start_resource_monitoring 11:07:50 INFO - Starting resource monitoring. 11:07:50 INFO - [mozharness: 2017-02-22 19:07:50.838948Z] Finished create-virtualenv step (success) 11:07:50 INFO - [mozharness: 2017-02-22 19:07:50.839408Z] Running install step. 11:07:50 INFO - Running pre-action listener: _resource_record_pre_action 11:07:50 INFO - Running main action method: install 11:07:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:07:51 INFO - Reading from file tmpfile_stdout 11:07:51 INFO - Detecting whether we're running mozinstall >=1.0... 11:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:07:51 INFO - Reading from file tmpfile_stdout 11:07:51 INFO - Output received: 11:07:51 INFO - Usage: mozinstall [options] installer 11:07:51 INFO - Options: 11:07:51 INFO - -h, --help show this help message and exit 11:07:51 INFO - -d DEST, --destination=DEST 11:07:51 INFO - Directory to install application into. [default: 11:07:51 INFO - "/builds/slave/test"] 11:07:51 INFO - --app=APP Application being installed. [default: firefox] 11:07:51 INFO - mkdir: /builds/slave/test/build/application 11:07:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:07:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:08:05 INFO - Reading from file tmpfile_stdout 11:08:05 INFO - Output received: 11:08:05 INFO - /builds/slave/test/build/application/firefox/firefox 11:08:05 INFO - Running post-action listener: _resource_record_post_action 11:08:05 INFO - [mozharness: 2017-02-22 19:08:05.361875Z] Finished install step (success) 11:08:05 INFO - [mozharness: 2017-02-22 19:08:05.362204Z] Running run-tests step. 11:08:05 INFO - Running pre-action listener: _resource_record_pre_action 11:08:05 INFO - Running main action method: run_tests 11:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:08:05 INFO - Python 2.7.3 11:08:05 INFO - Return code: 0 11:08:05 INFO - grabbing minidump binary from tooltool 11:08:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:05 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 0x1d2ef60>, '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 0x1d2fd40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d301d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:08:05 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 11:08:05 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 11:08:05 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 11:08:05 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:08:05 INFO - Return code: 0 11:08:05 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:08:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:08:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:08:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:08:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:08:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:08:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-036 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:08:05 INFO - Using env: {'DISPLAY': ':0', 11:08:05 INFO - 'HOME': '/home/cltbld', 11:08:05 INFO - 'LANG': 'en_US.UTF-8', 11:08:05 INFO - 'LANGUAGE': 'en_US:en', 11:08:05 INFO - 'LOGNAME': 'cltbld', 11:08:05 INFO - 'MAIL': '/var/mail/cltbld', 11:08:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:08:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:08:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:08:05 INFO - 'MOZ_NO_REMOTE': '1', 11:08:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:08:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:08:05 INFO - 'NO_EM_RESTART': '1', 11:08:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:08:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:08:05 INFO - 'PWD': '/builds/slave/test', 11:08:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:08:05 INFO - 'SHELL': '/bin/bash', 11:08:05 INFO - 'SHLVL': '1', 11:08:05 INFO - 'TERM': 'linux', 11:08:05 INFO - 'TMOUT': '86400', 11:08:05 INFO - 'USER': 'cltbld', 11:08:05 INFO - 'XDG_SESSION_COOKIE': '14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156', 11:08:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:08:05 INFO - '_': '/tools/buildbot/bin/python'} 11:08:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-036', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:08:05 INFO - mozversion application_buildid: 20170222091722 11:08:05 INFO - mozversion application_changeset: 10bce9625147fe39903884dfe20c04eb7f899a98 11:08:05 INFO - mozversion application_display_name: Firefox 11:08:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:08:05 INFO - mozversion application_name: Firefox 11:08:05 INFO - mozversion application_remotingname: firefox 11:08:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:08:05 INFO - mozversion application_vendor: Mozilla 11:08:05 INFO - mozversion application_version: 52.0 11:08:05 INFO - mozversion platform_buildid: 20170222091722 11:08:05 INFO - mozversion platform_changeset: 10bce9625147fe39903884dfe20c04eb7f899a98 11:08:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:08:05 INFO - mozversion platform_version: 52.0 11:08:05 INFO - using testdate: 1487790485 11:08:05 INFO - actual date: 1487790485 11:08:05 INFO - starting webserver on 'localhost:56487' 11:08:05 INFO - SUITE-START | Running 2 tests 11:08:05 INFO - TEST-START | dromaeo_css 11:08:05 INFO - operating with platform_type : linux_ 11:08:05 INFO - Initialising browser for dromaeo_css test... 11:08:05 INFO - TEST-INFO | started process 2058 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ0Sngq/profile http://localhost:56487/getInfo.html) 11:08:10 INFO - PROCESS | 2058 | __metrics Screen width/height:1600/1200 11:08:10 INFO - PROCESS | 2058 | colorDepth:24 11:08:10 INFO - PROCESS | 2058 | Browser inner width/height: 1024/697 11:08:10 INFO - PROCESS | 2058 | __metrics 11:08:11 ERROR - PROCESS | 2058 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 11:08:11 INFO - TEST-INFO | 2058: exit 0 11:08:11 INFO - Browser initialized. 11:08:11 INFO - Running cycle 1/1 for dromaeo_css test... 11:08:11 INFO - TEST-INFO | started process 2189 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpZ0Sngq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:23 INFO - PROCESS | 2189 | RSS: Main: 146919424 11:08:23 INFO - PROCESS | 2189 | 11:11:23 INFO - PROCESS | 2189 | Cycle 1(1): loaded http://localhost:56487/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56487/tests/dromaeo/cssquery-ext.html) 11:11:24 INFO - PROCESS | 2189 | RSS: Main: 153788416 11:11:24 INFO - PROCESS | 2189 | 11:14:26 INFO - PROCESS | 2189 | Cycle 1(1): loaded http://localhost:56487/tests/dromaeo/cssquery-ext.html (next: http://localhost:56487/tests/dromaeo/cssquery-jquery.html) 11:14:26 INFO - PROCESS | 2189 | RSS: Main: 152178688 11:14:26 INFO - PROCESS | 2189 | 11:17:28 INFO - PROCESS | 2189 | Cycle 1(1): loaded http://localhost:56487/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56487/tests/dromaeo/cssquery-mootools.html) 11:17:29 INFO - PROCESS | 2189 | RSS: Main: 151937024 11:17:29 INFO - PROCESS | 2189 | 11:20:31 INFO - PROCESS | 2189 | Cycle 1(1): loaded http://localhost:56487/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56487/tests/dromaeo/cssquery-prototype.html) 11:20:31 INFO - PROCESS | 2189 | RSS: Main: 153841664 11:20:31 INFO - PROCESS | 2189 | 11:23:34 INFO - PROCESS | 2189 | Cycle 1(1): loaded http://localhost:56487/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56487/tests/dromaeo/cssquery-yui.html) 11:23:34 INFO - PROCESS | 2189 | RSS: Main: 154615808 11:23:34 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | Cycle 1(1): loaded http://localhost:56487/tests/dromaeo/cssquery-yui.html (next: http://localhost:56487/tests/dromaeo/cssquery-dojo.html) 11:26:37 INFO - PROCESS | 2189 | RSS: Main: 155201536 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | __start_tp_report 11:26:37 INFO - PROCESS | 2189 | _x_x_mozilla_page_load 11:26:37 INFO - PROCESS | 2189 | _x_x_mozilla_page_load_details 11:26:37 INFO - PROCESS | 2189 | |i|pagename|runs| 11:26:37 INFO - PROCESS | 2189 | |0;dojo.html;3162.5256211568267;3186.7769256152064;3205.759568032398;3206.567113439685;3209.571740575444;5544;5591.30108736408;5592.133219351002;5600.495955364022;5626.381098079208;5550.084236101048;5551.861203469915;5563.165525171226;5596.692181929992;5597.272354593897;5056.393232812058;5106.591472682195;5139.845804625848;5154.098032844237;5169.353830771153;5122.718250496221;5176.2235664650225;5181.0933086709865;5186.170212765958;5192.896142077157;5019.799208031674;5059.721715305656;5148.819791307305;5148.897022059576;5158.226266060084;5081.415637201713;5098.541131298182;5164.974175129138;5189.221616757479;5221.3473315835145;5109.9489005109845;5154.561862241714;5159.5614372778355;5165.896682066375;5177.663451875654;5338.652987555796;5537.972310138463;5557.472040156219;5635.380108188096;5639.097744360924;5769.624974376694;5809.2447981762525;5835.795747148872;5879.559033072536;5950.375210602911;5871.735771890275;5943.227380440559;5944;5952.4642782149385;5972.044472884361;5547.223388725582;5549.528290095306;5556.805511807067;5570.777168913198;5579.776808927638;6009.909851352233;6058.091286307001;6079.209702738615;6103.23709536308;6109.7861574844665;5834.94165058344;5886.6173698709445;5890.734916928663;5890.911636325459;5897.5871688982215;5729.828105156852;5777.046787280052;5981.671008094597;5981.790637327673;5985.850353741104;5793.073108302651;5827.125931110368;5847.035239185487;5854.502367298827;5888.9705551472825;5828.766849326064;5834.154047663065;5879.588428809942;5891.086881533369;5893.587448878623;4480.932786008212;4587.059652771174;4599.9080018399445;4607.170709272296;4617.930731039037;4011.779352135602;4082.183563287295;4108.4864391951005;4130.359794231838;4185.748855068706;5751;5784.537237021113;5790.247267855236;5795.420457954171;5800.941990580124;5982.371850955587;6053.303870054912;6059.121427393092;6062.514998800086;6075.210222671025;5571.192177134293;5635.520980716603;5673.659580425187;5709.086546152679;5749.971250143806;5198;5220.086484865134;5235.293235413246;5254.710990895461;5267.841964741064;6260.123582698426;6271.498280137578;6290.559660823698;6295.3389894060465;6303.36966303366;5708.172315014383;5742.16738572913;5896.941030589811;5909.52723782105;5967.910481342697;6207.565470417027;6229.56393052482;6242.656653883898;6244.157038799885;6245.0320200369015;5794.188813566188;5836.445537673914;5853.853653658693;5869.06095024794;5875.911861322169;5857.7949771757785;5892.234009578728;5911;5995.95070862606;6008.038713805945;5789.102689083199;5820.388859169726;5820.970895145497;5841.94158058414;5874.559408044499;5784.074548072288;5813.9534883721335;5834.095715164156;5837.0368889132815;5855.443732845373;5513.641613295123;5590.0496915523645;5731.254936858182;5792.855178620568;5798.159266906359;5782.479576838105;5922.674252915958;5924.407559244041;5949.3455719871645;5965.850853728692;5691.459311365414;5745.166950792028;5758.366579676316;5760.711964401847;5763.740631671669 11:26:37 INFO - PROCESS | 2189 | |1;ext.html;610.6427739772233;639.9776007839727;648.0214875537939;664.4783844681925;684.4079870911656;39376.212475750464;39824.004399890015;39888.60111398889;39945.2010959781;40031.79984100083;44271.557284427145;44676.106477870424;44755.55244447554;45067.77466112673;45100.54899451013;9027.909720902773;9163.312751543628;9174;9182.311326650495;9236;9019.323550733689;9079.046700096495;9088.681896133636;9091.590878410454;9133.041030691782;9163.312751543628;9222.30832687551;9238.815223695523;9239.260859131253;9239.676611318606;9042.276617870555;9125.954370228174;9140.725778226664;9190.586423610954;9202.723918282461;8903.33225008127;8971.596278167499;8993.10068993102;8997.235235005033;8999.595018224196;42483.78758106189;42632.14735705269;42661;42752.78623606893;42795.144097118195;3705.555333359987;3786.2995345861104;3825.1775868188197;3829.9617003829885;3861.7489863158803;31928.361432771446;33143.3371332572;33471.497927531105;33473;33631;4543.954560454386;4671.782096660668;4684.5549672781035;4695.389599352096;4817.470078291385;343178;348806.99999999744;349046;349369;351958;61050;61099.38900611026;61451.385486144565;61472.77054458975;61487.38512614906;63855.361446384944;64035;64146.35853641404;64159.67920160369;64229.678851606375;18299.63400731978;18427.539311517052;18482.722759158623;18485.44543663692;18557.35049273269;14253.572392828231;14287.642808929651;14310.85689143116;14316.85683143176;14319.21244331551;16762;17472.5631859202;17492.212850421507;17884.55288617769;17996.9100154501;6591.505637077238;6626.768063117768;6631.3700198481065;6636.9425478100975;6810.31896810315;937.376644531379;993.7068564773265;1031.8710161229847;1049.7952899184738;1118.2060736876747;32145.839270803495;32414;32419.86530471422;32434.513482297785;33044.339113217604;1075.5590208014676;1141.1099342512853;1150.539784086372;1180.728432460539;1213.4175595714107;16784.32862685479;17186.22661980214;17233.310667573158;17381;17548.82451175472;141336;142079;142283;142917.28541357434;143076.28461857833;61645;61655.38344616497;61726.691366543775;61746.38253617496;61933;1381.5648070857649;1416.6316757643087;1419.637992311954;1431.4488921765403;1431.957041288763;30691.2327191822;32174.35651287008;32484;32672.836635816668;32677.673223268415;52537.7373113132;52764.73617632041;52840;52882.47117528929;52889.735551321995;383645;384720;385482;385982;386714;42956.78521607497;43850;44221.778891105336;44357.33463998107;44371.33442998422;49241.50758492369;49296.50703492919;49615.255771164186;49760;50253.748731256106;39751.93582558004;40844.59155408408;41846;41877.581224187365;42781.78609106935;41368.1726365477;41542.169156616095;42239.788801055794;43352.78323608362;43471;40826.183476329716;41424.792876035426;42352.5764742361;42355.7882210587;42749;35512.822435887654;37450;37650.43524347192;38004.429933551575;38147;28407.85796071089;28584.428311434072;28724.42551149007;28747.856260718563;28872.422551548432 11:26:37 INFO - PROCESS | 2189 | |2;jquery.html;377.28881059203394;385.1180795977211;416.91453252083323;423.79234175254123;424.57542457542456;16130.596735081628;18873.528161795963;19281.132349044292;19478.610427791453;19498;19760;20073.698894516558;20892.164313427445;20999.895000525015;21041.894790526065;5644.858878528029;5969.910451343211;5997.340292567814;6012.609180403259;6025.3372129065765;5850.356460789331;5923.644581325134;5988.101784732303;6014.909776353358;6030.155778191057;5998.850028749294;6000.8499787505225;6015.518758499332;6020.066889632092;6026.698665066751;5785.074388097905;5923.022701254288;5976.5517586180995;5986.580939334249;6013.488853447462;5404.864878378033;5416.485433883775;5419.241306217133;5421.078416669176;5433.022056029913;23526.882365588233;24108.638370424458;24313.270601881974;24333.1483398082;24493.38766530833;2586.6895972483235;2603.179998300548;2691.4886171627522;3172.9206769830707;3223.1136437479645;14050.648733781634;15256.38974441021;15282.73609472167;15515.844841551552;15970.840291597051;2026.9493262668404;2054.9178032878667;2085.9165633374646;2106.8735875847497;2110.8205802506805;71580.6420967897;82353.58823205845;82763.5861820687;85367;86695.56652216762;11293.887061129448;11331.32012079278;11445;11546.538138474367;11842.171048026556;16193.757093643604;16202.594935126715;16219.189040547926;33316.33367332688;33609.49585756216;10308.639197628047;10479.371237725762;10593.788124237626;10794.514246858911;10817.350958942488;7983.1617680142745;8662.523561204194;8817.559122043873;8860.955695221483;9198.264138869008;8890.511021893857;9060.81878362429;9148.451092934445;9269.490178040272;9299.442033478013;3339.332133573295;3543.911402214965;3599.136207310227;3670.284294562588;3704.759190652599;906.6328137104483;978.8825340958988;981.7398389426808;1027.8355463125863;1101.7741363020723;17598.472045838644;19458;20107.899460502897;20191.49521261951;20373;953.2326477185932;1017.6387382479261;1036.124474818777;1040.47976011994;1051.4112097225577;10387.169026477864;10878.728031799268;11127.499262533205;11153.72115697114;11175.273607215668;442799;446542;448321;454907;463607;88748;90485;90876.09123908676;91748.54125729328;92318.04519148788;999.2705325112709;1017.1760873692185;1021.7547788530699;1024.2778840917135;1037.5486663301338;16622.833771662612;16886.57783555421;17414;17565.560860978578;17780.3776867809;38047.619523805515;38251.80874095612;38559.42160867645;39371.409428859166;40539.39190912198;56742;57103.71448142733;57658.7117064412;57912.4208757907;58144.709276453345;8241.546714930737;8657.480551166951;8784.516851573226;8811.867821982803;8853.69012084574;10228.59085636596;11996.700082497659;12121.27272363661;12499.187552809039;12597.685057873627;10531.789364212826;10604.787904242026;10699.197560182862;10808;11326.94336528345;11555.017823485097;11650.30098194111;11680.415979200836;11710.355930423908;11846.229995050295;11251.887481125412;11576.94211528937;11624.709382265512;11634.70913227176;11837.408129593314;8592.226699596817;8631.654733810828;8695.652173912973;8864;8910.198082172636;8379.874301885598;11179.72050698739;11195.440227988405;11222.102231821598;11440.084793216356 11:26:37 INFO - PROCESS | 2189 | |3;mootools.html;1495.820501539815;1498.7002599480104;1657.1134443072951;1718.5134858347533;1771.5128339706587;7823.726169584052;7987.760367188979;8446.113158118393;9096.36325457219;9097.499637519933;8496.532690702008;8710.73867783966;8751.212390884819;9011.053839346872;9012.233960113399;2446.8042556595474;2468.123816045305;2477.9008839646394;2483.7392073832257;2488.228649118779;2383.285014495644;2473.319837044809;2474.9010039598393;2476.6532685424054;2478.2317481580767;2402.2793162051403;2406.4826062396583;2435.8051355891575;2439.134107391877;2448.9877550612223;2315.617923042713;2321.3616255529696;2328.487732698812;2329.6738456616085;2362.244081893795;2380.071772008918;2391.964120538193;2400.099962514057;2400.327908185711;2402.6876506054277;8739.60671769772;8813.07462716412;8861.556922153866;8866.246369058637;8904.688335908277;5400.486953739389;5521.613487055908;5531.834044978657;5557.861053473655;5566.610337276392;3434.587849458056;3438.235132529876;3442;3471.94792078119;3500.1249687578106;1134.2627292260015;1148.62095508482;1186.1578279421622;1189.5420263198644;1208.9206564708686;12507.624771256877;12564.434600443003;12564.874351256462;12607.936960315232;12634.241945483209;4721.740304283228;4768.594669453136;4788.904221915543;4856.150173719585;4937.580305674058;10493.160547156209;10515.3690778552;10532.894671053346;10537.9473102634;10556.683299501028;5384.40771515133;5553.250311208016;5688.601797874192;5689.800856969986;5693.886122277531;4940.580050695659;5086.465921078234;5114.769835357418;5172.379314482211;5187.688738675616;4018.457508236351;4025.939610905839;4049.1699201663587;4126.525449573338;4151.97924010378;1194.8386967759416;1195.4441184849086;1196.359947428139;1202.2065436811663;1223.608445297514;982.7641366072091;995.7460847483845;1009.4952523738131;1009.5557954499997;1014.8680671512803;6016.939830601726;6103.206583144251;6155.907661385083;6199.752009919643;6274.529410294246;456.9063342014879;482.03592814371257;486.14195944158587;492.1731491094995;986.1666891476691;3749.343864823645;3918.5689574146822;3938.9212215755524;3951.940720889189;3994.7603143811466;10820.837687434694;11148.05241554477;11159.94420027911;11205.831912521318;11228.719282018015;11017.83473247902;11051.613193538187;11170.999999999838;11175.44122793857;11231.550737970552;8401.243888050105;8446.704365347183;8521.872171917426;8522.829543409098;8662.393632445668;5741.799037033684;5960.4635582797755;6005.489533389711;6027.6986150693165;6033.54748393881;9942.105210531086;10268.383896966207;10314.742131446774;10428.478576071318;10443.582256709646;9323.580438880403;9619.230461562918;9693.176080033276;10073.294869359075;10380;7506.28690274423;7804.609769511389;7824.491408058458;7906.802329941797;8030.558319292495;8856.070112638337;8879.334049946408;8895.421797583136;8898.065703101081;8969.058248883774;7581.54510729358;7865.921340786519;7895.7236496722335;8049.597520124087;8049.919500805151;7339.376153026839;7371.336579707852;7435.925640743523;7530.623468826647;7561.243875612395;7161.57030578167;7213.747518836815;7271.4182865369585;7306.159791624032;7308.780736577911;5203.609729270244;5314.707691076873;5372.435894231206;5384.703841288767;5393.892122157614;5361.061814182581;5369.221462887937;5393.244945707525;5395.2176934343515;5431.103867861759 11:26:37 INFO - PROCESS | 2189 | |4;prototype.html;327.7459968524393;333.43149929370423;335.47498165371195;348.3485881401779;351.07491759214463;5598.300212473441;5671.376148623648;5690.231818704484;5698.430156984305;5705.714714264286;5539.648512649849;5549.944500554993;5561.193626924093;5574.581906357019;5617.325920889499;4190.811413486393;4317.330813723862;4324.6107850293465;4340.240457919867;4376.452943382077;4353.586409291112;4362.6727995400315;4365.36702178184;4387.376670631855;4387.62705170061;4333.393324934512;4334.284843000901;4355.412019377375;4356.302991521357;4372.912541749163;4113.300738874414;4281.7002809803325;4354.9765805035795;4355.281378572532;4372.431583894089;3839.616038396138;3842.4620553122586;3843.3082045231845;3844.7116466265143;3845.8461661533784;5449.7275136243425;5454.10007348791;5469.472190437145;5487.670739755627;5505.394406615269;4908.852734417937;4938.135826230396;4981.178105612569;4984.601231901439;5008.273800298973;5774.711264436762;5778.739956701958;5781.595288329819;5801.883962320772;5803.419658034205;4222.43769805171;4228.386883901817;4231.322988321885;4233.301505251629;4237.343211802176;5900.498457631106;6003.6697981611;6038.788642397539;6052.878942421171;6103.206583144207;4309.5778393130195;4321.848735294312;4326.199653064194;4332.545082766327;4337.587929146726;4350.260455722471;4362.563743625612;4376.606105450524;4398.604125628709;4399.824007039682;4841.975790121027;4894.926576101361;4936;4994.325766021613;5002.299678045077;5814.011618024946;5826.242588463472;5843.18195452637;5875.823725288248;5905.704714764331;1454.0403333799852;1454.3964254834336;1457.7084583083426;1458.5843034735262;1460.8685218330402;981.764376549623;993.2947607198826;1003.4430890855499;1013.8681971343678;1016.4917541229386;5440.700761458079;5480.629842539365;5480.862978425571;5522.2544956430365;5524.784547399566;5782.335031471351;5803.448672376202;5818;5841.524293045631;5870;5266.183741520071;5495.835124946258;5512.255845460892;5532.1425179097305;5541.33503979517;3083.5661417440997;3087.2557005291937;3101.0386780098243;3105.208171916147;3111.4554952883154;5716.9137863805745;5987.700614969234;6021.832140429442;6027.578069535178;6033.607815491979;5798.578838485229;5827.213326200994;5828.446297601721;5867.383924687846;5891.528677705774;5973.37279585646;5994.400559943971;6028.4272994065495;6042.607230530001;6050.42520960508;5712.914306285492;5769.874874399452;6079.696015199311;6090;6092.928404342545;6023.578349515492;6027.306859711014;6038.456538911343;6052.576319657758;6078.696065196811;6209.689515524296;6296.150019747275;6360.522960778053;6373.808785736435;6388.36116388376;4270.444842170498;4277.086465883806;4281.443412356373;4284.978575107105;4293.763842988541;5776.855578610568;6051.515878729779;6058.939410605837;6120.3267640558615;6123.601965872204;5868.889488571064;5877.559183061373;5880.323762767165;5885.440883116097;5895.764169433174;5878.588498805214;5881;5885.882282354243;5903.527717782653;5936.198613187337;5635.746391412315;5775.393583673654;5797.188393624981;5803.883922321615;5866.2960444746905;5882.97016543669;5913.319968203539;5921.437463440966;5922.318933322723;5949.791757288647;5598.804041858678;5761.308642962871;5766.3368712598585;5805.680687562224;5827.388124246893 11:26:37 INFO - PROCESS | 2189 | |5;yui.html;776.7475570439609;777.8599852026637;813.8087549425885;833.7040350675506;848.2069265237002;14153.504627338045;15009.774853377208;15525;15530.767038494432;15884.205789710497;14499;15880.444184453547;15918.602034949132;16026.43907463239;16070.517884463485;835.1815221083327;857.4512312120248;857.5583574459158;859.2739135430551;861.2119910282084;827.3505298340804;854.7563944275882;859.3640705877636;862.4609618988133;864.1142828600672;896.3367108339846;898.2768871058828;899.1997122560927;900.837849187146;902.2195800632438;892.6563273139823;893.7854914820462;895.7671005538574;901.9503927283997;901.9503927283997;660.6201434175368;661.3254480429935;663.5355251324092;664.1764212376618;664.35225654986;13986.300684965812;14829;14837.629059273497;14863.182524961123;14872.479463218844;13275.86724132756;13478.191308521422;14127.717445651133;14175.503857365045;14208.715825683428;10632.149428045737;10755.516001779939;10888.40113793741;11013.504392302366;11042.723931901686;3748.9250214995545;3756.323861704892;3767.0958969847316;3778.489903862971;3855.036240939765;64795.35204647987;65659;65771.013434799;65826;67625.66187169081;11321.377324247082;11414.714632134263;11425.143114266462;11559.133065020158;11649.1263155262;20222.595548088957;20308.086136123613;20316.59366812685;20344.18623255053;20346.89826550858;7826.323736911134;7970.2826745593175;8099.716509922242;8165.79585510367;8271.503709777433;4374.18127300529;4405.845795397146;4482.193205223091;4487.708298960557;4489.438820147481;3301.8018918864946;3412.9761071678895;3441.8107004115013;3467.2025434150287;3483.8780642677893;613.8588124731336;615.2862679292;619.4455961914151;625.2403329954128;644.2462319086679;696.3837004251274;698.818307240111;707.4163814852767;714.9428045756327;724.0768020773451;8636.265917396966;8782.956085219534;8861.24679402258;8879.733607991771;9167.404118732262;624.7969409941787;655.05017724299;666.2637785247232;680.4556354916048;684.8596037812237;4341.218580655449;4533.206688829442;4685.1332503486965;4716.95283047172;4733.597644200213;54996.725016375465;80385.59807200926;88707;91644.54177729068;92089;68540.6572967132;81452.18547814565;81822;82412;83799.16200838036;48071.75964120157;54676;61574;67569;70051.2994870055;9494.335396522178;10010.349327293703;10112.696619101438;10159.23805714589;10315.48422578883;46099.76950115228;46319.76840115913;46357;46456.767716162554;47354.289685654054;57691;60484;61094.694526527084;62574.37425625868;62847.68576157273;12379;12809.487620495076;12814.871851281368;12999.805002925154;13204.207747535178;14711.779323310373;14871.628209294855;15343.616409589404;15361.155136467602;15682.607934801721;13686.589402317946;13881.652958676113;14659.41362345537;14767.778483322543;14940.252987350372;13850.584482465541;14219;14292.785608216092;14337.426502939765;14483.275836208359;12323.50705971751;13130.671733206746;13519.526816561372;14253.643658908195;14694.926525367304;11005.446229019273;11633.243839150427;11674.858147526478;11876.76246475083;12217.328046957502;10721.517531711248;11469.919330357405;11548.480318385526;11626.70933226676;11710.355930423908 11:26:37 INFO - PROCESS | 2189 | __end_tp_report 11:26:37 INFO - PROCESS | 2189 | __start_cc_report 11:26:37 INFO - PROCESS | 2189 | _x_x_mozilla_cycle_collect,314 11:26:37 INFO - PROCESS | 2189 | __end_cc_report 11:26:37 INFO - PROCESS | 2189 | __startTimestamp1487791597498__endTimestamp 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | ------- Summary: start ------- 11:26:37 INFO - PROCESS | 2189 | Number of tests: 6 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | [#0] dojo.html Cycles:165 Average:5547.13 Median:5769.62 stddev:617.71 (10.7%) stddev-sans-first:590.60 11:26:37 INFO - PROCESS | 2189 | Values: 3162.5 3186.8 3205.8 3206.6 3209.6 5544.0 5591.3 5592.1 5600.5 5626.4 5550.1 5551.9 5563.2 5596.7 5597.3 5056.4 5106.6 5139.8 5154.1 5169.4 5122.7 5176.2 5181.1 5186.2 5192.9 5019.8 5059.7 5148.8 5148.9 5158.2 5081.4 5098.5 5165.0 5189.2 5221.3 5109.9 5154.6 5159.6 5165.9 5177.7 5338.7 5538.0 5557.5 5635.4 5639.1 5769.6 5809.2 5835.8 5879.6 5950.4 5871.7 5943.2 5944.0 5952.5 5972.0 5547.2 5549.5 5556.8 5570.8 5579.8 6009.9 6058.1 6079.2 6103.2 6109.8 5834.9 5886.6 5890.7 5890.9 5897.6 5729.8 5777.0 5981.7 5981.8 5985.9 5793.1 5827.1 5847.0 5854.5 5889.0 5828.8 5834.2 5879.6 5891.1 5893.6 4480.9 4587.1 4599.9 4607.2 4617.9 4011.8 4082.2 4108.5 4130.4 4185.7 5751.0 5784.5 5790.2 5795.4 5800.9 5982.4 6053.3 6059.1 6062.5 6075.2 5571.2 5635.5 5673.7 5709.1 5750.0 5198.0 5220.1 5235.3 5254.7 5267.8 6260.1 6271.5 6290.6 6295.3 6303.4 5708.2 5742.2 5896.9 5909.5 5967.9 6207.6 6229.6 6242.7 6244.2 6245.0 5794.2 5836.4 5853.9 5869.1 5875.9 5857.8 5892.2 5911.0 5996.0 6008.0 5789.1 5820.4 5821.0 5841.9 5874.6 5784.1 5814.0 5834.1 5837.0 5855.4 5513.6 5590.0 5731.3 5792.9 5798.2 5782.5 5922.7 5924.4 5949.3 5965.9 5691.5 5745.2 5758.4 5760.7 5763.7 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | [#1] ext.html Cycles:180 Average:48857.60 Median:42058.69 stddev:82018.95 (195.0%) stddev-sans-first:82169.04 11:26:37 INFO - PROCESS | 2189 | Values: 610.6 640.0 648.0 664.5 684.4 39376.2 39824.0 39888.6 39945.2 40031.8 44271.6 44676.1 44755.6 45067.8 45100.5 9027.9 9163.3 9174.0 9182.3 9236.0 9019.3 9079.0 9088.7 9091.6 9133.0 9163.3 9222.3 9238.8 9239.3 9239.7 9042.3 9126.0 9140.7 9190.6 9202.7 8903.3 8971.6 8993.1 8997.2 8999.6 42483.8 42632.1 42661.0 42752.8 42795.1 3705.6 3786.3 3825.2 3830.0 3861.7 31928.4 33143.3 33471.5 33473.0 33631.0 4544.0 4671.8 4684.6 4695.4 4817.5 343178.0 348807.0 349046.0 349369.0 351958.0 61050.0 61099.4 61451.4 61472.8 61487.4 63855.4 64035.0 64146.4 64159.7 64229.7 18299.6 18427.5 18482.7 18485.4 18557.4 14253.6 14287.6 14310.9 14316.9 14319.2 16762.0 17472.6 17492.2 17884.6 17996.9 6591.5 6626.8 6631.4 6636.9 6810.3 937.4 993.7 1031.9 1049.8 1118.2 32145.8 32414.0 32419.9 32434.5 33044.3 1075.6 1141.1 1150.5 1180.7 1213.4 16784.3 17186.2 17233.3 17381.0 17548.8 141336.0 142079.0 142283.0 142917.3 143076.3 61645.0 61655.4 61726.7 61746.4 61933.0 1381.6 1416.6 1419.6 1431.4 1432.0 30691.2 32174.4 32484.0 32672.8 32677.7 52537.7 52764.7 52840.0 52882.5 52889.7 383645.0 384720.0 385482.0 385982.0 386714.0 42956.8 43850.0 44221.8 44357.3 44371.3 49241.5 49296.5 49615.3 49760.0 50253.7 39751.9 40844.6 41846.0 41877.6 42781.8 41368.2 41542.2 42239.8 43352.8 43471.0 40826.2 41424.8 42352.6 42355.8 42749.0 35512.8 37450.0 37650.4 38004.4 38147.0 28407.9 28584.4 28724.4 28747.9 28872.4 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | [#2] jquery.html Cycles:180 Average:28469.53 Median:3198.02 stddev:74495.24 (2329.4%) stddev-sans-first:74674.35 11:26:37 INFO - PROCESS | 2189 | Values: 377.3 385.1 416.9 423.8 424.6 16130.6 18873.5 19281.1 19478.6 19498.0 19760.0 20073.7 20892.2 20999.9 21041.9 5644.9 5969.9 5997.3 6012.6 6025.3 5850.4 5923.6 5988.1 6014.9 6030.2 5998.9 6000.8 6015.5 6020.1 6026.7 5785.1 5923.0 5976.6 5986.6 6013.5 5404.9 5416.5 5419.2 5421.1 5433.0 23526.9 24108.6 24313.3 24333.1 24493.4 2586.7 2603.2 2691.5 3172.9 3223.1 14050.6 15256.4 15282.7 15515.8 15970.8 2026.9 2054.9 2085.9 2106.9 2110.8 71580.6 82353.6 82763.6 85367.0 86695.6 11293.9 11331.3 11445.0 11546.5 11842.2 16193.8 16202.6 16219.2 33316.3 33609.5 10308.6 10479.4 10593.8 10794.5 10817.4 7983.2 8662.5 8817.6 8861.0 9198.3 8890.5 9060.8 9148.5 9269.5 9299.4 3339.3 3543.9 3599.1 3670.3 3704.8 906.6 978.9 981.7 1027.8 1101.8 17598.5 19458.0 20107.9 20191.5 20373.0 953.2 1017.6 1036.1 1040.5 1051.4 10387.2 10878.7 11127.5 11153.7 11175.3 442799.0 446542.0 448321.0 454907.0 463607.0 88748.0 90485.0 90876.1 91748.5 92318.0 999.3 1017.2 1021.8 1024.3 1037.5 16622.8 16886.6 17414.0 17565.6 17780.4 38047.6 38251.8 38559.4 39371.4 40539.4 56742.0 57103.7 57658.7 57912.4 58144.7 8241.5 8657.5 8784.5 8811.9 8853.7 10228.6 11996.7 12121.3 12499.2 12597.7 10531.8 10604.8 10699.2 10808.0 11326.9 11555.0 11650.3 11680.4 11710.4 11846.2 11251.9 11576.9 11624.7 11634.7 11837.4 8592.2 8631.7 8695.7 8864.0 8910.2 8379.9 11179.7 11195.4 11222.1 11440.1 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | [#3] mootools.html Cycles:180 Average:5898.71 Median:489.16 stddev:3337.26 (682.2%) stddev-sans-first:3330.22 11:26:37 INFO - PROCESS | 2189 | Values: 1495.8 1498.7 1657.1 1718.5 1771.5 7823.7 7987.8 8446.1 9096.4 9097.5 8496.5 8710.7 8751.2 9011.1 9012.2 2446.8 2468.1 2477.9 2483.7 2488.2 2383.3 2473.3 2474.9 2476.7 2478.2 2402.3 2406.5 2435.8 2439.1 2449.0 2315.6 2321.4 2328.5 2329.7 2362.2 2380.1 2392.0 2400.1 2400.3 2402.7 8739.6 8813.1 8861.6 8866.2 8904.7 5400.5 5521.6 5531.8 5557.9 5566.6 3434.6 3438.2 3442.0 3471.9 3500.1 1134.3 1148.6 1186.2 1189.5 1208.9 12507.6 12564.4 12564.9 12607.9 12634.2 4721.7 4768.6 4788.9 4856.2 4937.6 10493.2 10515.4 10532.9 10537.9 10556.7 5384.4 5553.3 5688.6 5689.8 5693.9 4940.6 5086.5 5114.8 5172.4 5187.7 4018.5 4025.9 4049.2 4126.5 4152.0 1194.8 1195.4 1196.4 1202.2 1223.6 982.8 995.7 1009.5 1009.6 1014.9 6016.9 6103.2 6155.9 6199.8 6274.5 456.9 482.0 486.1 492.2 986.2 3749.3 3918.6 3938.9 3951.9 3994.8 10820.8 11148.1 11159.9 11205.8 11228.7 11017.8 11051.6 11171.0 11175.4 11231.6 8401.2 8446.7 8521.9 8522.8 8662.4 5741.8 5960.5 6005.5 6027.7 6033.5 9942.1 10268.4 10314.7 10428.5 10443.6 9323.6 9619.2 9693.2 10073.3 10380.0 7506.3 7804.6 7824.5 7906.8 8030.6 8856.1 8879.3 8895.4 8898.1 8969.1 7581.5 7865.9 7895.7 8049.6 8049.9 7339.4 7371.3 7435.9 7530.6 7561.2 7161.6 7213.7 7271.4 7306.2 7308.8 5203.6 5314.7 5372.4 5384.7 5393.9 5361.1 5369.2 5393.2 5395.2 5431.1 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | [#4] prototype.html Cycles:180 Average:4909.02 Median:5567.89 stddev:1441.30 (25.9%) stddev-sans-first:1403.73 11:26:37 INFO - PROCESS | 2189 | Values: 327.7 333.4 335.5 348.3 351.1 5598.3 5671.4 5690.2 5698.4 5705.7 5539.6 5549.9 5561.2 5574.6 5617.3 4190.8 4317.3 4324.6 4340.2 4376.5 4353.6 4362.7 4365.4 4387.4 4387.6 4333.4 4334.3 4355.4 4356.3 4372.9 4113.3 4281.7 4355.0 4355.3 4372.4 3839.6 3842.5 3843.3 3844.7 3845.8 5449.7 5454.1 5469.5 5487.7 5505.4 4908.9 4938.1 4981.2 4984.6 5008.3 5774.7 5778.7 5781.6 5801.9 5803.4 4222.4 4228.4 4231.3 4233.3 4237.3 5900.5 6003.7 6038.8 6052.9 6103.2 4309.6 4321.8 4326.2 4332.5 4337.6 4350.3 4362.6 4376.6 4398.6 4399.8 4842.0 4894.9 4936.0 4994.3 5002.3 5814.0 5826.2 5843.2 5875.8 5905.7 1454.0 1454.4 1457.7 1458.6 1460.9 981.8 993.3 1003.4 1013.9 1016.5 5440.7 5480.6 5480.9 5522.3 5524.8 5782.3 5803.4 5818.0 5841.5 5870.0 5266.2 5495.8 5512.3 5532.1 5541.3 3083.6 3087.3 3101.0 3105.2 3111.5 5716.9 5987.7 6021.8 6027.6 6033.6 5798.6 5827.2 5828.4 5867.4 5891.5 5973.4 5994.4 6028.4 6042.6 6050.4 5712.9 5769.9 6079.7 6090.0 6092.9 6023.6 6027.3 6038.5 6052.6 6078.7 6209.7 6296.2 6360.5 6373.8 6388.4 4270.4 4277.1 4281.4 4285.0 4293.8 5776.9 6051.5 6058.9 6120.3 6123.6 5868.9 5877.6 5880.3 5885.4 5895.8 5878.6 5881.0 5885.9 5903.5 5936.2 5635.7 5775.4 5797.2 5803.9 5866.3 5883.0 5913.3 5921.4 5922.3 5949.8 5598.8 5761.3 5766.3 5805.7 5827.4 11:26:37 INFO - PROCESS | 2189 | 11:26:37 INFO - PROCESS | 2189 | [#5] yui.html Cycles:180 Average:17792.88 Median:25316.49 stddev:22966.53 (90.7%) stddev-sans-first:22995.41 11:26:37 INFO - PROCESS | 2189 | Values: 776.7 777.9 813.8 833.7 848.2 14153.5 15009.8 15525.0 15530.8 15884.2 14499.0 15880.4 15918.6 16026.4 16070.5 835.2 857.5 857.6 859.3 861.2 827.4 854.8 859.4 862.5 864.1 896.3 898.3 899.2 900.8 902.2 892.7 893.8 895.8 902.0 902.0 660.6 661.3 663.5 664.2 664.4 13986.3 14829.0 14837.6 14863.2 14872.5 13275.9 13478.2 14127.7 14175.5 14208.7 10632.1 10755.5 10888.4 11013.5 11042.7 3748.9 3756.3 3767.1 3778.5 3855.0 64795.4 65659.0 65771.0 65826.0 67625.7 11321.4 11414.7 11425.1 11559.1 11649.1 20222.6 20308.1 20316.6 20344.2 20346.9 7826.3 7970.3 8099.7 8165.8 8271.5 4374.2 4405.8 4482.2 4487.7 4489.4 3301.8 3413.0 3441.8 3467.2 3483.9 613.9 615.3 619.4 625.2 644.2 696.4 698.8 707.4 714.9 724.1 8636.3 8783.0 8861.2 8879.7 9167.4 624.8 655.1 666.3 680.5 684.9 4341.2 4533.2 4685.1 4717.0 4733.6 54996.7 80385.6 88707.0 91644.5 92089.0 68540.7 81452.2 81822.0 82412.0 83799.2 48071.8 54676.0 61574.0 67569.0 70051.3 9494.3 10010.3 10112.7 10159.2 10315.5 46099.8 46319.8 46357.0 46456.8 47354.3 57691.0 60484.0 61094.7 62574.4 62847.7 12379.0 12809.5 12814.9 12999.8 13204.2 14711.8 14871.6 15343.6 15361.2 15682.6 13686.6 13881.7 14659.4 14767.8 14940.3 13850.6 14219.0 14292.8 14337.4 14483.3 12323.5 13130.7 13519.5 14253.6 14694.9 11005.4 11633.2 11674.9 11876.8 12217.3 10721.5 11469.9 11548.5 11626.7 11710.4 11:26:37 INFO - PROCESS | 2189 | -------- Summary: end -------- 11:26:37 INFO - PROCESS | 2189 | 11:26:38 INFO - TEST-INFO | 2189: exit 0 11:26:38 INFO - TEST-OK | dromaeo_css | took 1112616ms 11:26:38 INFO - TEST-START | kraken 11:26:38 INFO - operating with platform_type : linux_ 11:26:38 INFO - Initialising browser for kraken test... 11:26:38 INFO - TEST-INFO | started process 2645 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpugc48d/profile http://localhost:56487/getInfo.html) 11:26:38 CRITICAL - PROCESS | 2645 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 11:26:38 INFO - TEST-INFO | 2645: exit 127 11:26:38 INFO - Could not find __metrics(.*)__metrics in browser output 11:26:38 CRITICAL - Raw results:Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 11:26:38 INFO - TEST-UNEXPECTED-ERROR | kraken | browser failed to close after being initialized 11:26:38 ERROR - Traceback (most recent call last): 11:26:38 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 11:26:38 INFO - talos_results.add(mytest.runTest(browser_config, test)) 11:26:38 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 69, in runTest 11:26:38 INFO - with FFSetup(browser_config, test_config) as setup: 11:26:38 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 165, in __enter__ 11:26:38 INFO - self._run_profile() 11:26:38 INFO - File "/builds/slave/test/build/tests/talos/talos/ffsetup.py", line 141, in _run_profile 11:26:38 INFO - raise TalosError("browser failed to close after being initialized") 11:26:38 INFO - TalosError: browser failed to close after being initialized 11:26:38 INFO - TEST-INFO took 277ms 11:26:38 INFO - SUITE-END | took 1112s 11:26:38 ERROR - Return code: 2 11:26:38 WARNING - setting return code to 2 11:26:38 CRITICAL - # TBPL RETRY # 11:26:38 WARNING - setting return code to 4 11:26:38 INFO - Running post-action listener: _resource_record_post_action 11:26:38 INFO - [mozharness: 2017-02-22 19:26:38.651985Z] Finished run-tests step (success) 11:26:38 INFO - Running post-run listener: _resource_record_post_run 11:26:38 INFO - Total resource usage - Wall time: 1127s; CPU: 13.0%; Read bytes: 40960; Write bytes: 257531904; Read time: 72; Write time: 188056 11:26:38 INFO - TinderboxPrint: CPU usage
13.3% 11:26:38 INFO - TinderboxPrint: I/O read bytes / time
40,960 / 72 11:26:38 INFO - TinderboxPrint: I/O write bytes / time
257,531,904 / 188,056 11:26:38 INFO - TinderboxPrint: CPU idle
7,799.0 (86.7%) 11:26:38 INFO - TinderboxPrint: CPU user
1,156.0 (12.9%) 11:26:38 INFO - TinderboxPrint: Swap in / out
0 / 0 11:26:38 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 101072896; Read time: 0; Write time: 83796 11:26:38 INFO - run-tests - Wall time: 1113s; CPU: 13.0%; Read bytes: 36864; Write bytes: 156459008; Read time: 52; Write time: 104260 11:26:39 INFO - Running post-run listener: _upload_blobber_files 11:26:39 INFO - Blob upload gear active. 11:26:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:26:39 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 11:26:39 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:26:39 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:26:39 INFO - (blobuploader) - INFO - Open directory for files ... 11:26:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:26:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:26:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:40 INFO - (blobuploader) - INFO - Done attempting. 11:26:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:26:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:26:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:41 INFO - (blobuploader) - INFO - Done attempting. 11:26:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:26:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:26:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:26:44 INFO - (blobuploader) - INFO - Done attempting. 11:26:44 INFO - (blobuploader) - INFO - Iteration through files over. 11:26:44 INFO - Return code: 0 11:26:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:26:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:26:44 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/921ff9d29d23ddfac39f9e4acd9dadb70da361e96f25a703636e6c9e24e0fc6506464ba7a1ca8f04c84f8cc2f445b33078cd52b40c3a046c789bd11bb3f51496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20710cddf155b9550c2d3a6fb56b3a36bd301e9ce286a2255559c22fa40ac632b39717a5c7363c16926ed1ed21195bf6484a06f81556594f9cbf01252cf58514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92b1d11ee754f13653c0fc540ea30b9c6bb08c6a42b686e5842b7d2a13a7c501a192f8dcb539d2dd10dfb9f9db7f7394729a9c3554fbb96f176ae5fd2bcc04ce"} 11:26:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:26:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:26:44 INFO - Contents: 11:26:44 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/921ff9d29d23ddfac39f9e4acd9dadb70da361e96f25a703636e6c9e24e0fc6506464ba7a1ca8f04c84f8cc2f445b33078cd52b40c3a046c789bd11bb3f51496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20710cddf155b9550c2d3a6fb56b3a36bd301e9ce286a2255559c22fa40ac632b39717a5c7363c16926ed1ed21195bf6484a06f81556594f9cbf01252cf58514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92b1d11ee754f13653c0fc540ea30b9c6bb08c6a42b686e5842b7d2a13a7c501a192f8dcb539d2dd10dfb9f9db7f7394729a9c3554fbb96f176ae5fd2bcc04ce"} 11:26:44 INFO - Running post-run listener: copy_logs_to_upload_dir 11:26:44 INFO - Copying logs to upload dir... 11:26:44 INFO - mkdir: /builds/slave/test/build/upload/logs 11:26:44 INFO - Copying logs to upload dir... 11:26:44 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1171.036393 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 31 secs) (at 2017-02-22 11:26:44.664784) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 11:26:44.667948) ========= 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=14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/921ff9d29d23ddfac39f9e4acd9dadb70da361e96f25a703636e6c9e24e0fc6506464ba7a1ca8f04c84f8cc2f445b33078cd52b40c3a046c789bd11bb3f51496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20710cddf155b9550c2d3a6fb56b3a36bd301e9ce286a2255559c22fa40ac632b39717a5c7363c16926ed1ed21195bf6484a06f81556594f9cbf01252cf58514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92b1d11ee754f13653c0fc540ea30b9c6bb08c6a42b686e5842b7d2a13a7c501a192f8dcb539d2dd10dfb9f9db7f7394729a9c3554fbb96f176ae5fd2bcc04ce"} build_url:https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011498 build_url: 'https://queue.taskcluster.net/v1/task/H_9nKlT7RMeXdJt0gDSFxA/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/921ff9d29d23ddfac39f9e4acd9dadb70da361e96f25a703636e6c9e24e0fc6506464ba7a1ca8f04c84f8cc2f445b33078cd52b40c3a046c789bd11bb3f51496", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20710cddf155b9550c2d3a6fb56b3a36bd301e9ce286a2255559c22fa40ac632b39717a5c7363c16926ed1ed21195bf6484a06f81556594f9cbf01252cf58514", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/92b1d11ee754f13653c0fc540ea30b9c6bb08c6a42b686e5842b7d2a13a7c501a192f8dcb539d2dd10dfb9f9db7f7394729a9c3554fbb96f176ae5fd2bcc04ce"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-22 11:26:44.692526) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:26:44.692855) ========= 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=14ef55db36f7623abb31b9cf000001d5-1487790431.952597-641672156 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004483 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-22 11:26:44.777317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 11:26:44.777633) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-22 11:26:44.777937) ========= ========= Total master_lag: 0.17 =========