builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-077 starttime: 1487886922.69 results: retry (5) buildid: 20170223120822 builduid: 86366fe5fc1c40fb827c97dbe3e79bed revision: 8a654cb6a61e3c051d50542cc67b61943e4fbf80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.686401) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.686914) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.687212) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.704118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.704454) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-23 13:55:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.5M=0s 2017-02-23 13:55:23 (46.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.867230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.867514) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020853 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:22.925126) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 13:55:22.925473) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8a654cb6a61e3c051d50542cc67b61943e4fbf80 --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 8a654cb6a61e3c051d50542cc67b61943e4fbf80 --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=20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-23 13:55:23,257 truncating revision to first 12 chars 2017-02-23 13:55:23,257 Setting DEBUG logging. 2017-02-23 13:55:23,257 attempt 1/10 2017-02-23 13:55:23,257 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8a654cb6a61e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-23 13:55:24,631 unpacking tar archive at: mozilla-beta-8a654cb6a61e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.715540 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 13:55:24.654209) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:24.654507) ========= script_repo_revision: 8a654cb6a61e3c051d50542cc67b61943e4fbf80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:24.654875) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:24.655126) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 13:55:24.664158) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 40 secs) (at 2017-02-23 13:55:24.664448) ========= /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=20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:55:25 INFO - MultiFileLogger online at 20170223 13:55:25 in /builds/slave/test 13:55:25 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 13:55:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:55:25 INFO - {'append_to_log': False, 13:55:25 INFO - 'base_work_dir': '/builds/slave/test', 13:55:25 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:55:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:55:25 INFO - 'branch': 'Mozilla-Beta', 13:55:25 INFO - 'buildbot_json_path': 'buildprops.json', 13:55:25 INFO - 'config_files': ('talos/linux_config.py',), 13:55:25 INFO - 'default_actions': ('clobber', 13:55:25 INFO - 'read-buildbot-config', 13:55:25 INFO - 'download-and-extract', 13:55:25 INFO - 'populate-webroot', 13:55:25 INFO - 'create-virtualenv', 13:55:25 INFO - 'install', 13:55:25 INFO - 'run-tests'), 13:55:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:55:25 INFO - 'download_minidump_stackwalk': True, 13:55:25 INFO - 'download_symbols': 'ondemand', 13:55:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:55:25 INFO - 'tooltool.py': '/tools/tooltool.py', 13:55:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:55:25 INFO - '/tools/misc-python/virtualenv.py')}, 13:55:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:55:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:55:25 INFO - 'installer_path': 'installer.exe', 13:55:25 INFO - 'log_level': 'info', 13:55:25 INFO - 'log_name': 'talos', 13:55:25 INFO - 'log_to_console': True, 13:55:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:55:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:55:25 INFO - 'opt_config_files': (), 13:55:25 INFO - 'pip_index': False, 13:55:25 INFO - 'sps_profile': False, 13:55:25 INFO - 'sps_profile_interval': 0, 13:55:25 INFO - 'suite': 'dromaeojs-e10s', 13:55:25 INFO - 'system_bits': '32', 13:55:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:55:25 INFO - 'title': 'talos-linux64-ix-077', 13:55:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:55:25 INFO - 'use_talos_json': True, 13:55:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:55:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:55:25 INFO - 'work_dir': 'build'} 13:55:25 INFO - [mozharness: 2017-02-23 21:55:25.087917Z] Running clobber step. 13:55:25 INFO - Running pre-action listener: _resource_record_pre_action 13:55:25 INFO - Running main action method: clobber 13:55:25 INFO - rmtree: /builds/slave/test/build 13:55:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:55:26 INFO - Running post-action listener: _resource_record_post_action 13:55:26 INFO - [mozharness: 2017-02-23 21:55:26.499640Z] Finished clobber step (success) 13:55:26 INFO - [mozharness: 2017-02-23 21:55:26.499738Z] Running read-buildbot-config step. 13:55:26 INFO - Running pre-action listener: _resource_record_pre_action 13:55:26 INFO - Running main action method: read_buildbot_config 13:55:26 INFO - Using buildbot properties: 13:55:26 INFO - { 13:55:26 INFO - "project": "", 13:55:26 INFO - "product": "firefox", 13:55:26 INFO - "script_repo_revision": "production", 13:55:26 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 13:55:26 INFO - "repository": "", 13:55:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 13:55:26 INFO - "buildid": "20170223120822", 13:55:26 INFO - "pgo_build": "True", 13:55:26 INFO - "basedir": "/builds/slave/test", 13:55:26 INFO - "buildnumber": 82, 13:55:26 INFO - "slavename": "talos-linux64-ix-077", 13:55:26 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:55:26 INFO - "platform": "ubuntu64_hw", 13:55:26 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:55:26 INFO - "branch": "mozilla-beta", 13:55:26 INFO - "repo_path": "releases/mozilla-beta", 13:55:26 INFO - "revision": "8a654cb6a61e3c051d50542cc67b61943e4fbf80", 13:55:26 INFO - "stage_platform": "linux64-pgo", 13:55:26 INFO - "builduid": "86366fe5fc1c40fb827c97dbe3e79bed", 13:55:26 INFO - "slavebuilddir": "test" 13:55:26 INFO - } 13:55:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 13:55:26 INFO - Running post-action listener: _resource_record_post_action 13:55:26 INFO - [mozharness: 2017-02-23 21:55:26.502134Z] Finished read-buildbot-config step (success) 13:55:26 INFO - [mozharness: 2017-02-23 21:55:26.502222Z] Running download-and-extract step. 13:55:26 INFO - Running pre-action listener: _resource_record_pre_action 13:55:26 INFO - Running main action method: download_and_extract 13:55:26 INFO - mkdir: /builds/slave/test/build/tests 13:55: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')]} 13:55:26 INFO - trying https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 13:55:26 INFO - Downloading https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/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 13:55:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/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 13:55:27 INFO - Downloaded 1327 bytes. 13:55:27 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 13:55:27 INFO - Using the following test package requirements: 13:55:27 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 13:55:27 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:55:27 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 13:55:27 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'jsshell-linux-x86_64.zip'], 13:55:27 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:55:27 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 13:55:27 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 13:55:27 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 13:55:27 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:55:27 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 13:55:27 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:55:27 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:55:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 13:55:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:55:27 INFO - Fetch https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 13:55:27 INFO - Http code: 200 13:55:27 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 13:55:27 INFO - x-amz-cf-id: siQyfyvj8nXNu4NEz6FA7h9ZA6IDHQuCoVp0civhTJLqTxzrXB4oKQ== 13:55:27 INFO - x-amz-version-id: kMeiXwKjEdDQ7pL4VG1MjXGFrxc9tpjy 13:55:27 INFO - Expected file size: 21963586 13:55:27 INFO - Obtained file size: 21963586 13:55:32 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 13:55:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 13:55:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:55:32 INFO - Fetch https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 13:55:32 INFO - Http code: 200 13:55:32 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 13:55:32 INFO - x-amz-cf-id: qoH76dkAJ_V-QdjYYd62tpZPMPZsAZPA67i19cxRUdMtl0RJC7GodQ== 13:55:32 INFO - x-amz-version-id: kMeiXwKjEdDQ7pL4VG1MjXGFrxc9tpjy 13:55:32 INFO - Expected file size: 21963586 13:55:32 INFO - Obtained file size: 21963586 13:55:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 13:55:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:55:37 INFO - Fetch https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 13:55:37 INFO - Http code: 200 13:55:37 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 13:55:37 INFO - x-amz-cf-id: 8k0Kjran0DCV7wWB1RcSOtVKRqHzHrb2AbW--jEjplxUFQ43qBd0fQ== 13:55:37 INFO - x-amz-version-id: BeZgSkAz7RyjLQKc_Tb0P.cfwRXqgaQj 13:55:38 INFO - Expected file size: 14402992 13:55:38 INFO - Obtained file size: 14402992 13:55:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:38 INFO - trying https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:55:38 INFO - Downloading https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/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 13:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/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 13:55:41 INFO - Downloaded 57761252 bytes. 13:55:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:55:41 INFO - mkdir: /builds/slave/test/properties 13:55:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:55:41 INFO - Writing to file /builds/slave/test/properties/build_url 13:55:41 INFO - Contents: 13:55:41 INFO - build_url:https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 13:55:42 INFO - Running post-action listener: _resource_record_post_action 13:55:42 INFO - Running post-action listener: set_extra_try_arguments 13:55:42 INFO - [mozharness: 2017-02-23 21:55:42.281275Z] Finished download-and-extract step (success) 13:55:42 INFO - [mozharness: 2017-02-23 21:55:42.281502Z] Running populate-webroot step. 13:55:42 INFO - Running pre-action listener: _resource_record_pre_action 13:55:42 INFO - Running main action method: populate_webroot 13:55:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:55:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:55:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:55:42 INFO - '--tppagecycles', 13:55:42 INFO - '7'], 13:55:42 INFO - 'tests': ['tsvgm']}}, 13:55:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:55:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:55:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:55:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:55:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:55:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:55:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['damp', 'tps']}, 13:55:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'tests': ['damp', 'tps']}, 13:55:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['dromaeo_dom']}, 13:55:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:55:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['basic_compositor_video']}, 13:55:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:55:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['a11yr', 13:55:42 INFO - 'ts_paint', 13:55:42 INFO - 'tpaint', 13:55:42 INFO - 'sessionrestore', 13:55:42 INFO - 'sessionrestore_no_auto_restore', 13:55:42 INFO - 'tabpaint']}, 13:55:42 INFO - 'other-e10s': {'tests': ['a11yr', 13:55:42 INFO - 'ts_paint', 13:55:42 INFO - 'tpaint', 13:55:42 INFO - 'sessionrestore', 13:55:42 INFO - 'sessionrestore_no_auto_restore', 13:55:42 INFO - 'tabpaint']}, 13:55:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:55:42 INFO - 'ts_paint', 13:55:42 INFO - 'tpaint', 13:55:42 INFO - 'sessionrestore', 13:55:42 INFO - 'sessionrestore_no_auto_restore', 13:55:42 INFO - 'tabpaint']}, 13:55:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:55:42 INFO - 'ts_paint', 13:55:42 INFO - 'tpaint', 13:55:42 INFO - 'sessionrestore', 13:55:42 INFO - 'sessionrestore_no_auto_restore', 13:55:42 INFO - 'tabpaint']}, 13:55:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['a11yr', 13:55:42 INFO - 'ts_paint', 13:55:42 INFO - 'tpaint', 13:55:42 INFO - 'sessionrestore', 13:55:42 INFO - 'sessionrestore_no_auto_restore', 13:55:42 INFO - 'tabpaint']}, 13:55:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['a11yr', 13:55:42 INFO - 'ts_paint', 13:55:42 INFO - 'tpaint', 13:55:42 INFO - 'sessionrestore', 13:55:42 INFO - 'sessionrestore_no_auto_restore', 13:55:42 INFO - 'tabpaint']}, 13:55:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['tsvgx', 13:55:42 INFO - 'tsvgr_opacity', 13:55:42 INFO - 'tart', 13:55:42 INFO - 'tscrollx', 13:55:42 INFO - 'cart']}, 13:55:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:55:42 INFO - 'tsvgr_opacity', 13:55:42 INFO - 'tart', 13:55:42 INFO - 'tscrollx', 13:55:42 INFO - 'cart']}, 13:55:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'talos_options': ['--disable-e10s'], 13:55:42 INFO - 'tests': ['tp5o']}, 13:55:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'tests': ['tp5o']}, 13:55:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'talos_options': ['--disable-e10s', 13:55:42 INFO - '--xperf_path', 13:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:55:42 INFO - 'tests': ['tp5n']}, 13:55:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:55:42 INFO - 'talos_options': ['--xperf_path', 13:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:55:42 INFO - 'tests': ['tp5n']}}, 13:55:42 INFO - 'talos.zip': {'path': '', 13:55:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:55:42 INFO - Running post-action listener: _resource_record_post_action 13:55:42 INFO - [mozharness: 2017-02-23 21:55:42.301075Z] Finished populate-webroot step (success) 13:55:42 INFO - [mozharness: 2017-02-23 21:55:42.301163Z] Running create-virtualenv step. 13:55:42 INFO - Running pre-action listener: _resource_record_pre_action 13:55:42 INFO - Running main action method: create_virtualenv 13:55:42 INFO - Creating virtualenv /builds/slave/test/build/venv 13:55:42 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 13:55:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:55:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:55:42 INFO - Using real prefix '/usr' 13:55:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:55:42 INFO - Installing distribute.............................................................................................................................................................................................done. 13:55:44 INFO - Installing pip.................done. 13:55:44 INFO - Return code: 0 13:55:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:55:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:55:45 INFO - Reading from file tmpfile_stdout 13:55:45 INFO - Output received: 13:55:45 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) 13:55:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:55:45 INFO - Using env: {'DISPLAY': ':0', 13:55:45 INFO - 'HOME': '/home/cltbld', 13:55:45 INFO - 'LANG': 'en_US.UTF-8', 13:55:45 INFO - 'LANGUAGE': 'en_US:en', 13:55:45 INFO - 'LOGNAME': 'cltbld', 13:55:45 INFO - 'MAIL': '/var/mail/cltbld', 13:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:45 INFO - 'MOZ_NO_REMOTE': '1', 13:55:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:45 INFO - 'NO_EM_RESTART': '1', 13:55:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:45 INFO - 'PWD': '/builds/slave/test', 13:55:45 INFO - 'SHELL': '/bin/bash', 13:55:45 INFO - 'SHLVL': '1', 13:55:45 INFO - 'TERM': 'linux', 13:55:45 INFO - 'TMOUT': '86400', 13:55:45 INFO - 'USER': 'cltbld', 13:55:45 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:45 INFO - '_': '/tools/buildbot/bin/python'} 13:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:45 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 13:55:45 INFO - Cleaning up... 13:55:45 INFO - Return code: 0 13:55:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:55:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:55:45 INFO - Using env: {'DISPLAY': ':0', 13:55:45 INFO - 'HOME': '/home/cltbld', 13:55:45 INFO - 'LANG': 'en_US.UTF-8', 13:55:45 INFO - 'LANGUAGE': 'en_US:en', 13:55:45 INFO - 'LOGNAME': 'cltbld', 13:55:45 INFO - 'MAIL': '/var/mail/cltbld', 13:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:45 INFO - 'MOZ_NO_REMOTE': '1', 13:55:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:45 INFO - 'NO_EM_RESTART': '1', 13:55:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:45 INFO - 'PWD': '/builds/slave/test', 13:55:45 INFO - 'SHELL': '/bin/bash', 13:55:45 INFO - 'SHLVL': '1', 13:55:45 INFO - 'TERM': 'linux', 13:55:45 INFO - 'TMOUT': '86400', 13:55:45 INFO - 'USER': 'cltbld', 13:55:45 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:45 INFO - '_': '/tools/buildbot/bin/python'} 13:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:45 INFO - Downloading/unpacking psutil>=3.1.1 13:55:45 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 13:55:45 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 13:55:45 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 13:55:45 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 13:55:45 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 13:55:45 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 13:55:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:55:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:55:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:55:47 INFO - Installing collected packages: psutil 13:55:47 INFO - Running setup.py install for psutil 13:55:47 INFO - building 'psutil._psutil_linux' extension 13:55:47 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 13:55:47 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 13:55:47 INFO - building 'psutil._psutil_posix' extension 13:55:47 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 13:55:48 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 13:55:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:55:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:55:48 INFO - Successfully installed psutil 13:55:48 INFO - Cleaning up... 13:55:48 INFO - Return code: 0 13:55:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:55:48 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')]} 13:55:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:48 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')]} 13:55:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:48 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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55:48 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 13:55:48 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 13:55:48 INFO - Using env: {'DISPLAY': ':0', 13:55:48 INFO - 'HOME': '/home/cltbld', 13:55:48 INFO - 'LANG': 'en_US.UTF-8', 13:55:48 INFO - 'LANGUAGE': 'en_US:en', 13:55:48 INFO - 'LOGNAME': 'cltbld', 13:55:48 INFO - 'MAIL': '/var/mail/cltbld', 13:55:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:48 INFO - 'MOZ_NO_REMOTE': '1', 13:55:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:48 INFO - 'NO_EM_RESTART': '1', 13:55:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:48 INFO - 'PWD': '/builds/slave/test', 13:55:48 INFO - 'SHELL': '/bin/bash', 13:55:48 INFO - 'SHLVL': '1', 13:55:48 INFO - 'TERM': 'linux', 13:55:48 INFO - 'TMOUT': '86400', 13:55:48 INFO - 'USER': 'cltbld', 13:55:48 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:48 INFO - '_': '/tools/buildbot/bin/python'} 13:55:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:55:48 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 13:55:48 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 13:55:48 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 13:55:48 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 13:55:48 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 13:55:48 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 13:55:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:55:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:55:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:55:50 INFO - Installing collected packages: mozsystemmonitor 13:55:50 INFO - Running setup.py install for mozsystemmonitor 13:55:50 INFO - Successfully installed mozsystemmonitor 13:55:50 INFO - Cleaning up... 13:55:50 INFO - Return code: 0 13:55:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:55: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')]} 13:55:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55: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')]} 13:55:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55: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==2.5.1'] in /builds/slave/test/build 13:55: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==2.5.1 13:55:50 INFO - Using env: {'DISPLAY': ':0', 13:55:50 INFO - 'HOME': '/home/cltbld', 13:55:50 INFO - 'LANG': 'en_US.UTF-8', 13:55:50 INFO - 'LANGUAGE': 'en_US:en', 13:55:50 INFO - 'LOGNAME': 'cltbld', 13:55:50 INFO - 'MAIL': '/var/mail/cltbld', 13:55:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:50 INFO - 'MOZ_NO_REMOTE': '1', 13:55:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:50 INFO - 'NO_EM_RESTART': '1', 13:55:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:50 INFO - 'PWD': '/builds/slave/test', 13:55:50 INFO - 'SHELL': '/bin/bash', 13:55:50 INFO - 'SHLVL': '1', 13:55:50 INFO - 'TERM': 'linux', 13:55:50 INFO - 'TMOUT': '86400', 13:55:50 INFO - 'USER': 'cltbld', 13:55:50 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:50 INFO - '_': '/tools/buildbot/bin/python'} 13:55:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:50 INFO - Downloading/unpacking jsonschema==2.5.1 13:55:50 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 13:55:50 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 13:55:50 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 13:55:50 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 13:55:50 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 13:55:50 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 13:55:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:55:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:55:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:55:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:55:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:55:53 INFO - Downloading functools32-3.2.3-2.tar.gz 13:55:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:55:53 INFO - warning: no files found matching '*.txt' 13:55:53 INFO - no previously-included directories found matching 'build' 13:55:53 INFO - no previously-included directories found matching 'dist' 13:55:53 INFO - no previously-included directories found matching '.git*' 13:55:53 INFO - Installing collected packages: jsonschema, functools32 13:55:53 INFO - Running setup.py install for functools32 13:55:53 INFO - warning: no files found matching '*.txt' 13:55:53 INFO - no previously-included directories found matching 'build' 13:55:53 INFO - no previously-included directories found matching 'dist' 13:55:53 INFO - no previously-included directories found matching '.git*' 13:55:53 INFO - Successfully installed jsonschema functools32 13:55:53 INFO - Cleaning up... 13:55:53 INFO - Return code: 0 13:55:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:55:53 INFO - Using env: {'DISPLAY': ':0', 13:55:53 INFO - 'HOME': '/home/cltbld', 13:55:53 INFO - 'LANG': 'en_US.UTF-8', 13:55:53 INFO - 'LANGUAGE': 'en_US:en', 13:55:53 INFO - 'LOGNAME': 'cltbld', 13:55:53 INFO - 'MAIL': '/var/mail/cltbld', 13:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:53 INFO - 'MOZ_NO_REMOTE': '1', 13:55:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:53 INFO - 'NO_EM_RESTART': '1', 13:55:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:53 INFO - 'PWD': '/builds/slave/test', 13:55:53 INFO - 'SHELL': '/bin/bash', 13:55:53 INFO - 'SHLVL': '1', 13:55:53 INFO - 'TERM': 'linux', 13:55:53 INFO - 'TMOUT': '86400', 13:55:53 INFO - 'USER': 'cltbld', 13:55:53 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:53 INFO - '_': '/tools/buildbot/bin/python'} 13:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:55:53 INFO - Cleaning up... 13:55:53 INFO - Return code: 0 13:55:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:55:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:55:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:55:53 INFO - Using env: {'DISPLAY': ':0', 13:55:53 INFO - 'HOME': '/home/cltbld', 13:55:53 INFO - 'LANG': 'en_US.UTF-8', 13:55:53 INFO - 'LANGUAGE': 'en_US:en', 13:55:53 INFO - 'LOGNAME': 'cltbld', 13:55:53 INFO - 'MAIL': '/var/mail/cltbld', 13:55:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:53 INFO - 'MOZ_NO_REMOTE': '1', 13:55:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:53 INFO - 'NO_EM_RESTART': '1', 13:55:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:53 INFO - 'PWD': '/builds/slave/test', 13:55:53 INFO - 'SHELL': '/bin/bash', 13:55:53 INFO - 'SHLVL': '1', 13:55:53 INFO - 'TERM': 'linux', 13:55:53 INFO - 'TMOUT': '86400', 13:55:53 INFO - 'USER': 'cltbld', 13:55:53 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:53 INFO - '_': '/tools/buildbot/bin/python'} 13:55:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:53 INFO - Downloading/unpacking blobuploader==1.2.4 13:55:53 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 13:55:53 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 13:55:53 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 13:55:53 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 13:55:53 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 13:55:53 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 13:55:55 INFO - Downloading blobuploader-1.2.4.tar.gz 13:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:55:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:55:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:55:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:55:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:55:56 INFO - Downloading docopt-0.6.1.tar.gz 13:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:55:56 INFO - Installing collected packages: blobuploader, requests, docopt 13:55:56 INFO - Running setup.py install for blobuploader 13:55:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:55:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:55:56 INFO - Running setup.py install for requests 13:55:56 INFO - Running setup.py install for docopt 13:55:57 INFO - Successfully installed blobuploader requests docopt 13:55:57 INFO - Cleaning up... 13:55:57 INFO - Return code: 0 13:55:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:55:57 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')]} 13:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:57 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')]} 13:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:55:57 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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:55:57 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 13:55:57 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 13:55:57 INFO - Using env: {'DISPLAY': ':0', 13:55:57 INFO - 'HOME': '/home/cltbld', 13:55:57 INFO - 'LANG': 'en_US.UTF-8', 13:55:57 INFO - 'LANGUAGE': 'en_US:en', 13:55:57 INFO - 'LOGNAME': 'cltbld', 13:55:57 INFO - 'MAIL': '/var/mail/cltbld', 13:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:55:57 INFO - 'MOZ_NO_REMOTE': '1', 13:55:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:55:57 INFO - 'NO_EM_RESTART': '1', 13:55:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:55:57 INFO - 'PWD': '/builds/slave/test', 13:55:57 INFO - 'SHELL': '/bin/bash', 13:55:57 INFO - 'SHLVL': '1', 13:55:57 INFO - 'TERM': 'linux', 13:55:57 INFO - 'TMOUT': '86400', 13:55:57 INFO - 'USER': 'cltbld', 13:55:57 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:55:57 INFO - '_': '/tools/buildbot/bin/python'} 13:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:55:57 INFO - Running setup.py (path:/tmp/pip-GxtN2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:55:57 INFO - Running setup.py (path:/tmp/pip-KM9AW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:55:57 INFO - Running setup.py (path:/tmp/pip-rAFOYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:55:57 INFO - Running setup.py (path:/tmp/pip-goIVNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:55:57 INFO - Running setup.py (path:/tmp/pip-pu0YVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:55:57 INFO - Running setup.py (path:/tmp/pip-nFHynx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:55:57 INFO - Running setup.py (path:/tmp/pip-rWaDkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:55:57 INFO - Running setup.py (path:/tmp/pip-kG93qS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:55:57 INFO - Running setup.py (path:/tmp/pip-fOEmlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:55:58 INFO - Running setup.py (path:/tmp/pip-Encsrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:55:58 INFO - Running setup.py (path:/tmp/pip-ox1u1w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:55:58 INFO - Running setup.py (path:/tmp/pip-fsRmwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:55:58 INFO - Running setup.py (path:/tmp/pip-zawQbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:55:58 INFO - Running setup.py (path:/tmp/pip-ntMIpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:55:58 INFO - Running setup.py (path:/tmp/pip-5vRShQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:55:58 INFO - Running setup.py (path:/tmp/pip-t1F2yv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:55:58 INFO - Running setup.py (path:/tmp/pip-nyajOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:55:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:55:58 INFO - Running setup.py install for manifestparser 13:55:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:55:58 INFO - Running setup.py install for mozcrash 13:55:58 INFO - Running setup.py install for mozdebug 13:55:58 INFO - Running setup.py install for mozdevice 13:55:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:55:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for mozfile 13:55:59 INFO - Running setup.py install for mozhttpd 13:55:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for mozinfo 13:55:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for mozInstall 13:55:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for mozleak 13:55:59 INFO - Running setup.py install for mozlog 13:55:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for moznetwork 13:55:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for mozprocess 13:55:59 INFO - Running setup.py install for mozprofile 13:55:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:55:59 INFO - Running setup.py install for mozrunner 13:56:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:56:00 INFO - Running setup.py install for mozscreenshot 13:56:00 INFO - Running setup.py install for moztest 13:56:00 INFO - Running setup.py install for mozversion 13:56:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:56:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:56:00 INFO - Cleaning up... 13:56:00 INFO - Return code: 0 13:56:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:00 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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:56:00 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 13:56:00 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 13:56:00 INFO - Using env: {'DISPLAY': ':0', 13:56:00 INFO - 'HOME': '/home/cltbld', 13:56:00 INFO - 'LANG': 'en_US.UTF-8', 13:56:00 INFO - 'LANGUAGE': 'en_US:en', 13:56:00 INFO - 'LOGNAME': 'cltbld', 13:56:00 INFO - 'MAIL': '/var/mail/cltbld', 13:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:00 INFO - 'MOZ_NO_REMOTE': '1', 13:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:00 INFO - 'NO_EM_RESTART': '1', 13:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:00 INFO - 'PWD': '/builds/slave/test', 13:56:00 INFO - 'SHELL': '/bin/bash', 13:56:00 INFO - 'SHLVL': '1', 13:56:00 INFO - 'TERM': 'linux', 13:56:00 INFO - 'TMOUT': '86400', 13:56:00 INFO - 'USER': 'cltbld', 13:56:00 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:00 INFO - '_': '/tools/buildbot/bin/python'} 13:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:56:00 INFO - Running setup.py (path:/tmp/pip-HRJlyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:56:00 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)) 13:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:56:00 INFO - Running setup.py (path:/tmp/pip-ZsY7A_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:56:00 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)) 13:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:56:00 INFO - Running setup.py (path:/tmp/pip-JKwVoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:56:00 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)) 13:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:56:00 INFO - Running setup.py (path:/tmp/pip-eaRMfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:56:00 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)) 13:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:56:00 INFO - Running setup.py (path:/tmp/pip-MIRDnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:56:00 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)) 13:56:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:56:00 INFO - Running setup.py (path:/tmp/pip-2wABg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:56:01 INFO - Running setup.py (path:/tmp/pip-uI5kzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:56:01 INFO - Running setup.py (path:/tmp/pip-76Vd_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:56:01 INFO - Running setup.py (path:/tmp/pip-GbKjuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:56:01 INFO - Running setup.py (path:/tmp/pip-bhujFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:56:01 INFO - Running setup.py (path:/tmp/pip-6B6EHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:56:01 INFO - Running setup.py (path:/tmp/pip-BDWTv2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:56:01 INFO - Running setup.py (path:/tmp/pip-8Ptis0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:56:01 INFO - Running setup.py (path:/tmp/pip-XyM8xZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:01 INFO - Running setup.py (path:/tmp/pip-UyfSsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:56:01 INFO - Running setup.py (path:/tmp/pip-NRFdBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:56:01 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)) 13:56:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:56:01 INFO - Running setup.py (path:/tmp/pip-OG3NtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:56:01 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)) 13:56:01 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)) 13:56:01 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)) 13:56:01 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)) 13:56:01 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)) 13:56:01 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)) 13:56:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:56:01 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 13:56:01 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 13:56:01 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 13:56:01 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 13:56:01 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 13:56:01 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 13:56:03 INFO - Downloading blessings-1.6.tar.gz 13:56:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:56:03 INFO - Installing collected packages: blessings 13:56:03 INFO - Running setup.py install for blessings 13:56:04 INFO - Successfully installed blessings 13:56:04 INFO - Cleaning up... 13:56:04 INFO - Return code: 0 13:56:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:56:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:04 INFO - Reading from file tmpfile_stdout 13:56:04 INFO - Current package versions: 13:56:04 INFO - argparse == 1.2.1 13:56:04 INFO - blessings == 1.6 13:56:04 INFO - blobuploader == 1.2.4 13:56:04 INFO - docopt == 0.6.1 13:56:04 INFO - functools32 == 3.2.3-2 13:56:04 INFO - jsonschema == 2.5.1 13:56:04 INFO - manifestparser == 1.1 13:56:04 INFO - mozInstall == 1.12 13:56:04 INFO - mozcrash == 1.0 13:56:04 INFO - mozdebug == 0.1 13:56:04 INFO - mozdevice == 0.48 13:56:04 INFO - mozfile == 1.2 13:56:04 INFO - mozhttpd == 0.7 13:56:04 INFO - mozinfo == 0.9 13:56:04 INFO - mozleak == 0.1 13:56:04 INFO - mozlog == 3.4 13:56:04 INFO - moznetwork == 0.27 13:56:04 INFO - mozprocess == 0.23 13:56:04 INFO - mozprofile == 0.28 13:56:04 INFO - mozrunner == 6.13 13:56:04 INFO - mozscreenshot == 0.1 13:56:04 INFO - mozsystemmonitor == 0.3 13:56:04 INFO - moztest == 0.8 13:56:04 INFO - mozversion == 1.4 13:56:04 INFO - psutil == 3.1.1 13:56:04 INFO - requests == 1.2.3 13:56:04 INFO - wsgiref == 0.1.2 13:56:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:04 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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:56:04 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 13:56:04 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 13:56:04 INFO - Using env: {'DISPLAY': ':0', 13:56:04 INFO - 'HOME': '/home/cltbld', 13:56:04 INFO - 'LANG': 'en_US.UTF-8', 13:56:04 INFO - 'LANGUAGE': 'en_US:en', 13:56:04 INFO - 'LOGNAME': 'cltbld', 13:56:04 INFO - 'MAIL': '/var/mail/cltbld', 13:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:04 INFO - 'MOZ_NO_REMOTE': '1', 13:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:04 INFO - 'NO_EM_RESTART': '1', 13:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:04 INFO - 'PWD': '/builds/slave/test', 13:56:04 INFO - 'SHELL': '/bin/bash', 13:56:04 INFO - 'SHLVL': '1', 13:56:04 INFO - 'TERM': 'linux', 13:56:04 INFO - 'TMOUT': '86400', 13:56:04 INFO - 'USER': 'cltbld', 13:56:04 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:04 INFO - '_': '/tools/buildbot/bin/python'} 13:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 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)) 13:56:04 INFO - Cleaning up... 13:56:04 INFO - Return code: 0 13:56:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:56:04 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 0x7f1ec188af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x242f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x24fcca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, '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': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', '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 13:56:04 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 13:56:04 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 13:56:04 INFO - Using env: {'DISPLAY': ':0', 13:56:04 INFO - 'HOME': '/home/cltbld', 13:56:04 INFO - 'LANG': 'en_US.UTF-8', 13:56:04 INFO - 'LANGUAGE': 'en_US:en', 13:56:04 INFO - 'LOGNAME': 'cltbld', 13:56:04 INFO - 'MAIL': '/var/mail/cltbld', 13:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:04 INFO - 'MOZ_NO_REMOTE': '1', 13:56:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:04 INFO - 'NO_EM_RESTART': '1', 13:56:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:04 INFO - 'PWD': '/builds/slave/test', 13:56:04 INFO - 'SHELL': '/bin/bash', 13:56:04 INFO - 'SHLVL': '1', 13:56:04 INFO - 'TERM': 'linux', 13:56:04 INFO - 'TMOUT': '86400', 13:56:04 INFO - 'USER': 'cltbld', 13:56:04 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:04 INFO - '_': '/tools/buildbot/bin/python'} 13:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:56:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:56:04 INFO - Cleaning up... 13:56:04 INFO - Return code: 0 13:56:04 INFO - Running post-action listener: _resource_record_post_action 13:56:04 INFO - Running post-action listener: _start_resource_monitoring 13:56:04 INFO - Starting resource monitoring. 13:56:04 INFO - [mozharness: 2017-02-23 21:56:04.784666Z] Finished create-virtualenv step (success) 13:56:04 INFO - [mozharness: 2017-02-23 21:56:04.785140Z] Running install step. 13:56:04 INFO - Running pre-action listener: _resource_record_pre_action 13:56:04 INFO - Running main action method: install 13:56:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:56:04 INFO - Reading from file tmpfile_stdout 13:56:04 INFO - Detecting whether we're running mozinstall >=1.0... 13:56:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:56:05 INFO - Reading from file tmpfile_stdout 13:56:05 INFO - Output received: 13:56:05 INFO - Usage: mozinstall [options] installer 13:56:05 INFO - Options: 13:56:05 INFO - -h, --help show this help message and exit 13:56:05 INFO - -d DEST, --destination=DEST 13:56:05 INFO - Directory to install application into. [default: 13:56:05 INFO - "/builds/slave/test"] 13:56:05 INFO - --app=APP Application being installed. [default: firefox] 13:56:05 INFO - mkdir: /builds/slave/test/build/application 13:56:05 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'] 13:56:05 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 13:56:18 INFO - Reading from file tmpfile_stdout 13:56:18 INFO - Output received: 13:56:18 INFO - /builds/slave/test/build/application/firefox/firefox 13:56:18 INFO - Running post-action listener: _resource_record_post_action 13:56:18 INFO - [mozharness: 2017-02-23 21:56:18.745278Z] Finished install step (success) 13:56:18 INFO - [mozharness: 2017-02-23 21:56:18.745614Z] Running run-tests step. 13:56:18 INFO - Running pre-action listener: _resource_record_pre_action 13:56:18 INFO - Running main action method: run_tests 13:56:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:56:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:56:18 INFO - Python 2.7.3 13:56:18 INFO - Return code: 0 13:56:18 INFO - grabbing minidump binary from tooltool 13:56:18 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')]} 13:56:18 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 0x24f9f60>, '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 0x24fad40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x24fb1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:56:18 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 13:56:18 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 13:56:18 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 13:56:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:56:18 INFO - Return code: 0 13:56:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:56:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:56:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:56:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:56:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:56:18 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/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 13:56:18 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/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-077 --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 13:56:18 INFO - Using env: {'DISPLAY': ':0', 13:56:18 INFO - 'HOME': '/home/cltbld', 13:56:18 INFO - 'LANG': 'en_US.UTF-8', 13:56:18 INFO - 'LANGUAGE': 'en_US:en', 13:56:18 INFO - 'LOGNAME': 'cltbld', 13:56:18 INFO - 'MAIL': '/var/mail/cltbld', 13:56:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:56:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:56:18 INFO - 'MOZ_NO_REMOTE': '1', 13:56:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:56:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:56:18 INFO - 'NO_EM_RESTART': '1', 13:56:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:56:18 INFO - 'PWD': '/builds/slave/test', 13:56:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:56:18 INFO - 'SHELL': '/bin/bash', 13:56:18 INFO - 'SHLVL': '1', 13:56:18 INFO - 'TERM': 'linux', 13:56:18 INFO - 'TMOUT': '86400', 13:56:18 INFO - 'USER': 'cltbld', 13:56:18 INFO - 'XDG_SESSION_COOKIE': '20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564', 13:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:56:18 INFO - '_': '/tools/buildbot/bin/python'} 13:56:18 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/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-077', '--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 13:56:19 INFO - mozversion application_buildid: 20170223120822 13:56:19 INFO - mozversion application_changeset: 8a654cb6a61e3c051d50542cc67b61943e4fbf80 13:56:19 INFO - mozversion application_display_name: Firefox 13:56:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:56:19 INFO - mozversion application_name: Firefox 13:56:19 INFO - mozversion application_remotingname: firefox 13:56:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:56:19 INFO - mozversion application_vendor: Mozilla 13:56:19 INFO - mozversion application_version: 52.0 13:56:19 INFO - mozversion platform_buildid: 20170223120822 13:56:19 INFO - mozversion platform_changeset: 8a654cb6a61e3c051d50542cc67b61943e4fbf80 13:56:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:56:19 INFO - mozversion platform_version: 52.0 13:56:19 INFO - using testdate: 1487886979 13:56:19 INFO - actual date: 1487886979 13:56:19 INFO - starting webserver on 'localhost:60526' 13:56:19 INFO - SUITE-START | Running 2 tests 13:56:19 INFO - TEST-START | dromaeo_css 13:56:19 INFO - operating with platform_type : linux_ 13:56:19 INFO - Initialising browser for dromaeo_css test... 13:56:19 INFO - TEST-INFO | started process 2623 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOVyr7r/profile http://localhost:60526/getInfo.html) 13:56:23 INFO - PROCESS | 2623 | __metrics Screen width/height:1600/1200 13:56:23 INFO - PROCESS | 2623 | colorDepth:24 13:56:23 INFO - PROCESS | 2623 | Browser inner width/height: 1024/697 13:56:23 INFO - PROCESS | 2623 | __metrics 13:56:24 ERROR - PROCESS | 2623 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 13:56:24 INFO - TEST-INFO | 2623: exit 0 13:56:24 INFO - Browser initialized. 13:56:24 INFO - Running cycle 1/1 for dromaeo_css test... 13:56:24 INFO - TEST-INFO | started process 2809 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpOVyr7r/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:56:36 INFO - PROCESS | 2809 | RSS: Main: 146587648 13:56:36 INFO - PROCESS | 2809 | 13:59:36 INFO - PROCESS | 2809 | Cycle 1(1): loaded http://localhost:60526/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60526/tests/dromaeo/cssquery-ext.html) 13:59:38 INFO - PROCESS | 2809 | RSS: Main: 154800128 13:59:38 INFO - PROCESS | 2809 | 14:02:40 INFO - PROCESS | 2809 | Cycle 1(1): loaded http://localhost:60526/tests/dromaeo/cssquery-ext.html (next: http://localhost:60526/tests/dromaeo/cssquery-jquery.html) 14:02:40 INFO - PROCESS | 2809 | RSS: Main: 152993792 14:02:40 INFO - PROCESS | 2809 | 14:05:42 INFO - PROCESS | 2809 | Cycle 1(1): loaded http://localhost:60526/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60526/tests/dromaeo/cssquery-mootools.html) 14:05:43 INFO - PROCESS | 2809 | RSS: Main: 152580096 14:05:43 INFO - PROCESS | 2809 | 14:08:45 INFO - PROCESS | 2809 | Cycle 1(1): loaded http://localhost:60526/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60526/tests/dromaeo/cssquery-prototype.html) 14:08:45 INFO - PROCESS | 2809 | RSS: Main: 153538560 14:08:45 INFO - PROCESS | 2809 | 14:11:48 INFO - PROCESS | 2809 | Cycle 1(1): loaded http://localhost:60526/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60526/tests/dromaeo/cssquery-yui.html) 14:11:48 INFO - PROCESS | 2809 | RSS: Main: 154955776 14:11:48 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | Cycle 1(1): loaded http://localhost:60526/tests/dromaeo/cssquery-yui.html (next: http://localhost:60526/tests/dromaeo/cssquery-dojo.html) 14:14:51 INFO - PROCESS | 2809 | RSS: Main: 155525120 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | __start_tp_report 14:14:51 INFO - PROCESS | 2809 | _x_x_mozilla_page_load 14:14:51 INFO - PROCESS | 2809 | _x_x_mozilla_page_load_details 14:14:51 INFO - PROCESS | 2809 | |i|pagename|runs| 14:14:51 INFO - PROCESS | 2809 | |0;dojo.html;3203.5194720791887;3238.1742655622797;3238.9352212955732;3251.3172233830896;3260.0708797992547;5681.829545113643;5726.713664316778;5732.111522713975;5738.31140263169;5765.164051212571;5701.458361455665;5796.333421656511;5804.651720896749;5813.418658134185;5836.15375770513;5243.318368612085;5285.682859028451;5298.523132918037;5328.307320048408;5335.466453354672;5331.813386531472;5336.066188417031;5341.65279256849;5347.759350829203;5369.812056578021;5027.120253955562;5034.043531728978;5343;5343.358796944352;5348.117560602496;5193.402758682743;5207.140821764423;5227.059129356714;5290.153575427913;5318.30861987943;5174.663646862942;5249.186376111708;5256.50063243995;5258.789648414097;5297.364316282071;5426.131818908995;5664.858378540528;5702.0021496238;5755.8561035974435;5770.9422905770825;5872.236609240771;5986.161937328778;5999.880002399971;6078.696065196723;6117.418845209698;6037.54718396122;6119.785807496716;6124.265088189446;6128;6145.170401995718;5730.9426905730825;5733.512651424634;5745.9712701437065;5747.023006088892;5747.655140691572;6150.661713605705;6188.752449901954;6213.813585592349;6264.029075493306;6286.559940804192;5949.375315591887;6016.458518733335;6032.125341825411;6040.909386359208;6057.454829065317;5856.590038697335;5888.087346461305;6105.48103411215;6123.203983482119;6133.049377346519;5883.294004719461;5902.822915312548;5920.2303700519515;5947.6431414115295;5981.222441082631;5936.406359364029;5939.346671866091;5941.940580594225;5953.553483488756;5971.044632858721;4527.388802511685;4644.8374306899095;4652.441706995182;4665.6967297125575;4679.321498382716;4022.054817117975;4131.380292956081;4142.08874047709;4192.0777428965575;4200.991761977103;5785.797497087666;5825.155352473867;5827.475527202572;5831.067029275296;5831.737571809279;6115.84710382244;6171.074338849119;6175.413335733098;6181.660008699565;6190.350013248634;5603.579731520153;5664.20701101846;5674.432556744375;5696.686682232434;5729.598928075078;5206.140986737221;5207;5220.791168353299;5235.842924712265;5246.160614301693;6266.937330626726;6273.96863015682;6289.905651415233;6291.182146320949;6302.1492098566105;5924.437178468039;5976.163337132892;6068.180795592715;6072.696365181635;6080.787172448943;6270.278917924497;6331.366863313816;6362.872742545031;6368.681565921593;6374.107624932421;5980.132880732356;6018.8194354169445;6039.214902062879;6041.78853740117;6049.667268300285;5794.0439827428;5853.122031695109;6111.663858487826;6117.335272814302;6132.233136608343;5799.739011744566;5806.564507662027;5919.348871624204;5954.255718035246;6006.759729610767;6009.669468179117;6021.42796434338;6024.3975602439405;6032.788852390146;6064.818055458343;5903.114532820111;5915.08316210988;5918.3489816121055;5941.1385349125;6006.399360063959;5723.7424315905055;5834.03738383162;5979.162917191509;5993.220881285405;6056.485198758155;5834.241548598655;5891.58758886891;5908.586398952032;5942.821715348547;5973.074173503114 14:14:51 INFO - PROCESS | 2809 | |1;ext.html;548.2726940440789;597.8266302772196;611.8164550634812;651.9185101862267;663.127986697493;40919.79540102299;41887.16225675485;44364.33453498192;44455.110897782026;44563;44013.77993110038;44705;44834;44931.10137797242;44949.32576011354;8795.384323097385;10530.894691053107;10874.130069594414;10889.564417423293;11110.277831940939;10863.022327990479;10939.945300273488;10942.288751231185;10946.233763636548;10959.945200273987;10847.132229421666;10965.287256328353;10985.395803230818;11003.834942475869;11022.118230541577;10944.23390362671;10959.178061645369;10965.780684386347;10995.230333876632;11003.834942475869;10847;10944.069754070913;10973.945130274298;10994.725131871688;11007.174461915389;42519.149617007795;42603.14793704109;42606.573934260574;42824.357634635504;43068;3627.4196128619296;3648.0332711831384;3832.7891965941863;3925.587813279593;4200.474940632421;32769.50845737316;33025.66974330274;33094.17264568381;33221.83389083063;33237.171955542435;4492.820287188509;4538.682292239545;4734.502877197879;4754.168020596382;4767.928481072752;340335;341226;343699;343710.28144859365;344833;62211.688941555;62217.377826222066;62268;62535.6873215631;62571.687141564915;66083;66083;66341.66829165824;66376.6681166591;66427;15072.472463463726;15676.372945082298;15714.135722535142;15768.448104316294;15774.684506310039;11722.472488738027;11855.34795586251;11857.881421185677;11891.464884080064;12127.818082728767;19595.510112247306;19706.60586788256;19839.900800495907;19848.50378740543;20112.296069637494;7197.280271972865;7203.27967203286;7257.8911316330295;7271.3455788979245;7272.745453909088;1017.9389236645825;1049.0139269087033;1069.4332004037872;1088.90199882011;1094.852194953671;31358.37283254322;31743.52384714231;31999.680003200137;32071.87748428794;32922;1089.1939964426426;1104.2325583719398;1105.6738262212666;1137.9487923043482;1156.0809156720427;16078.67842643164;17082.57293567646;17129.459451404156;17262.309507619804;17308.221130049176;456156;458251;459263;459671;460462;58931.410685892595;59333.40666593365;59405.70297148487;59427.70286148541;59667.104993425135;1330.1619979413101;1356.0575400096839;1358.042579981111;1364.3042048555308;1398.5664444022386;30350.54474182845;30621.540676889417;31290.35313825827;31842.20394490156;32086;46155;46292.537074628824;46399.76800115978;46420;46481.302780458995;386291;389290;390241;391258;391380;29833.701662983094;30256.54615180818;30663.54004689976;30876.53685194679;31196.688033120285;47262.76368618135;47724;47781.522184779096;49045.264321035924;51673.7416312916;41173.79413102915;42011.7899410501;42162.36756448717;42522.149557008066;42578.21266663704;40135.1972960545;40455.59544404636;40738.38892416676;42217.78891105648;42254;39928;40030.39954400628;40663.39004914869;41917.37123943205;43429;35078.47382289216;36522.8173859129;36870.8156459216;37204.44193337048;37531.812340939214;28183.154505364873;28240.576391354556;28323.858380707963;28438.289042774097;28476.430471390042 14:14:51 INFO - PROCESS | 2809 | |2;jquery.html;395.45229856648535;398.5476484190443;409.3981846685372;414.390845457178;424.6008751773333;18486;19871.30450434235;20264.29074982376;20285.898570507146;20448.18207271711;19692.310769123083;19999.60000799983;20248.8987555062;20403.387898363035;20467.18131274751;5409.134538473845;5765.711714414283;5789.4500022498005;5790.478856902878;5790.623609465392;5573.4147914469;5654.208410822488;5707.571932105088;5714.885702285952;5735.168400581934;5670.404607516213;5723.084306510959;5727.369989301194;5733.197352370671;5736.627119237236;5475.917861232085;5680.886382272372;5713.800016999385;5718.857028574277;5743.569232307573;5171.7672704728375;5175.223716442526;5179.300794392747;5182.4299327073995;5185.948140518584;23379.4155146121;23450.765492345028;23690.88154559216;23969;24056.639150412757;2371.9288421347387;2381.083282936082;2431.6717243172297;2911.403162351713;3019.139545229621;14372.928135359361;14729.484468043567;14826.33281502335;15003.924980375137;15470.845291547166;1760.595063135486;1780.3679693708807;1805.629845881591;1915.82757551821;1984.9305274315475;83609.58195208985;84334.15665843325;85143.57428212881;86446.13553864506;87495;11475.655730328104;11501.13741469393;11568.479418426192;11692.824607630891;11744.823827642593;16133.677326453406;16210.108444035692;16217.918910405373;16223.675526489404;16276.26756795947;8073;8197.46716463428;8251.216134467217;8356.080831108573;8360.28937540316;7700.614969251515;7716.151223365426;7727.343175830005;7746.845063098819;8186.345092392713;4903.779329930162;4916.606671466274;4941.209406494943;4992.326035985168;5022.121128802445;1984.9900750496156;2057.300517823947;2117.25895936421;2119.5866805972687;2130.371540395587;949.2216382566229;977.8875429325576;977.9266555008403;984.015984015984;1081;19490.805091949183;19595.60808783816;19770.209191632173;19921.800781992286;20019.199232030554;1023.6570748799087;1053.5680371047833;1057.9206559508068;1063.5426766490484;1089.2048804372694;10790.89209107899;11208.103351731841;11216.775664486664;11292.774144517063;11311.151663625262;447561;450342;455733;457035;469378;60725;79397;90674.54662726643;98791.50604247076;99763;1002.2710823828837;1008.1682611845257;1047.9738006549744;1050.6847945616285;1057.1331508163387;17487.38794142199;17511.124443778015;17916.193771280585;17918.193681284112;18371.81628183701;43332.56667433411;44393;44519;44536.55463445454;44623.33065003963;54179.187312191134;54188.72905635447;54555.72722136364;61026.38973610385;61951.69024155031;8644.092370301028;8745.344099192713;8821.470711757314;8854;9003.414778039407;12640.810387844005;13137;13198.736025279633;13321.600351989457;13612.183269003892;11709.414529273332;11836.76326473483;11839.526418943145;12168.391580421121;12300.87699123033;11650.475728592064;11657.475413606238;11743.354115523729;11868.881311186778;12024.33866137371;11736.70658233551;11948.76102477963;11961.64115076549;12070.155089144027;12200.328981906081;8653.17794809492;8754.124587541195;8906.599203036008;8945.910540894507;8978.191962723386;8801.91198088011;11525.250858694159;11785.116116291483;11897.643070707893;11926.344051077274 14:14:51 INFO - PROCESS | 2809 | |3;mootools.html;1473.4106357457015;1482.36999275308;1670.9498715038553;1717.8110407855127;1793.9730904036442;8355.582220888955;8748.737537873874;8914.420562663423;8921.197092261704;9014.594343254554;8949.463032218071;9054.773630659236;9062.682806101788;9075.319351048682;9077.773055673611;2350.3536527454958;2373.0151986925407;2375.1805627058725;2377.9643305350432;2385.0459816073535;2334.323046316566;2374.631932050535;2379.095943541452;2380.5595964746576;2386.8806559672034;2324.0587562037404;2326.150954901457;2350.083467447697;2355.4817940053163;2357.9882100589475;2298.138198175684;2320.6170981787986;2336.240721765433;2349.9765002349927;2350.3419042667997;2275.590393729128;2290.8625482471107;2295.4605667668084;2295.5867943770113;2299.0803678528555;8556.957215213884;8632.39573229874;8883.955580222122;8920.197182253629;8986.101389860962;4957.3059771632;4969.701817890938;5101.0308041472;5330.360356757175;5341.973290133564;3220.146661134801;3252.30075533759;3296.670332966708;3406.318736252759;3420.1278673938236;1016.3901659004612;1071.2929466552116;1096.6545538155456;1106.1703722208345;1107.0424083168002;12202.877971220263;12320.876791232062;12369.381530923418;12443;12451.93774031133;4319.136172765459;4393.362962370438;4488;4530.116627257719;4554.407926969473;9720.270979676552;9758.609655613838;9780.315377923476;9784.804303913881;9788.706338809847;5304.310439642822;5307.203919412119;5315.6013299002725;5328.6802791832615;5328.706921119376;4717.905641887143;4840.6127509799135;4873.098476781806;4901.803927842918;4939.851804445872;3682.9447558286647;3759.248150369937;3782.905427364283;3842.116313247913;3867.381219005002;1129.203911242572;1138.6014894786924;1150.8561429821273;1156.0520373293818;1177.4701384377065;763.8052296664315;920.2086205862953;920.3189639666598;924.5469719837366;939.7462685074992;5874.706264686834;5891.086881533369;5912.320083190402;5951.642901425928;6083.69581520931;443.1159836126947;445.08090792513354;464.55170760216555;481.6171143940576;486.11527020822473;3976.2842688315804;4049.3723472861166;4131.132462182915;4142.171565686815;4168.437260969791;10648.733781655366;10905.509252083517;10922.453877306103;10977.39624320654;11001.449927503592;10175.643852465128;10799.622013229498;10802.135829133651;10804.081653059578;10808.94595527033;7984.800379990546;8432.915670843337;8434.367422443342;8435.283000944988;8454.746357609158;6175.073738939195;6180.536459765445;6252.531060170596;6271.905921411091;6283.214598175228;9798.608055677692;9835.606575737176;9923.503824808875;9935.652252171429;9991.750206244611;10018.39889606626;10084.445355505517;10148.797024059626;10162.949185254027;10308.433036183082;7689.307962283197;7811.453198276294;7900.604969751605;7964.28321451049;7980.600969951595;8196.54918979438;8273.83452330938;8350;8364.372672049742;8377.204165604258;7270.563766174047;7306.744263950949;7538.434617403826;7725.227477252454;7769.844603108019;7412.147603025505;7534.284242996907;7575.166731659403;7604.201558836243;7647.38820894316;7112.466565007541;7141.500094993508;7208.06295181623;7224;7226.421886248982;5363.678179309254;5435.83692489226;5454.263674404064;5457.290552228185;5516.862078448071;5438.918416223681;5448.264484294569;5540.889182216414;5582.441755824385;5626.043572592587 14:14:51 INFO - PROCESS | 2809 | |4;prototype.html;320.85080437596514;328.0813721579577;332.8169506771276;334.87609584453753;337.047839852417;5534.778608855646;5610.719464026798;5636.15457681348;5643.8024669136585;5644.068728659771;5526.834194974147;5537.972310138453;5542;5544.417836127209;5584.972075139619;4154.771487568182;4273.188094262096;4299.312110062375;4310.159518893817;4313.568643135693;4310.82756689732;4315.633171180453;4326.6538676905775;4331.913361732763;4333.978330108345;4288.335308027261;4294.26997410441;4305.50486694031;4307.2246995540945;4310.749882534077;4151.439555659993;4271.466066741657;4271.718484454651;4290.935635965463;4317.633001194902;3707.536557930259;3712.9071773205615;3722.1066943933533;3723.069232691827;3757.605451427588;5419.620626556142;5432.891342173174;5438.347398312228;5466.480684335022;5481.862953426156;4744.363194697824;4853.563179313844;4870.439899411542;4921.753912304371;4932.372317135337;5723.713814309268;5741.3110426748635;5756.107803290497;5756.683382413966;5763.567732420043;4112.732672376316;4124.360724087771;4124.52567954686;4125.1749650070105;4129.236091323085;5704.71476426177;6003.118503118528;6028.4272994065495;6040.0939859021055;6097.725602347905;4165.14614504032;4174.3947127666925;4182.121754431542;4182.937255941163;4185.162967406531;4263.978680106579;4267.381229721673;4272.850450234227;4274.4870615525715;4283.593058659422;4786.659735274129;4865.683730557503;4868.245421959531;4877.95122048775;4893.339399181065;5813.447722466359;5824.301083869878;5828.825135245949;5836.8540786479825;5884.293884733859;1422.7652437347929;1422.8292604887479;1423.5373503611368;1424.64383904024;1425.301602214928;927.1146055516965;941.7410212191566;971.2569884038716;978.8923218445965;982.9115379615725;5405.621606487508;5415.6479828811;5415.675059496442;5448;5448.7548060336585;5733.598648094593;5771.134329850472;5771.913421298684;5774.682392468455;5800.767969281266;5302.257683924254;5393.568514518907;5443.700596467153;5453.400125986138;5461.863453413617;3059.969400306013;3073.615798025247;3083.1213104265175;3120.282335062903;3120.6411262704623;5893.970530147321;5969.343372229051;5988.472939400512;6047.939520604826;6075.5747097703625;5880.235929221302;5884.764609415575;5899.233099697012;5906.438888305604;5942.524598032147;5997.580169388101;5999.160117583542;6000.5799594028;6010.934423444075;6024.969875150597;5682.77268909239;5759.301006203096;5784.248047753765;6064.211652485325;6066.332703402534;5943.762249509971;5952.642841429528;5972.402759723993;5987.161797348288;6012.248468941382;6084.053103006995;6176.289726681489;6250.249970003629;6259.247410725005;6279.591826531261;4135.110951145518;4187.1834992177455;4187.906046976512;4193.993441573939;4197.467924207704;6028.367021462858;6053.213082299273;6065.72704228302;6112.633242005494;6118.388161184024;5768.855778605401;5905.202797622266;5943.494802941798;5957.821265362046;5981.551383646331;5845.473907348359;5917.467427931507;5942.484666410211;5953.315368732651;5975.970120149372;5846.210761547307;5857;5878.706064696834;5885.970570147122;5886.529077673861;5774.2782152230975;5957.821265362046;6005.339412664516;6016.368281330398;6021.789237376671;5842.620229685057;5851.590388672752;5857.765689372548;5868.979748872036;5874.148248504029 14:14:51 INFO - PROCESS | 2809 | |5;yui.html;786.2020049649603;799.0890384961142;823.6417158536036;832.5171400587658;881.5988725130067;13433.798493022612;14015.929920350396;14448.205348705817;14594.781078283835;14652.413903443863;11895.64313070607;12947.741045179091;13015.414306356213;13091.345432728373;13192.208467491957;807.8545861744898;819.5246756880995;822.1408627983772;824.0194168938973;824.5547404401616;808.4138999225544;811.6712731343786;811.9147489513605;814.1898810683379;814.6822739131743;848.8769128476367;850.8468475674376;854.3122786157141;856.0070318430621;858.4291446188278;842.6250318608222;846.610559142792;848.4697064334791;849.587949844325;851.6210286422545;626.3611116661026;627.1752645271454;627.2347735762363;629.7953165221321;630.4200135875006;11886.465109070112;12043.096767742454;12053.156279060382;12520.624381268577;12792.360381980863;11769.41152942358;11996.520139194421;12175.08686848481;12322.753544929052;12468.376581170996;11083.971284739753;11298.435078246137;11528.538858445652;11574.189806713533;11764.882351176464;3688.2992231475932;3755.6056614055406;3769.8680546180753;3790.6209379062147;3934.8032598370255;54168;54682.726586367215;60705;62870.37129628691;63132;11512;11631.302121872715;11654.30074195551;11728.88271117295;11753.059755219732;20161.899190503955;20163.092660830298;20205.797942020687;20297.391078267676;20363.898180509004;8020.639071241808;8348.499090054616;8475.872861907077;8567.143285671507;8620.913790862136;4512.661550383735;4620.792064357112;4663.953360466419;4683.859484215479;4758.262469317261;3271.689189526991;3383.4417321142228;3386.6444023377685;3392.8473218704726;3406.9659303407143;609.7804790275497;613.5337143770766;616.8612062286039;630.4578062865932;639.1052526462989;748.554610007045;763.8815983522563;779.6998155710093;783.0564170175039;787.8109253779052;8912.955435222782;9148.954255228815;9417.529123543796;9461.52692365379;10275.691729247985;621.2451870976785;621.8694074244369;634.1882390540023;642.6433329502078;644.6841047886502;4457.9331310030375;4467.486239082482;4587.9082418351445;4590.403247577793;4690.413698287714;51296.74351628293;53608.195877061095;54748;55297.72351138219;56158.43841561532;62388.688056559426;75395;78741;79122;79275.60362198151;60909;61110;63024.054639180446;63495.36504634895;63672.36327636665;9938.30431869791;10099.343542669703;10150.593976241164;10229.181665466596;10316.535755890853;40607.39088913728;42099.7895010523;43228.56771432245;43440;43986.34020489631;40627;62781;64430.35569644431;65999.67000164969;66873.66563167347;12428.102298736265;13006.739865202453;13034.934825325812;13109.344532773513;13183.934080329536;13957.162570245819;14291.142531448146;14352.856471435569;14933.328000240233;15139.848601513844;14238.216898070705;14381.137131772128;14835.925820370829;15233.6191595211;15345.386184552493;13662.18026918388;13959.092658977133;14019.57941261723;14262.500812471513;14287.64280892986;13670.31648417595;14046.087004344685;14229.573112806633;14412.063215890934;14891;10911.975731207305;11185.66443006711;11865.40672966331;11964.760704785682;12084.516619335129;11698.35659038761;11869.347185904859;12245.510179593073;12249.448774805333;12374 14:14:51 INFO - PROCESS | 2809 | __end_tp_report 14:14:51 INFO - PROCESS | 2809 | __start_cc_report 14:14:51 INFO - PROCESS | 2809 | _x_x_mozilla_cycle_collect,308 14:14:51 INFO - PROCESS | 2809 | __end_cc_report 14:14:51 INFO - PROCESS | 2809 | __startTimestamp1487888091538__endTimestamp 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | ------- Summary: start ------- 14:14:51 INFO - PROCESS | 2809 | Number of tests: 6 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | [#0] dojo.html Cycles:165 Average:5660.75 Median:5856.59 stddev:637.57 (10.9%) stddev-sans-first:609.69 14:14:51 INFO - PROCESS | 2809 | Values: 3203.5 3238.2 3238.9 3251.3 3260.1 5681.8 5726.7 5732.1 5738.3 5765.2 5701.5 5796.3 5804.7 5813.4 5836.2 5243.3 5285.7 5298.5 5328.3 5335.5 5331.8 5336.1 5341.7 5347.8 5369.8 5027.1 5034.0 5343.0 5343.4 5348.1 5193.4 5207.1 5227.1 5290.2 5318.3 5174.7 5249.2 5256.5 5258.8 5297.4 5426.1 5664.9 5702.0 5755.9 5770.9 5872.2 5986.2 5999.9 6078.7 6117.4 6037.5 6119.8 6124.3 6128.0 6145.2 5730.9 5733.5 5746.0 5747.0 5747.7 6150.7 6188.8 6213.8 6264.0 6286.6 5949.4 6016.5 6032.1 6040.9 6057.5 5856.6 5888.1 6105.5 6123.2 6133.0 5883.3 5902.8 5920.2 5947.6 5981.2 5936.4 5939.3 5941.9 5953.6 5971.0 4527.4 4644.8 4652.4 4665.7 4679.3 4022.1 4131.4 4142.1 4192.1 4201.0 5785.8 5825.2 5827.5 5831.1 5831.7 6115.8 6171.1 6175.4 6181.7 6190.4 5603.6 5664.2 5674.4 5696.7 5729.6 5206.1 5207.0 5220.8 5235.8 5246.2 6266.9 6274.0 6289.9 6291.2 6302.1 5924.4 5976.2 6068.2 6072.7 6080.8 6270.3 6331.4 6362.9 6368.7 6374.1 5980.1 6018.8 6039.2 6041.8 6049.7 5794.0 5853.1 6111.7 6117.3 6132.2 5799.7 5806.6 5919.3 5954.3 6006.8 6009.7 6021.4 6024.4 6032.8 6064.8 5903.1 5915.1 5918.3 5941.1 6006.4 5723.7 5834.0 5979.2 5993.2 6056.5 5834.2 5891.6 5908.6 5942.8 5973.1 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | [#1] ext.html Cycles:180 Average:57212.39 Median:3637.73 stddev:105317.70 (2895.2%) stddev-sans-first:105527.22 14:14:51 INFO - PROCESS | 2809 | Values: 548.3 597.8 611.8 651.9 663.1 40919.8 41887.2 44364.3 44455.1 44563.0 44013.8 44705.0 44834.0 44931.1 44949.3 8795.4 10530.9 10874.1 10889.6 11110.3 10863.0 10939.9 10942.3 10946.2 10959.9 10847.1 10965.3 10985.4 11003.8 11022.1 10944.2 10959.2 10965.8 10995.2 11003.8 10847.0 10944.1 10973.9 10994.7 11007.2 42519.1 42603.1 42606.6 42824.4 43068.0 3627.4 3648.0 3832.8 3925.6 4200.5 32769.5 33025.7 33094.2 33221.8 33237.2 4492.8 4538.7 4734.5 4754.2 4767.9 340335.0 341226.0 343699.0 343710.3 344833.0 62211.7 62217.4 62268.0 62535.7 62571.7 66083.0 66083.0 66341.7 66376.7 66427.0 15072.5 15676.4 15714.1 15768.4 15774.7 11722.5 11855.3 11857.9 11891.5 12127.8 19595.5 19706.6 19839.9 19848.5 20112.3 7197.3 7203.3 7257.9 7271.3 7272.7 1017.9 1049.0 1069.4 1088.9 1094.9 31358.4 31743.5 31999.7 32071.9 32922.0 1089.2 1104.2 1105.7 1137.9 1156.1 16078.7 17082.6 17129.5 17262.3 17308.2 456156.0 458251.0 459263.0 459671.0 460462.0 58931.4 59333.4 59405.7 59427.7 59667.1 1330.2 1356.1 1358.0 1364.3 1398.6 30350.5 30621.5 31290.4 31842.2 32086.0 46155.0 46292.5 46399.8 46420.0 46481.3 386291.0 389290.0 390241.0 391258.0 391380.0 29833.7 30256.5 30663.5 30876.5 31196.7 47262.8 47724.0 47781.5 49045.3 51673.7 41173.8 42011.8 42162.4 42522.1 42578.2 40135.2 40455.6 40738.4 42217.8 42254.0 39928.0 40030.4 40663.4 41917.4 43429.0 35078.5 36522.8 36870.8 37204.4 37531.8 28183.2 28240.6 28323.9 28438.3 28476.4 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | [#2] jquery.html Cycles:180 Average:28353.71 Median:24012.82 stddev:75341.25 (313.8%) stddev-sans-first:75523.36 14:14:51 INFO - PROCESS | 2809 | Values: 395.5 398.5 409.4 414.4 424.6 18486.0 19871.3 20264.3 20285.9 20448.2 19692.3 19999.6 20248.9 20403.4 20467.2 5409.1 5765.7 5789.5 5790.5 5790.6 5573.4 5654.2 5707.6 5714.9 5735.2 5670.4 5723.1 5727.4 5733.2 5736.6 5475.9 5680.9 5713.8 5718.9 5743.6 5171.8 5175.2 5179.3 5182.4 5185.9 23379.4 23450.8 23690.9 23969.0 24056.6 2371.9 2381.1 2431.7 2911.4 3019.1 14372.9 14729.5 14826.3 15003.9 15470.8 1760.6 1780.4 1805.6 1915.8 1984.9 83609.6 84334.2 85143.6 86446.1 87495.0 11475.7 11501.1 11568.5 11692.8 11744.8 16133.7 16210.1 16217.9 16223.7 16276.3 8073.0 8197.5 8251.2 8356.1 8360.3 7700.6 7716.2 7727.3 7746.8 8186.3 4903.8 4916.6 4941.2 4992.3 5022.1 1985.0 2057.3 2117.3 2119.6 2130.4 949.2 977.9 977.9 984.0 1081.0 19490.8 19595.6 19770.2 19921.8 20019.2 1023.7 1053.6 1057.9 1063.5 1089.2 10790.9 11208.1 11216.8 11292.8 11311.2 447561.0 450342.0 455733.0 457035.0 469378.0 60725.0 79397.0 90674.5 98791.5 99763.0 1002.3 1008.2 1048.0 1050.7 1057.1 17487.4 17511.1 17916.2 17918.2 18371.8 43332.6 44393.0 44519.0 44536.6 44623.3 54179.2 54188.7 54555.7 61026.4 61951.7 8644.1 8745.3 8821.5 8854.0 9003.4 12640.8 13137.0 13198.7 13321.6 13612.2 11709.4 11836.8 11839.5 12168.4 12300.9 11650.5 11657.5 11743.4 11868.9 12024.3 11736.7 11948.8 11961.6 12070.2 12200.3 8653.2 8754.1 8906.6 8945.9 8978.2 8801.9 11525.3 11785.1 11897.6 11926.3 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | [#3] mootools.html Cycles:180 Average:5783.21 Median:4963.50 stddev:3294.34 (66.4%) stddev-sans-first:3287.66 14:14:51 INFO - PROCESS | 2809 | Values: 1473.4 1482.4 1670.9 1717.8 1794.0 8355.6 8748.7 8914.4 8921.2 9014.6 8949.5 9054.8 9062.7 9075.3 9077.8 2350.4 2373.0 2375.2 2378.0 2385.0 2334.3 2374.6 2379.1 2380.6 2386.9 2324.1 2326.2 2350.1 2355.5 2358.0 2298.1 2320.6 2336.2 2350.0 2350.3 2275.6 2290.9 2295.5 2295.6 2299.1 8557.0 8632.4 8884.0 8920.2 8986.1 4957.3 4969.7 5101.0 5330.4 5342.0 3220.1 3252.3 3296.7 3406.3 3420.1 1016.4 1071.3 1096.7 1106.2 1107.0 12202.9 12320.9 12369.4 12443.0 12451.9 4319.1 4393.4 4488.0 4530.1 4554.4 9720.3 9758.6 9780.3 9784.8 9788.7 5304.3 5307.2 5315.6 5328.7 5328.7 4717.9 4840.6 4873.1 4901.8 4939.9 3682.9 3759.2 3782.9 3842.1 3867.4 1129.2 1138.6 1150.9 1156.1 1177.5 763.8 920.2 920.3 924.5 939.7 5874.7 5891.1 5912.3 5951.6 6083.7 443.1 445.1 464.6 481.6 486.1 3976.3 4049.4 4131.1 4142.2 4168.4 10648.7 10905.5 10922.5 10977.4 11001.4 10175.6 10799.6 10802.1 10804.1 10808.9 7984.8 8432.9 8434.4 8435.3 8454.7 6175.1 6180.5 6252.5 6271.9 6283.2 9798.6 9835.6 9923.5 9935.7 9991.8 10018.4 10084.4 10148.8 10162.9 10308.4 7689.3 7811.5 7900.6 7964.3 7980.6 8196.5 8273.8 8350.0 8364.4 8377.2 7270.6 7306.7 7538.4 7725.2 7769.8 7412.1 7534.3 7575.2 7604.2 7647.4 7112.5 7141.5 7208.1 7224.0 7226.4 5363.7 5435.8 5454.3 5457.3 5516.9 5438.9 5448.3 5540.9 5582.4 5626.0 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | [#4] prototype.html Cycles:180 Average:4874.99 Median:5597.85 stddev:1454.13 (26.0%) stddev-sans-first:1417.47 14:14:51 INFO - PROCESS | 2809 | Values: 320.9 328.1 332.8 334.9 337.0 5534.8 5610.7 5636.2 5643.8 5644.1 5526.8 5538.0 5542.0 5544.4 5585.0 4154.8 4273.2 4299.3 4310.2 4313.6 4310.8 4315.6 4326.7 4331.9 4334.0 4288.3 4294.3 4305.5 4307.2 4310.7 4151.4 4271.5 4271.7 4290.9 4317.6 3707.5 3712.9 3722.1 3723.1 3757.6 5419.6 5432.9 5438.3 5466.5 5481.9 4744.4 4853.6 4870.4 4921.8 4932.4 5723.7 5741.3 5756.1 5756.7 5763.6 4112.7 4124.4 4124.5 4125.2 4129.2 5704.7 6003.1 6028.4 6040.1 6097.7 4165.1 4174.4 4182.1 4182.9 4185.2 4264.0 4267.4 4272.9 4274.5 4283.6 4786.7 4865.7 4868.2 4878.0 4893.3 5813.4 5824.3 5828.8 5836.9 5884.3 1422.8 1422.8 1423.5 1424.6 1425.3 927.1 941.7 971.3 978.9 982.9 5405.6 5415.6 5415.7 5448.0 5448.8 5733.6 5771.1 5771.9 5774.7 5800.8 5302.3 5393.6 5443.7 5453.4 5461.9 3060.0 3073.6 3083.1 3120.3 3120.6 5894.0 5969.3 5988.5 6047.9 6075.6 5880.2 5884.8 5899.2 5906.4 5942.5 5997.6 5999.2 6000.6 6010.9 6025.0 5682.8 5759.3 5784.2 6064.2 6066.3 5943.8 5952.6 5972.4 5987.2 6012.2 6084.1 6176.3 6250.2 6259.2 6279.6 4135.1 4187.2 4187.9 4194.0 4197.5 6028.4 6053.2 6065.7 6112.6 6118.4 5768.9 5905.2 5943.5 5957.8 5981.6 5845.5 5917.5 5942.5 5953.3 5976.0 5846.2 5857.0 5878.7 5886.0 5886.5 5774.3 5957.8 6005.3 6016.4 6021.8 5842.6 5851.6 5857.8 5869.0 5874.1 14:14:51 INFO - PROCESS | 2809 | 14:14:51 INFO - PROCESS | 2809 | [#5] yui.html Cycles:180 Average:16495.21 Median:24222.14 stddev:20275.42 (83.7%) stddev-sans-first:20297.98 14:14:51 INFO - PROCESS | 2809 | Values: 786.2 799.1 823.6 832.5 881.6 13433.8 14015.9 14448.2 14594.8 14652.4 11895.6 12947.7 13015.4 13091.3 13192.2 807.9 819.5 822.1 824.0 824.6 808.4 811.7 811.9 814.2 814.7 848.9 850.8 854.3 856.0 858.4 842.6 846.6 848.5 849.6 851.6 626.4 627.2 627.2 629.8 630.4 11886.5 12043.1 12053.2 12520.6 12792.4 11769.4 11996.5 12175.1 12322.8 12468.4 11084.0 11298.4 11528.5 11574.2 11764.9 3688.3 3755.6 3769.9 3790.6 3934.8 54168.0 54682.7 60705.0 62870.4 63132.0 11512.0 11631.3 11654.3 11728.9 11753.1 20161.9 20163.1 20205.8 20297.4 20363.9 8020.6 8348.5 8475.9 8567.1 8620.9 4512.7 4620.8 4664.0 4683.9 4758.3 3271.7 3383.4 3386.6 3392.8 3407.0 609.8 613.5 616.9 630.5 639.1 748.6 763.9 779.7 783.1 787.8 8913.0 9149.0 9417.5 9461.5 10275.7 621.2 621.9 634.2 642.6 644.7 4457.9 4467.5 4587.9 4590.4 4690.4 51296.7 53608.2 54748.0 55297.7 56158.4 62388.7 75395.0 78741.0 79122.0 79275.6 60909.0 61110.0 63024.1 63495.4 63672.4 9938.3 10099.3 10150.6 10229.2 10316.5 40607.4 42099.8 43228.6 43440.0 43986.3 40627.0 62781.0 64430.4 65999.7 66873.7 12428.1 13006.7 13034.9 13109.3 13183.9 13957.2 14291.1 14352.9 14933.3 15139.8 14238.2 14381.1 14835.9 15233.6 15345.4 13662.2 13959.1 14019.6 14262.5 14287.6 13670.3 14046.1 14229.6 14412.1 14891.0 10912.0 11185.7 11865.4 11964.8 12084.5 11698.4 11869.3 12245.5 12249.4 12374.0 14:14:51 INFO - PROCESS | 2809 | -------- Summary: end -------- 14:14:51 INFO - PROCESS | 2809 | 14:14:52 INFO - TEST-INFO | 2809: exit 0 14:14:52 INFO - TEST-OK | dromaeo_css | took 1113301ms 14:14:52 INFO - TEST-START | kraken 14:14:52 INFO - operating with platform_type : linux_ 14:14:52 INFO - Initialising browser for kraken test... 14:14:52 INFO - TEST-INFO | started process 3217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMgtyXp/profile http://localhost:60526/getInfo.html) 14:14:56 INFO - PROCESS | 3217 | __metrics Screen width/height:1600/1200 14:14:56 INFO - PROCESS | 3217 | colorDepth:24 14:14:56 INFO - PROCESS | 3217 | Browser inner width/height: 1024/697 14:14:56 INFO - PROCESS | 3217 | __metrics 14:14:57 ERROR - PROCESS | 3217 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3197: TypeError: this.tabs is undefined 14:14:58 INFO - TEST-INFO | 3217: exit 0 14:14:58 INFO - Browser initialized. 14:14:58 INFO - Running cycle 1/1 for kraken test... 14:14:58 INFO - TEST-INFO | started process 3346 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMgtyXp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:14:58 CRITICAL - PROCESS | 3346 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 14:14:58 INFO - Terminating psutil.Process(pid=3346, name='firefox') 14:14:58 INFO - TEST-INFO | 3346: exit 127 14:14:58 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 14:14:58 ERROR - Traceback (most recent call last): 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 14:14:58 INFO - talos_results.add(mytest.runTest(browser_config, test)) 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 14:14:58 INFO - return self._runTest(browser_config, test_config, setup) 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 14:14:58 INFO - else None) 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 14:14:58 INFO - global_counters=self.global_counters 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 14:14:58 INFO - self.parse() 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 14:14:58 INFO - % self.report_tokens) 14:14:58 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 14:14:58 INFO - raise utils.TalosError(message) 14:14:58 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 14:14:58 INFO - TEST-INFO took 6109ms 14:14:58 INFO - SUITE-END | took 1119s 14:14:58 ERROR - Return code: 2 14:14:58 WARNING - setting return code to 2 14:14:58 CRITICAL - # TBPL RETRY # 14:14:58 WARNING - setting return code to 4 14:14:58 INFO - Running post-action listener: _resource_record_post_action 14:14:58 INFO - [mozharness: 2017-02-23 22:14:58.860173Z] Finished run-tests step (success) 14:14:58 INFO - Running post-run listener: _resource_record_post_run 14:14:58 INFO - Total resource usage - Wall time: 1134s; CPU: 13.0%; Read bytes: 8192; Write bytes: 269475840; Read time: 196; Write time: 195336 14:14:58 INFO - TinderboxPrint: CPU usage
13.3% 14:14:58 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 196 14:14:58 INFO - TinderboxPrint: I/O write bytes / time
269,475,840 / 195,336 14:14:58 INFO - TinderboxPrint: CPU idle
7,846.3 (86.7%) 14:14:58 INFO - TinderboxPrint: CPU user
1,161.8 (12.8%) 14:14:58 INFO - TinderboxPrint: Swap in / out
0 / 0 14:14:58 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 4096; Write bytes: 97370112; Read time: 136; Write time: 91868 14:14:59 INFO - run-tests - Wall time: 1120s; CPU: 13.0%; Read bytes: 4096; Write bytes: 167710720; Read time: 60; Write time: 102896 14:14:59 INFO - Running post-run listener: _upload_blobber_files 14:14:59 INFO - Blob upload gear active. 14:14:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:14:59 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 14:14:59 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'] 14:14:59 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 14:14:59 INFO - (blobuploader) - INFO - Open directory for files ... 14:14:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:14:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:15:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:03 INFO - (blobuploader) - INFO - Done attempting. 14:15:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:15:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:15:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:15:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:04 INFO - (blobuploader) - INFO - Done attempting. 14:15:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:15:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:15:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:15:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:15:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:15:05 INFO - (blobuploader) - INFO - Done attempting. 14:15:05 INFO - (blobuploader) - INFO - Iteration through files over. 14:15:05 INFO - Return code: 0 14:15:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:15:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:15:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5e517350d82de2cffda042ca583ca7681ffd399c8fd3a99f34e4f22e59557a993bd5c6ba4731681c64374bfc9843fabbda634216e026ea4485fbd8b1fb2a4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/84eb82f5cebb3db46faf8fe2eda0a81e11001e5ab7d84eaef805dcc9d602cd562489f49e651a69b92daf93e0d08955ad47b40c6a7946691837bcb210012668cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d34ecde947a426265ed7b1127fb6d859b5698c9a2051000f59a45c357a0f0f0e75e4a25b763491957b9a425939eebefc16817d18e6c6a63dda80798e375ccf1"} 14:15:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:15:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:15:05 INFO - Contents: 14:15:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5e517350d82de2cffda042ca583ca7681ffd399c8fd3a99f34e4f22e59557a993bd5c6ba4731681c64374bfc9843fabbda634216e026ea4485fbd8b1fb2a4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/84eb82f5cebb3db46faf8fe2eda0a81e11001e5ab7d84eaef805dcc9d602cd562489f49e651a69b92daf93e0d08955ad47b40c6a7946691837bcb210012668cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d34ecde947a426265ed7b1127fb6d859b5698c9a2051000f59a45c357a0f0f0e75e4a25b763491957b9a425939eebefc16817d18e6c6a63dda80798e375ccf1"} 14:15:05 INFO - Running post-run listener: copy_logs_to_upload_dir 14:15:05 INFO - Copying logs to upload dir... 14:15:05 INFO - mkdir: /builds/slave/test/build/upload/logs 14:15:05 INFO - Copying logs to upload dir... 14:15:05 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1180.585699 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 40 secs) (at 2017-02-23 14:15:05.262686) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 14:15:05.265775) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564 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/b5e517350d82de2cffda042ca583ca7681ffd399c8fd3a99f34e4f22e59557a993bd5c6ba4731681c64374bfc9843fabbda634216e026ea4485fbd8b1fb2a4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/84eb82f5cebb3db46faf8fe2eda0a81e11001e5ab7d84eaef805dcc9d602cd562489f49e651a69b92daf93e0d08955ad47b40c6a7946691837bcb210012668cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d34ecde947a426265ed7b1127fb6d859b5698c9a2051000f59a45c357a0f0f0e75e4a25b763491957b9a425939eebefc16817d18e6c6a63dda80798e375ccf1"} build_url:https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011373 build_url: 'https://queue.taskcluster.net/v1/task/CrYOxwWZRV22A6s47GtWww/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/b5e517350d82de2cffda042ca583ca7681ffd399c8fd3a99f34e4f22e59557a993bd5c6ba4731681c64374bfc9843fabbda634216e026ea4485fbd8b1fb2a4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/84eb82f5cebb3db46faf8fe2eda0a81e11001e5ab7d84eaef805dcc9d602cd562489f49e651a69b92daf93e0d08955ad47b40c6a7946691837bcb210012668cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d34ecde947a426265ed7b1127fb6d859b5698c9a2051000f59a45c357a0f0f0e75e4a25b763491957b9a425939eebefc16817d18e6c6a63dda80798e375ccf1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 14:15:05.290618) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 14:15:05.290928) ========= 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=20e20d796406ba5117c4a6f1000001ba-1487886262.430042-973203564 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004490 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 14:15:05.374084) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 14:15:05.374394) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 14:15:05.374678) ========= ========= Total master_lag: 0.17 =========