builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1488410967.86 results: retry (5) buildid: 20170301133922 builduid: b67885b8f9e94199842d821a6b095c0f revision: d1b4c5e38c8fabe11d2b9e9fb79441e53d568381 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.857071) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.857651) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.858162) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.878637) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.878957) ========= 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=826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-01 15:29:28-- 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% 20.2M=0.001s 2017-03-01 15:29:28 (20.2 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.077889 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.970891) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:27.971240) ========= 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=826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016522 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.021308) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.021783) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1b4c5e38c8fabe11d2b9e9fb79441e53d568381 --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 d1b4c5e38c8fabe11d2b9e9fb79441e53d568381 --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=826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-01 15:29:28,389 truncating revision to first 12 chars 2017-03-01 15:29:28,389 Setting DEBUG logging. 2017-03-01 15:29:28,389 attempt 1/10 2017-03-01 15:29:28,389 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1b4c5e38c8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-01 15:29:28,711 unpacking tar archive at: mozilla-beta-d1b4c5e38c8f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.623903 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.660214) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.660542) ========= script_repo_revision: d1b4c5e38c8fabe11d2b9e9fb79441e53d568381 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.660998) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.661311) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-01 15:29:28.672303) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 42 secs) (at 2017-03-01 15:29:28.672747) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:29:29 INFO - MultiFileLogger online at 20170301 15:29:29 in /builds/slave/test 15:29:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:29:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:29:29 INFO - {'append_to_log': False, 15:29:29 INFO - 'base_work_dir': '/builds/slave/test', 15:29:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:29:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:29:29 INFO - 'branch': 'Mozilla-Beta', 15:29:29 INFO - 'buildbot_json_path': 'buildprops.json', 15:29:29 INFO - 'config_files': ('talos/linux_config.py',), 15:29:29 INFO - 'default_actions': ('clobber', 15:29:29 INFO - 'read-buildbot-config', 15:29:29 INFO - 'download-and-extract', 15:29:29 INFO - 'populate-webroot', 15:29:29 INFO - 'create-virtualenv', 15:29:29 INFO - 'install', 15:29:29 INFO - 'run-tests'), 15:29:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:29:29 INFO - 'download_minidump_stackwalk': True, 15:29:29 INFO - 'download_symbols': 'ondemand', 15:29:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:29:29 INFO - 'tooltool.py': '/tools/tooltool.py', 15:29:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:29:29 INFO - '/tools/misc-python/virtualenv.py')}, 15:29:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:29:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:29:29 INFO - 'installer_path': 'installer.exe', 15:29:29 INFO - 'log_level': 'info', 15:29:29 INFO - 'log_name': 'talos', 15:29:29 INFO - 'log_to_console': True, 15:29:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:29:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:29:29 INFO - 'opt_config_files': (), 15:29:29 INFO - 'pip_index': False, 15:29:29 INFO - 'sps_profile': False, 15:29:29 INFO - 'sps_profile_interval': 0, 15:29:29 INFO - 'suite': 'dromaeojs', 15:29:29 INFO - 'system_bits': '32', 15:29:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:29:29 INFO - 'title': 'talos-linux64-ix-052', 15:29:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:29:29 INFO - 'use_talos_json': True, 15:29:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:29:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:29:29 INFO - 'work_dir': 'build'} 15:29:29 INFO - [mozharness: 2017-03-01 23:29:29.137269Z] Running clobber step. 15:29:29 INFO - Running pre-action listener: _resource_record_pre_action 15:29:29 INFO - Running main action method: clobber 15:29:29 INFO - rmtree: /builds/slave/test/build 15:29:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:29:30 INFO - Running post-action listener: _resource_record_post_action 15:29:30 INFO - [mozharness: 2017-03-01 23:29:30.578196Z] Finished clobber step (success) 15:29:30 INFO - [mozharness: 2017-03-01 23:29:30.578295Z] Running read-buildbot-config step. 15:29:30 INFO - Running pre-action listener: _resource_record_pre_action 15:29:30 INFO - Running main action method: read_buildbot_config 15:29:30 INFO - Using buildbot properties: 15:29:30 INFO - { 15:29:30 INFO - "project": "", 15:29:30 INFO - "product": "firefox", 15:29:30 INFO - "script_repo_revision": "production", 15:29:30 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:29:30 INFO - "repository": "", 15:29:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:29:30 INFO - "buildid": "20170301133922", 15:29:30 INFO - "pgo_build": "True", 15:29:30 INFO - "basedir": "/builds/slave/test", 15:29:30 INFO - "buildnumber": 33, 15:29:30 INFO - "slavename": "talos-linux64-ix-052", 15:29:30 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 15:29:30 INFO - "platform": "ubuntu64_hw", 15:29:30 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:29:30 INFO - "branch": "mozilla-beta", 15:29:30 INFO - "repo_path": "releases/mozilla-beta", 15:29:30 INFO - "revision": "d1b4c5e38c8fabe11d2b9e9fb79441e53d568381", 15:29:30 INFO - "stage_platform": "linux64-pgo", 15:29:30 INFO - "builduid": "b67885b8f9e94199842d821a6b095c0f", 15:29:30 INFO - "slavebuilddir": "test" 15:29:30 INFO - } 15:29:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2. 15:29:30 INFO - Running post-action listener: _resource_record_post_action 15:29:30 INFO - [mozharness: 2017-03-01 23:29:30.580709Z] Finished read-buildbot-config step (success) 15:29:30 INFO - [mozharness: 2017-03-01 23:29:30.580797Z] Running download-and-extract step. 15:29:30 INFO - Running pre-action listener: _resource_record_pre_action 15:29:30 INFO - Running main action method: download_and_extract 15:29:30 INFO - mkdir: /builds/slave/test/build/tests 15:29:30 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')]} 15:29:30 INFO - trying https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 15:29:30 INFO - Downloading https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/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 15:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/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 15:29:31 INFO - Downloaded 1327 bytes. 15:29:31 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.linux-x86_64.test_packages.json 15:29:31 INFO - Using the following test package requirements: 15:29:31 INFO - {u'common': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 15:29:31 INFO - u'cppunittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:29:31 INFO - u'gtest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.gtest.tests.zip'], 15:29:31 INFO - u'jittest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'jsshell-linux-x86_64.zip'], 15:29:31 INFO - u'mochitest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:29:31 INFO - u'mozbase': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'], 15:29:31 INFO - u'reftest': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.reftest.tests.zip'], 15:29:31 INFO - u'talos': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.talos.tests.zip'], 15:29:31 INFO - u'web-platform': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:29:31 INFO - u'xpcshell': [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip', 15:29:31 INFO - u'firefox-52.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:29:31 INFO - Downloading packages: [u'firefox-52.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:29:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 15:29:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:29:31 INFO - Fetch https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 15:29:31 INFO - Http code: 200 15:29:31 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 15:29:31 INFO - x-amz-cf-id: LMV89BHOE2kmYqH1Va6tNwhjDdVJDygsaHp4thr3qNr42S7Vt369Ew== 15:29:31 INFO - x-amz-version-id: 3PQSzlnQ2DUMj8pmRkeTJP7hrrvu7lmz 15:29:32 INFO - Expected file size: 21963572 15:29:32 INFO - Obtained file size: 21963572 15:29:36 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 15:29:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip 15:29:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:29:36 INFO - Fetch https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.common.tests.zip into memory 15:29:37 INFO - Http code: 200 15:29:37 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:29:37 INFO - x-amz-cf-id: zX2r6338OMP_05xExxE9thWaFrBLVpRx7PBaXtTAYTphxmiKegBoXQ== 15:29:37 INFO - x-amz-version-id: 3PQSzlnQ2DUMj8pmRkeTJP7hrrvu7lmz 15:29:37 INFO - Expected file size: 21963572 15:29:37 INFO - Obtained file size: 21963572 15:29:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip 15:29:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:29:41 INFO - Fetch https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.talos.tests.zip into memory 15:29:42 INFO - Http code: 200 15:29:42 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 15:29:42 INFO - x-amz-cf-id: XCfyHspLCYOEX1iZnDVkP643DcIs3hDjoO-2b6HR6b4ykx7PdSvTMw== 15:29:42 INFO - x-amz-version-id: S1iL1yrHDEHyaJGMzOVktPKwMaFaeel_ 15:29:43 INFO - Expected file size: 14402992 15:29:43 INFO - Obtained file size: 14402992 15:29:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:43 INFO - trying https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 15:29:43 INFO - Downloading https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/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 15:29:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/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 15:29:45 INFO - Downloaded 57740719 bytes. 15:29:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 15:29:45 INFO - mkdir: /builds/slave/test/properties 15:29:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:29:45 INFO - Writing to file /builds/slave/test/properties/build_url 15:29:45 INFO - Contents: 15:29:45 INFO - build_url:https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 15:29:46 INFO - Running post-action listener: _resource_record_post_action 15:29:46 INFO - Running post-action listener: set_extra_try_arguments 15:29:46 INFO - [mozharness: 2017-03-01 23:29:46.420817Z] Finished download-and-extract step (success) 15:29:46 INFO - [mozharness: 2017-03-01 23:29:46.421088Z] Running populate-webroot step. 15:29:46 INFO - Running pre-action listener: _resource_record_pre_action 15:29:46 INFO - Running main action method: populate_webroot 15:29:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:29:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:29:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:29:46 INFO - '--tppagecycles', 15:29:46 INFO - '7'], 15:29:46 INFO - 'tests': ['tsvgm']}}, 15:29:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:29:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:29:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:29:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:29:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:29:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['damp', 'tps']}, 15:29:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'tests': ['damp', 'tps']}, 15:29:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['dromaeo_dom']}, 15:29:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:29:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['basic_compositor_video']}, 15:29:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:29:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['a11yr', 15:29:46 INFO - 'ts_paint', 15:29:46 INFO - 'tpaint', 15:29:46 INFO - 'sessionrestore', 15:29:46 INFO - 'sessionrestore_no_auto_restore', 15:29:46 INFO - 'tabpaint']}, 15:29:46 INFO - 'other-e10s': {'tests': ['a11yr', 15:29:46 INFO - 'ts_paint', 15:29:46 INFO - 'tpaint', 15:29:46 INFO - 'sessionrestore', 15:29:46 INFO - 'sessionrestore_no_auto_restore', 15:29:46 INFO - 'tabpaint']}, 15:29:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:29:46 INFO - 'ts_paint', 15:29:46 INFO - 'tpaint', 15:29:46 INFO - 'sessionrestore', 15:29:46 INFO - 'sessionrestore_no_auto_restore', 15:29:46 INFO - 'tabpaint']}, 15:29:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:29:46 INFO - 'ts_paint', 15:29:46 INFO - 'tpaint', 15:29:46 INFO - 'sessionrestore', 15:29:46 INFO - 'sessionrestore_no_auto_restore', 15:29:46 INFO - 'tabpaint']}, 15:29:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['a11yr', 15:29:46 INFO - 'ts_paint', 15:29:46 INFO - 'tpaint', 15:29:46 INFO - 'sessionrestore', 15:29:46 INFO - 'sessionrestore_no_auto_restore', 15:29:46 INFO - 'tabpaint']}, 15:29:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['a11yr', 15:29:46 INFO - 'ts_paint', 15:29:46 INFO - 'tpaint', 15:29:46 INFO - 'sessionrestore', 15:29:46 INFO - 'sessionrestore_no_auto_restore', 15:29:46 INFO - 'tabpaint']}, 15:29:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['tsvgx', 15:29:46 INFO - 'tsvgr_opacity', 15:29:46 INFO - 'tart', 15:29:46 INFO - 'tscrollx', 15:29:46 INFO - 'cart']}, 15:29:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:29:46 INFO - 'tsvgr_opacity', 15:29:46 INFO - 'tart', 15:29:46 INFO - 'tscrollx', 15:29:46 INFO - 'cart']}, 15:29:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'talos_options': ['--disable-e10s'], 15:29:46 INFO - 'tests': ['tp5o']}, 15:29:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'tests': ['tp5o']}, 15:29:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'talos_options': ['--disable-e10s', 15:29:46 INFO - '--xperf_path', 15:29:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:46 INFO - 'tests': ['tp5n']}, 15:29:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:29:46 INFO - 'talos_options': ['--xperf_path', 15:29:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:29:46 INFO - 'tests': ['tp5n']}}, 15:29:46 INFO - 'talos.zip': {'path': '', 15:29:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:29:46 INFO - Running post-action listener: _resource_record_post_action 15:29:46 INFO - [mozharness: 2017-03-01 23:29:46.436775Z] Finished populate-webroot step (success) 15:29:46 INFO - [mozharness: 2017-03-01 23:29:46.436863Z] Running create-virtualenv step. 15:29:46 INFO - Running pre-action listener: _resource_record_pre_action 15:29:46 INFO - Running main action method: create_virtualenv 15:29:46 INFO - Creating virtualenv /builds/slave/test/build/venv 15:29:46 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 15:29:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:29:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:29:46 INFO - Using real prefix '/usr' 15:29:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:29:47 INFO - Installing distribute.............................................................................................................................................................................................done. 15:29:49 INFO - Installing pip.................done. 15:29:49 INFO - Return code: 0 15:29:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:29:49 INFO - Reading from file tmpfile_stdout 15:29:49 INFO - Output received: 15:29:49 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) 15:29:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:29:49 INFO - Using env: {'DISPLAY': ':0', 15:29:49 INFO - 'HOME': '/home/cltbld', 15:29:49 INFO - 'LANG': 'en_US.UTF-8', 15:29:49 INFO - 'LANGUAGE': 'en_US:en', 15:29:49 INFO - 'LOGNAME': 'cltbld', 15:29:49 INFO - 'MAIL': '/var/mail/cltbld', 15:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:49 INFO - 'MOZ_NO_REMOTE': '1', 15:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:49 INFO - 'NO_EM_RESTART': '1', 15:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:49 INFO - 'PWD': '/builds/slave/test', 15:29:49 INFO - 'SHELL': '/bin/bash', 15:29:49 INFO - 'SHLVL': '1', 15:29:49 INFO - 'TERM': 'linux', 15:29:49 INFO - 'TMOUT': '86400', 15:29:49 INFO - 'USER': 'cltbld', 15:29:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:49 INFO - '_': '/tools/buildbot/bin/python'} 15:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:49 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 15:29:49 INFO - Cleaning up... 15:29:49 INFO - Return code: 0 15:29:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:29:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:29:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:29:49 INFO - Using env: {'DISPLAY': ':0', 15:29:49 INFO - 'HOME': '/home/cltbld', 15:29:49 INFO - 'LANG': 'en_US.UTF-8', 15:29:49 INFO - 'LANGUAGE': 'en_US:en', 15:29:49 INFO - 'LOGNAME': 'cltbld', 15:29:49 INFO - 'MAIL': '/var/mail/cltbld', 15:29:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:49 INFO - 'MOZ_NO_REMOTE': '1', 15:29:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:49 INFO - 'NO_EM_RESTART': '1', 15:29:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:49 INFO - 'PWD': '/builds/slave/test', 15:29:49 INFO - 'SHELL': '/bin/bash', 15:29:49 INFO - 'SHLVL': '1', 15:29:49 INFO - 'TERM': 'linux', 15:29:49 INFO - 'TMOUT': '86400', 15:29:49 INFO - 'USER': 'cltbld', 15:29:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:29:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:49 INFO - '_': '/tools/buildbot/bin/python'} 15:29:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:49 INFO - Downloading/unpacking psutil>=3.1.1 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:29:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:51 INFO - Installing collected packages: psutil 15:29:51 INFO - Running setup.py install for psutil 15:29:51 INFO - building 'psutil._psutil_linux' extension 15:29:51 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 15:29:52 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 15:29:52 INFO - building 'psutil._psutil_posix' extension 15:29:52 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 15:29:52 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 15:29:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:29:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:29:52 INFO - Successfully installed psutil 15:29:52 INFO - Cleaning up... 15:29:52 INFO - Return code: 0 15:29:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:29:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:52 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:29:52 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 15:29:52 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 15:29:52 INFO - Using env: {'DISPLAY': ':0', 15:29:52 INFO - 'HOME': '/home/cltbld', 15:29:52 INFO - 'LANG': 'en_US.UTF-8', 15:29:52 INFO - 'LANGUAGE': 'en_US:en', 15:29:52 INFO - 'LOGNAME': 'cltbld', 15:29:52 INFO - 'MAIL': '/var/mail/cltbld', 15:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:52 INFO - 'MOZ_NO_REMOTE': '1', 15:29:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:52 INFO - 'NO_EM_RESTART': '1', 15:29:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:52 INFO - 'PWD': '/builds/slave/test', 15:29:52 INFO - 'SHELL': '/bin/bash', 15:29:52 INFO - 'SHLVL': '1', 15:29:52 INFO - 'TERM': 'linux', 15:29:52 INFO - 'TMOUT': '86400', 15:29:52 INFO - 'USER': 'cltbld', 15:29:52 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:52 INFO - '_': '/tools/buildbot/bin/python'} 15:29:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29: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 15:29:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:29:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:29:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:29:54 INFO - Installing collected packages: mozsystemmonitor 15:29:54 INFO - Running setup.py install for mozsystemmonitor 15:29:54 INFO - Successfully installed mozsystemmonitor 15:29:54 INFO - Cleaning up... 15:29:54 INFO - Return code: 0 15:29:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:29:54 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')]} 15:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:54 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')]} 15:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:54 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:29:54 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 15:29:54 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 15:29:54 INFO - Using env: {'DISPLAY': ':0', 15:29:54 INFO - 'HOME': '/home/cltbld', 15:29:54 INFO - 'LANG': 'en_US.UTF-8', 15:29:54 INFO - 'LANGUAGE': 'en_US:en', 15:29:54 INFO - 'LOGNAME': 'cltbld', 15:29:54 INFO - 'MAIL': '/var/mail/cltbld', 15:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:54 INFO - 'MOZ_NO_REMOTE': '1', 15:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:54 INFO - 'NO_EM_RESTART': '1', 15:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:54 INFO - 'PWD': '/builds/slave/test', 15:29:54 INFO - 'SHELL': '/bin/bash', 15:29:54 INFO - 'SHLVL': '1', 15:29:54 INFO - 'TERM': 'linux', 15:29:54 INFO - 'TMOUT': '86400', 15:29:54 INFO - 'USER': 'cltbld', 15:29:54 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:54 INFO - '_': '/tools/buildbot/bin/python'} 15:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:54 INFO - Downloading/unpacking jsonschema==2.5.1 15:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:29:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:57 INFO - Downloading functools32-3.2.3-2.tar.gz 15:29:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:29:57 INFO - warning: no files found matching '*.txt' 15:29:57 INFO - no previously-included directories found matching 'build' 15:29:57 INFO - no previously-included directories found matching 'dist' 15:29:57 INFO - no previously-included directories found matching '.git*' 15:29:57 INFO - Installing collected packages: jsonschema, functools32 15:29:57 INFO - Running setup.py install for functools32 15:29:57 INFO - warning: no files found matching '*.txt' 15:29:57 INFO - no previously-included directories found matching 'build' 15:29:57 INFO - no previously-included directories found matching 'dist' 15:29:57 INFO - no previously-included directories found matching '.git*' 15:29:57 INFO - Successfully installed jsonschema functools32 15:29:57 INFO - Cleaning up... 15:29:57 INFO - Return code: 0 15:29:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:29: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')]} 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29: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')]} 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:57 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:29:57 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 15:29:57 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 15:29:57 INFO - Using env: {'DISPLAY': ':0', 15:29:57 INFO - 'HOME': '/home/cltbld', 15:29:57 INFO - 'LANG': 'en_US.UTF-8', 15:29:57 INFO - 'LANGUAGE': 'en_US:en', 15:29:57 INFO - 'LOGNAME': 'cltbld', 15:29:57 INFO - 'MAIL': '/var/mail/cltbld', 15:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:57 INFO - 'MOZ_NO_REMOTE': '1', 15:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:57 INFO - 'NO_EM_RESTART': '1', 15:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:57 INFO - 'PWD': '/builds/slave/test', 15:29:57 INFO - 'SHELL': '/bin/bash', 15:29:57 INFO - 'SHLVL': '1', 15:29:57 INFO - 'TERM': 'linux', 15:29:57 INFO - 'TMOUT': '86400', 15:29:57 INFO - 'USER': 'cltbld', 15:29:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:57 INFO - '_': '/tools/buildbot/bin/python'} 15:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:29:57 INFO - Cleaning up... 15:29:57 INFO - Return code: 0 15:29:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:29: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')]} 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29: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')]} 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:29:57 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:29:57 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 15:29:57 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 15:29:57 INFO - Using env: {'DISPLAY': ':0', 15:29:57 INFO - 'HOME': '/home/cltbld', 15:29:57 INFO - 'LANG': 'en_US.UTF-8', 15:29:57 INFO - 'LANGUAGE': 'en_US:en', 15:29:57 INFO - 'LOGNAME': 'cltbld', 15:29:57 INFO - 'MAIL': '/var/mail/cltbld', 15:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:29:57 INFO - 'MOZ_NO_REMOTE': '1', 15:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:29:57 INFO - 'NO_EM_RESTART': '1', 15:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:29:57 INFO - 'PWD': '/builds/slave/test', 15:29:57 INFO - 'SHELL': '/bin/bash', 15:29:57 INFO - 'SHLVL': '1', 15:29:57 INFO - 'TERM': 'linux', 15:29:57 INFO - 'TMOUT': '86400', 15:29:57 INFO - 'USER': 'cltbld', 15:29:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:29:57 INFO - '_': '/tools/buildbot/bin/python'} 15:29:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:29:57 INFO - Downloading/unpacking blobuploader==1.2.4 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:29:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:29:59 INFO - Downloading blobuploader-1.2.4.tar.gz 15:29:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:30:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:30:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:30:00 INFO - Downloading docopt-0.6.1.tar.gz 15:30:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:30:00 INFO - Installing collected packages: blobuploader, requests, docopt 15:30:00 INFO - Running setup.py install for blobuploader 15:30:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:30:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:30:00 INFO - Running setup.py install for requests 15:30:01 INFO - Running setup.py install for docopt 15:30:01 INFO - Successfully installed blobuploader requests docopt 15:30:01 INFO - Cleaning up... 15:30:01 INFO - Return code: 0 15:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:01 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')]} 15:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:01 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')]} 15:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:01 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:30:01 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 15:30:01 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 15:30:01 INFO - Using env: {'DISPLAY': ':0', 15:30:01 INFO - 'HOME': '/home/cltbld', 15:30:01 INFO - 'LANG': 'en_US.UTF-8', 15:30:01 INFO - 'LANGUAGE': 'en_US:en', 15:30:01 INFO - 'LOGNAME': 'cltbld', 15:30:01 INFO - 'MAIL': '/var/mail/cltbld', 15:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:01 INFO - 'MOZ_NO_REMOTE': '1', 15:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:01 INFO - 'NO_EM_RESTART': '1', 15:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:01 INFO - 'PWD': '/builds/slave/test', 15:30:01 INFO - 'SHELL': '/bin/bash', 15:30:01 INFO - 'SHLVL': '1', 15:30:01 INFO - 'TERM': 'linux', 15:30:01 INFO - 'TMOUT': '86400', 15:30:01 INFO - 'USER': 'cltbld', 15:30:01 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:01 INFO - '_': '/tools/buildbot/bin/python'} 15:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:01 INFO - Running setup.py (path:/tmp/pip-XP813R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:01 INFO - Running setup.py (path:/tmp/pip-3KL0IW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:01 INFO - Running setup.py (path:/tmp/pip-wNgxWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:01 INFO - Running setup.py (path:/tmp/pip-Hg8LQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:01 INFO - Running setup.py (path:/tmp/pip-pS4agS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:01 INFO - Running setup.py (path:/tmp/pip-d5kblR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:02 INFO - Running setup.py (path:/tmp/pip-Mv1qR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:02 INFO - Running setup.py (path:/tmp/pip-E0uq_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:02 INFO - Running setup.py (path:/tmp/pip-u2qCIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:02 INFO - Running setup.py (path:/tmp/pip-Z9h83E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:02 INFO - Running setup.py (path:/tmp/pip-8a_jYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:02 INFO - Running setup.py (path:/tmp/pip-S2BrS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:02 INFO - Running setup.py (path:/tmp/pip-VaWbWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:02 INFO - Running setup.py (path:/tmp/pip-akIMsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:02 INFO - Running setup.py (path:/tmp/pip-Z9Zl_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:02 INFO - Running setup.py (path:/tmp/pip-F9jxXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:02 INFO - Running setup.py (path:/tmp/pip-kC3d68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:30:02 INFO - Running setup.py install for manifestparser 15:30:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:30:02 INFO - Running setup.py install for mozcrash 15:30:03 INFO - Running setup.py install for mozdebug 15:30:03 INFO - Running setup.py install for mozdevice 15:30:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Running setup.py install for mozfile 15:30:03 INFO - Running setup.py install for mozhttpd 15:30:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Running setup.py install for mozinfo 15:30:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Running setup.py install for mozInstall 15:30:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Running setup.py install for mozleak 15:30:03 INFO - Running setup.py install for mozlog 15:30:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Running setup.py install for moznetwork 15:30:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:30:03 INFO - Running setup.py install for mozprocess 15:30:04 INFO - Running setup.py install for mozprofile 15:30:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:30:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:30:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:30:04 INFO - Running setup.py install for mozrunner 15:30:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:30:04 INFO - Running setup.py install for mozscreenshot 15:30:04 INFO - Running setup.py install for moztest 15:30:04 INFO - Running setup.py install for mozversion 15:30:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:30:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:30:04 INFO - Cleaning up... 15:30:04 INFO - Return code: 0 15:30:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30: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')]} 15:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30: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')]} 15:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:04 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:30:04 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 15:30:04 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 15:30:04 INFO - Using env: {'DISPLAY': ':0', 15:30:04 INFO - 'HOME': '/home/cltbld', 15:30:04 INFO - 'LANG': 'en_US.UTF-8', 15:30:04 INFO - 'LANGUAGE': 'en_US:en', 15:30:04 INFO - 'LOGNAME': 'cltbld', 15:30:04 INFO - 'MAIL': '/var/mail/cltbld', 15:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:04 INFO - 'MOZ_NO_REMOTE': '1', 15:30:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:04 INFO - 'NO_EM_RESTART': '1', 15:30:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:04 INFO - 'PWD': '/builds/slave/test', 15:30:04 INFO - 'SHELL': '/bin/bash', 15:30:04 INFO - 'SHLVL': '1', 15:30:04 INFO - 'TERM': 'linux', 15:30:04 INFO - 'TMOUT': '86400', 15:30:04 INFO - 'USER': 'cltbld', 15:30:04 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:04 INFO - '_': '/tools/buildbot/bin/python'} 15:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:30:04 INFO - Running setup.py (path:/tmp/pip-E9XU3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:30:04 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)) 15:30:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:30:04 INFO - Running setup.py (path:/tmp/pip-NOnAHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:30:05 INFO - Running setup.py (path:/tmp/pip-ctkRrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:30:05 INFO - Running setup.py (path:/tmp/pip-nFshlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:30:05 INFO - Running setup.py (path:/tmp/pip-1rexJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:30:05 INFO - Running setup.py (path:/tmp/pip-xgwi2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:30:05 INFO - Running setup.py (path:/tmp/pip-r19dFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:30:05 INFO - Running setup.py (path:/tmp/pip-e7LWH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:30:05 INFO - Running setup.py (path:/tmp/pip-jmSpxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:30:05 INFO - Running setup.py (path:/tmp/pip-fSCywk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:30:05 INFO - Running setup.py (path:/tmp/pip-_ItvBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:30:05 INFO - Running setup.py (path:/tmp/pip-J0wEDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:30:05 INFO - Running setup.py (path:/tmp/pip-p0Wv08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:30:05 INFO - Running setup.py (path:/tmp/pip-R3wPQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:05 INFO - Running setup.py (path:/tmp/pip-ypk3li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:30:05 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)) 15:30:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:30:05 INFO - Running setup.py (path:/tmp/pip-cxwJzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:30:06 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)) 15:30:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:30:06 INFO - Running setup.py (path:/tmp/pip-Cl0Y4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:30:06 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)) 15:30:06 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)) 15:30:06 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)) 15:30:06 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)) 15:30:06 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)) 15:30:06 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)) 15:30:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:30:06 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 15:30:06 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 15:30:06 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 15:30:06 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 15:30:06 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 15:30:06 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 15:30:08 INFO - Downloading blessings-1.6.tar.gz 15:30:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:30:08 INFO - Installing collected packages: blessings 15:30:08 INFO - Running setup.py install for blessings 15:30:08 INFO - Successfully installed blessings 15:30:08 INFO - Cleaning up... 15:30:08 INFO - Return code: 0 15:30:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:30:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:08 INFO - Reading from file tmpfile_stdout 15:30:08 INFO - Current package versions: 15:30:08 INFO - argparse == 1.2.1 15:30:08 INFO - blessings == 1.6 15:30:08 INFO - blobuploader == 1.2.4 15:30:08 INFO - docopt == 0.6.1 15:30:08 INFO - functools32 == 3.2.3-2 15:30:08 INFO - jsonschema == 2.5.1 15:30:08 INFO - manifestparser == 1.1 15:30:08 INFO - mozInstall == 1.12 15:30:08 INFO - mozcrash == 1.0 15:30:08 INFO - mozdebug == 0.1 15:30:08 INFO - mozdevice == 0.48 15:30:08 INFO - mozfile == 1.2 15:30:08 INFO - mozhttpd == 0.7 15:30:08 INFO - mozinfo == 0.9 15:30:08 INFO - mozleak == 0.1 15:30:08 INFO - mozlog == 3.4 15:30:08 INFO - moznetwork == 0.27 15:30:08 INFO - mozprocess == 0.23 15:30:08 INFO - mozprofile == 0.28 15:30:08 INFO - mozrunner == 6.13 15:30:08 INFO - mozscreenshot == 0.1 15:30:08 INFO - mozsystemmonitor == 0.3 15:30:08 INFO - moztest == 0.8 15:30:08 INFO - mozversion == 1.4 15:30:08 INFO - psutil == 3.1.1 15:30:08 INFO - requests == 1.2.3 15:30:08 INFO - wsgiref == 0.1.2 15:30:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:30:08 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 15:30:08 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 15:30:08 INFO - Using env: {'DISPLAY': ':0', 15:30:08 INFO - 'HOME': '/home/cltbld', 15:30:08 INFO - 'LANG': 'en_US.UTF-8', 15:30:08 INFO - 'LANGUAGE': 'en_US:en', 15:30:08 INFO - 'LOGNAME': 'cltbld', 15:30:08 INFO - 'MAIL': '/var/mail/cltbld', 15:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:08 INFO - 'MOZ_NO_REMOTE': '1', 15:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:08 INFO - 'NO_EM_RESTART': '1', 15:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:08 INFO - 'PWD': '/builds/slave/test', 15:30:08 INFO - 'SHELL': '/bin/bash', 15:30:08 INFO - 'SHLVL': '1', 15:30:08 INFO - 'TERM': 'linux', 15:30:08 INFO - 'TMOUT': '86400', 15:30:08 INFO - 'USER': 'cltbld', 15:30:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:08 INFO - '_': '/tools/buildbot/bin/python'} 15:30:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 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)) 15:30:08 INFO - Cleaning up... 15:30:08 INFO - Return code: 0 15:30:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:30:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:30:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:30:08 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 0x7f56fc38cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x18daca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, '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': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', '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 15:30:08 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 15:30:08 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 15:30:08 INFO - Using env: {'DISPLAY': ':0', 15:30:08 INFO - 'HOME': '/home/cltbld', 15:30:08 INFO - 'LANG': 'en_US.UTF-8', 15:30:08 INFO - 'LANGUAGE': 'en_US:en', 15:30:08 INFO - 'LOGNAME': 'cltbld', 15:30:08 INFO - 'MAIL': '/var/mail/cltbld', 15:30:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:08 INFO - 'MOZ_NO_REMOTE': '1', 15:30:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:08 INFO - 'NO_EM_RESTART': '1', 15:30:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:08 INFO - 'PWD': '/builds/slave/test', 15:30:08 INFO - 'SHELL': '/bin/bash', 15:30:08 INFO - 'SHLVL': '1', 15:30:08 INFO - 'TERM': 'linux', 15:30:08 INFO - 'TMOUT': '86400', 15:30:08 INFO - 'USER': 'cltbld', 15:30:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:30:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:08 INFO - '_': '/tools/buildbot/bin/python'} 15:30:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:30:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:30:09 INFO - Cleaning up... 15:30:09 INFO - Return code: 0 15:30:09 INFO - Running post-action listener: _resource_record_post_action 15:30:09 INFO - Running post-action listener: _start_resource_monitoring 15:30:09 INFO - Starting resource monitoring. 15:30:09 INFO - [mozharness: 2017-03-01 23:30:09.086477Z] Finished create-virtualenv step (success) 15:30:09 INFO - [mozharness: 2017-03-01 23:30:09.086931Z] Running install step. 15:30:09 INFO - Running pre-action listener: _resource_record_pre_action 15:30:09 INFO - Running main action method: install 15:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:30:09 INFO - Reading from file tmpfile_stdout 15:30:09 INFO - Detecting whether we're running mozinstall >=1.0... 15:30:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:30:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:30:09 INFO - Reading from file tmpfile_stdout 15:30:09 INFO - Output received: 15:30:09 INFO - Usage: mozinstall [options] installer 15:30:09 INFO - Options: 15:30:09 INFO - -h, --help show this help message and exit 15:30:09 INFO - -d DEST, --destination=DEST 15:30:09 INFO - Directory to install application into. [default: 15:30:09 INFO - "/builds/slave/test"] 15:30:09 INFO - --app=APP Application being installed. [default: firefox] 15:30:09 INFO - mkdir: /builds/slave/test/build/application 15:30:09 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'] 15:30:09 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 15:30:23 INFO - Reading from file tmpfile_stdout 15:30:23 INFO - Output received: 15:30:23 INFO - /builds/slave/test/build/application/firefox/firefox 15:30:23 INFO - Running post-action listener: _resource_record_post_action 15:30:23 INFO - [mozharness: 2017-03-01 23:30:23.011222Z] Finished install step (success) 15:30:23 INFO - [mozharness: 2017-03-01 23:30:23.011465Z] Running run-tests step. 15:30:23 INFO - Running pre-action listener: _resource_record_pre_action 15:30:23 INFO - Running main action method: run_tests 15:30:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:30:23 INFO - Python 2.7.3 15:30:23 INFO - Return code: 0 15:30:23 INFO - grabbing minidump binary from tooltool 15:30:23 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')]} 15:30:23 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 0x18d7f60>, '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 0x18d8d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18d91d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:30:23 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 15:30:23 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 15:30:23 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 15:30:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:30:23 INFO - Return code: 0 15:30:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:30:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:30:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:30:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:30:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:30:23 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:30:23 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:30:23 INFO - Using env: {'DISPLAY': ':0', 15:30:23 INFO - 'HOME': '/home/cltbld', 15:30:23 INFO - 'LANG': 'en_US.UTF-8', 15:30:23 INFO - 'LANGUAGE': 'en_US:en', 15:30:23 INFO - 'LOGNAME': 'cltbld', 15:30:23 INFO - 'MAIL': '/var/mail/cltbld', 15:30:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:30:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:30:23 INFO - 'MOZ_NO_REMOTE': '1', 15:30:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:30:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:30:23 INFO - 'NO_EM_RESTART': '1', 15:30:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:30:23 INFO - 'PWD': '/builds/slave/test', 15:30:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:30:23 INFO - 'SHELL': '/bin/bash', 15:30:23 INFO - 'SHLVL': '1', 15:30:23 INFO - 'TERM': 'linux', 15:30:23 INFO - 'TMOUT': '86400', 15:30:23 INFO - 'USER': 'cltbld', 15:30:23 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095', 15:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:30:23 INFO - '_': '/tools/buildbot/bin/python'} 15:30:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:30:23 INFO - mozversion application_buildid: 20170301133922 15:30:23 INFO - mozversion application_changeset: d1b4c5e38c8fabe11d2b9e9fb79441e53d568381 15:30:23 INFO - mozversion application_display_name: Firefox 15:30:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:30:23 INFO - mozversion application_name: Firefox 15:30:23 INFO - mozversion application_remotingname: firefox 15:30:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:30:23 INFO - mozversion application_vendor: Mozilla 15:30:23 INFO - mozversion application_version: 52.0 15:30:23 INFO - mozversion platform_buildid: 20170301133922 15:30:23 INFO - mozversion platform_changeset: d1b4c5e38c8fabe11d2b9e9fb79441e53d568381 15:30:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:30:23 INFO - mozversion platform_version: 52.0 15:30:23 INFO - using testdate: 1488411023 15:30:23 INFO - actual date: 1488411023 15:30:23 INFO - starting webserver on 'localhost:51337' 15:30:23 INFO - SUITE-START | Running 2 tests 15:30:23 INFO - TEST-START | dromaeo_css 15:30:23 INFO - operating with platform_type : linux_ 15:30:23 INFO - Initialising browser for dromaeo_css test... 15:30:23 INFO - TEST-INFO | started process 29805 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy5fWIr/profile http://localhost:51337/getInfo.html) 15:30:27 INFO - PROCESS | 29805 | __metrics Screen width/height:1600/1200 15:30:27 INFO - PROCESS | 29805 | colorDepth:24 15:30:27 INFO - PROCESS | 29805 | Browser inner width/height: 1024/697 15:30:27 INFO - PROCESS | 29805 | __metrics 15:30:28 INFO - PROCESS | 29805 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:30:28 INFO - PROCESS | 29805 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:30:29 INFO - TEST-INFO | 29805: exit 0 15:30:29 INFO - Browser initialized. 15:30:29 INFO - Running cycle 1/1 for dromaeo_css test... 15:30:29 INFO - TEST-INFO | started process 29896 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpy5fWIr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:30:40 INFO - PROCESS | 29896 | RSS: Main: 140713984 15:30:40 INFO - PROCESS | 29896 | 15:33:42 INFO - PROCESS | 29896 | Cycle 1(1): loaded http://localhost:51337/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51337/tests/dromaeo/cssquery-ext.html) 15:33:43 INFO - PROCESS | 29896 | RSS: Main: 250875904 15:33:43 INFO - PROCESS | 29896 | 15:36:46 INFO - PROCESS | 29896 | Cycle 1(1): loaded http://localhost:51337/tests/dromaeo/cssquery-ext.html (next: http://localhost:51337/tests/dromaeo/cssquery-jquery.html) 15:36:46 INFO - PROCESS | 29896 | RSS: Main: 232148992 15:36:46 INFO - PROCESS | 29896 | 15:39:48 INFO - PROCESS | 29896 | Cycle 1(1): loaded http://localhost:51337/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51337/tests/dromaeo/cssquery-mootools.html) 15:39:49 INFO - PROCESS | 29896 | RSS: Main: 175583232 15:39:49 INFO - PROCESS | 29896 | 15:42:51 INFO - PROCESS | 29896 | Cycle 1(1): loaded http://localhost:51337/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51337/tests/dromaeo/cssquery-prototype.html) 15:42:51 INFO - PROCESS | 29896 | RSS: Main: 217325568 15:42:51 INFO - PROCESS | 29896 | 15:45:54 INFO - PROCESS | 29896 | Cycle 1(1): loaded http://localhost:51337/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51337/tests/dromaeo/cssquery-yui.html) 15:45:54 INFO - PROCESS | 29896 | RSS: Main: 171880448 15:45:54 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | Cycle 1(1): loaded http://localhost:51337/tests/dromaeo/cssquery-yui.html (next: http://localhost:51337/tests/dromaeo/cssquery-dojo.html) 15:48:57 INFO - PROCESS | 29896 | RSS: Main: 270008320 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | __start_tp_report 15:48:57 INFO - PROCESS | 29896 | _x_x_mozilla_page_load 15:48:57 INFO - PROCESS | 29896 | _x_x_mozilla_page_load_details 15:48:57 INFO - PROCESS | 29896 | |i|pagename|runs| 15:48:57 INFO - PROCESS | 29896 | |0;dojo.html;3192.409404260212;3215.0515597898634;3218.9195270118253;3243;3265.8367081645924;5619.83140505785;5725.4847063764255;5734.627249228797;5739.942600573998;5751.683657398841;5658.349289831671;5727.942720572793;5735.2544169257935;5741.45456181662;5744.540436765059;5134.05020071288;5169.043726910515;5174.637775355736;5210.2445145453885;5239.266502689627;5052.0906236877345;5056.342675452186;5227.843164705046;5248.186531087668;5278;5100.770465329069;5114.156164232897;5128.948710512885;5130.127878260705;5145.151050076733;5142.460041695625;5142.51146141116;5189.42916279209;5195.1687729963405;5215.947840521584;4942.728149951751;5047.520485553903;5128.1282182029145;5169.922451163236;5180.352455943007;5478.643888147298;5499.642523235977;5560.054790685593;5564.972175139139;5583.218349431083;6012.158297838306;6021.7892373767145;6129.785457508966;6139;6139.355367686373;5906.409359064102;6174.660393678346;6175.69121543921;6190.164327815733;6192.102145188923;5545.77816887324;5571.804986825482;5619.943800562024;5634.267545219095;5656;6196.411340922605;6201.472874805692;6201.503879689704;6207.6585787782105;6238;5780.94219057804;5987.281526216795;5989.520838332923;5995.370486098896;6001.159837622737;5903.911441328383;5964.970175149097;6025.66858822769;6031.2159419276095;6128.17269668598;6055.031195008777;6069.817905462844;6070.423309785563;6071.908921366183;6113.113598528189;6018.578699490994;6060.393960603992;6071.241094863142;6074.878502429927;6082.057281121433;4571.634269258451;4599.839005634787;4667.323238130452;4683.7892294846815;4696.0842635686395;4150.64719498846;4158.334666453353;4159.604837540429;4179.6029377209115;4183.100633363842;5825.7378417971295;5834.299884013861;5835.4748072673665;5835.766569337264;5842.182094506772;5809.128630705428;5887.646741195542;5936.436038576328;5988.071848863433;6138.600990935575;5645;5658.688772117573;5665.773369065274;5673.517750991212;5692.260006199168;5173.896522069537;5181.3782346118705;5182.326297581366;5183.688978661293;5213.582913366921;6134.7121296814075;6137.959273351834;6198.6280823149855;6273.090401891792;6347.5239357047485;6098.481629061402;6114.206128133634;6114.996965385453;6116.305337585956;6118.588722523033;6330.082138090043;6336.556441049082;6340.207474065742;6341.651209183539;6348.650824204713;5856.824295270978;5871.500922421471;5900.203472531153;6000.039993601003;6005.849853753691;5979.342272350125;6077.027675571887;6106.297775755845;6130.295016073031;6134.601250918676;5830.53355731532;5936.554758393051;5944.108383742473;5958.553108516792;5958.940410595838;5764.250647415809;5819.883602328015;5920.2895652521975;5934.9406505934385;5939.821805345847;5764.337101233412;5795.82612521625;5796.0726283796075;5844.883102338014;5881.529477641693;5928.318243402064;5933;5937.584369094122;5939.227900372924;5942.108683697307;5671.602987790815;5729.968605651023;5752.798652047326;5765.279340082489;5787.797427090031 15:48:57 INFO - PROCESS | 29896 | |1;ext.html;583.2854752927664;604.8941435248831;630.1776182082382;665.8634979829136;673.3703986772372;34003.65996340036;44885;45785;46037.53962460374;46110.30834537476;46018.30972535415;46143.769281153545;46557;46740.29889551659;46813;8905.376623636332;9012;9039.954800225989;9041.547922603877;9049.049849765779;8512.957435212815;8747.16901894319;10800.78398432031;11044.613438529654;11045;11158.832617510743;11228.887711122907;11233;11236.044936180435;11239.044681202107;10846.240763146583;10896.99999999996;10900.563977440894;10910.23628346016;10911.727206819814;10803.459827008697;10867;10871.402072885989;10873.619423320146;10882.075023622923;44685;44999.100017999786;45012.3248151278;45102;45165;3732.64539868712;3740.682042026431;3834.9233015339814;3859.8842034739005;4100.220958017967;32806.629724719685;33395.83302083474;33674.663253367646;33762.662373376195;33904.49143262853;4523.117991991563;4583.49581546033;4593.862184134481;4777.331173635694;4791.2813078038225;360774.9999999974;361310;363379;363812;364451;67043;67317.32682673208;67355.66322168357;67453;67601.32398676049;70263.64868175729;70317.64841175864;70469.2953070473;70471.2952870475;70484;19163.904180479007;19353.709694354595;19423.902880485788;19438.222471101;19449.708254376197;12977.870221297855;13222;14241.928790355982;14341.139531628136;14369;17945.031092875666;18210.27158913655;19298.4210473686;20724.171033158807;20852.37442876716;6710.261871194165;6815.114035175396;6839.863202736017;6898;7001.964990175118;987.5605355616682;1049.2393015064017;1098.5441041967654;1114.1421105748527;1126.8760436351995;33186.66813331836;33429.498557521656;33543.329133417195;33625.15937101592;33739.81910633116;1153.1639561318145;1153.9711507212387;1154.280122617311;1163.6683545189749;1192.7077865922904;16831.74752378715;17735.822641773677;17854.107294635476;17865.106744662717;17917.910410447865;510666;514706;516437;517710;520146;62197.37802621916;62675;62771.05843412352;62786.0582091269;62793;1327.7543654423769;1363.2570249214202;1372.848986611452;1377.5454100146928;1422.8719415252676;31701.207469812518;35023.47464788081;35084.824575876955;35086;35124.473132902516;56037.71981140068;56569;56896.715516422155;56983.43016569947;57115.42884571101;452417;456751;458266;459117;459140;37940;40076.398854017796;40906.79546602248;43831.561684382745;47002.52997469981;49487.50512494973;49641.50358496369;49642.255366168814;49962.50037499578;50193.49806502034;41719.16561668689;43360;44334.778326108164;44414.777926111456;44933.775331123135;42732;43363.56636433722;44125.55874441215;44301.77849110734;44843;42109;42850.78574607107;43097.78451107725;43173.78413107914;43331.566684332756;36190.09524761902;37900.81049594734;38116.237675245786;38805.80597096997;38839.805800971946;27879;28323.858380707963;29101.272468188465;29122.12633620995;29201.7079829199 15:48:57 INFO - PROCESS | 29896 | |2;jquery.html;413.5864135864136;422.9259879521087;432.56743256743266;434.1729006243107;438.1565486438605;18612.25550977961;19758.506037349074;20109.095090720915;20184.59630807383;20467.48831279215;17626.911865440656;17831.37590184344;17923.551911202227;19742.506437339038;19983.500412489695;5691.459311365424;5857.941420585804;5903.380145084769;5935.584509084344;5937.792177273796;4198.937015944748;4398.516163222043;5727.885442291152;5812.215350927634;5818.941810581882;6269.467095296905;6348.460380867632;6351.047342898579;6366.74533018681;6372.872542549169;6189.473894718932;6359.65021923794;6371.872562548746;6555.80332590023;6557.377049180321;5853.209816674779;5854.385289544579;5861;5862.70686465675;5862.97068514659;23135.76864231353;23158.76841231583;23161.88419057911;23241.883790580938;23507.76492235073;2348.8003519700846;2442.2673198040516;2458.0706819115894;2493.738157493455;2522.8738563071956;14246.643833904132;14897.255137243095;15175.544733658007;15245.313960871676;15683.843161568466;1746.1007581095746;1796.4880009197316;1826.3253024013436;1869.813018698133;1959.4611481842464;53743;76757.61621191914;83406;85479.57260213721;85642.57178714129;11439.256448330832;11652.88347116518;11885.702857428461;11913;12020.879791202151;16817.15914204285;16856.662866742598;16860.325586976385;16863.662726745395;16863.915680421764;8531.10423405546;8809.647614095364;8969.775755606162;8987.146221108984;9068.138526839939;8486.236238738544;8549.615267312985;8559;8573.281452658859;8798.692045778367;5005.174146265826;5043.722595257223;5051.696898186121;5081.618878584121;5101;2046.1047617621198;2056.7223424837753;2071.8342532597358;2086.1863873089205;2183.0721943174403;888.8666699995052;929.6885543343055;975.6438899801522;1016.9491525423699;1025.497506221946;17807.287708491516;19258.22967081329;19340.903295483433;19677.803221967883;19698.803011969983;1023.7235946294459;1062.5643486170634;1066.861308029951;1103.089950790601;1140.5437824869953;10649.520771565298;11022.724431889106;11070.501827417786;11130.220884538005;11144.05275551571;164130.1793491025;167065;505045;510328;511398;75246;83928;102091;103271;107145.46427267813;1031.8658574385431;1048.402410625936;1084.9186311026704;1091.0943916549245;1096.3367162866512;16627.916860416106;16924.830751692818;16976.830231697524;17712.114394280492;17732.55668608295;42681.57318426776;42927;43055;43120;43838.56161438472;56438.71780641235;62300;63004.369956301685;63288.68355658376;64631.67684161549;8790.120987901159;8803.955980220058;8816.823663526822;8852;8865.246454051477;11490.368029758443;13224.272665003517;13438.260895650834;13498.865011350154;13612.795808063085;11433.142514311294;11617.941910290394;11692.94153529227;11952.701182470508;12092.75814483723;11020.559177632806;11234.494447749707;11638.88361116412;11654.30074195551;11694.649160525198;11561.190716649753;11721.472533735832;11806.35065071395;11883.76232475363;12000.1599888007;8604.440711353742;8632.568371581521;8672.132786721277;8750.737477875675;8949.463032218087;10736.838947415952;11361.147913906325;11635.41822908868;11759.118066145244;11819.645410637695 15:48:57 INFO - PROCESS | 29896 | |3;mootools.html;1388.1324172392256;1461.5688371930282;1525.3288553036664;1666.3251383189804;1828.8171182881706;7885.645145968431;8573.314134869212;8878.644854205824;8901.243394311492;8909.599068041945;8680.91319086809;8818.91181088189;8827.602757875895;9038.72883813485;9045.457272563634;2469.0494159748537;2499.9250022499264;2508.385445565839;2509.2472258322523;2515;2430.307362401716;2551.285640020797;2553.7573930476574;2560.641510188575;2562.5387430262545;2458;2460.1266550374626;2461.9261422157365;2463.6550882876413;2485.229578830559;2386.916457923973;2408.1932552594903;2418.5888398972215;2424.5878200705924;2431.841930274535;2463.3718401807605;2469.3209367423924;2486.9751302486925;2506.786923111538;2508.1973768394123;8821.51481668508;8840.778980525474;8927.330450216195;8934.463932164092;8968.596413161424;5489.1217405214975;5724.427557244284;5725.341585717654;5728.828135155952;5733.942660573382;3422.1273575238406;3537.5224344713392;3630.5824830144606;3694.3534881395917;3698.6116457772087;1171.9238249513755;1175.1010476985114;1233.187214794251;1271.3007845684836;1283.121062072474;12612.432440540199;12880.42038108278;12946.48214071436;12960.611181664564;12967.416466259041;4832.299316599144;4989.650724449326;5045.974770126126;5095.159298715745;5120.359955005624;10777.946110269399;10786.784264314827;10799.40603266828;10811.513481893333;10818.891811081945;5669.744861481243;5673.290838645169;5682.147677848273;5687.943120568824;5706.28671416073;4280.5291417944;4610.7694615269775;4741.454732705714;5162.793488260502;5200.271961925334;4229.175310814362;4263.253930562139;4308.80610372534;4357.237483440385;4380.846670366522;1278.3735969374939;1288.3171918883006;1360.7822748360215;1361.7140400515805;1394.3655636685285;878.5738916625431;939.5255396024963;947.2374738335568;984.6209209454269;995.9601615935281;5721.5136713379;5814.3604203538425;5824.621399609012;6028.216331876915;6067.180930574405;1004.1765752083221;1070.4915165296577;1084.7071290751453;1092.579356947565;1118.1613789657756;3914.745541539791;4074.9388759168637;4092.7544347338658;4114.074333275049;4192.035831758712;10948.945255273673;11223.438828058564;11362.715932101599;11443;11459.656210313704;11033.779324413466;11117.666470005914;11257.549698012152;11263.211575189822;11308.773824523463;8232.17678232172;8505.957470212734;8527.232549070613;8595.398322117517;8640.481571105754;5672.319321681424;5756.453136951983;5851;5879.735411906389;5925.762969481345;10172.949135254275;10184.490775461049;10330.070293673605;10358.430286334324;10361.481925903523;9256.72229833106;9316.534173291444;9413.246940244628;9672.70981870545;10284.742881428025;7870.567118808521;8002.399820013406;8206.425550211428;8248.958755206186;8250.628721707417;8504.489730616182;8519.616617252115;8588.441751286146;8592.226699597066;8608.526531040852;7846.646900889587;7881.724139655084;7949.801254968441;8011.038675358995;8152.796180095545;7645.159032506309;7807.609619519115;7831.804204894923;7851.371890248653;7872.84254314922;7093.113360829896;7159.606221657858;7168.17565979919;7190.209077001421;7231.385332246818;4972.453030166751;5184.922226166686;5197.922031169611;5214.478552144755;5404.270423492935;5082.186850103965;5092.821751238837;5112.182050871843;5117.488251175002;5316.175992721134 15:48:57 INFO - PROCESS | 29896 | |4;prototype.html;342.477721474751;350.9961510081168;353.83900325351965;358.9013021419108;360.1374707575357;5256.342957130359;5286.180642000496;5295.258663787068;5295.655782374148;5313.937212557485;5037.29477873098;5088.160453525164;5119.928043174094;5172.431032586413;5176.482351764822;4182.916341673164;4185.769782661952;4186.2883309837325;4188.81150348235;4216.683748718843;4202.138561594881;4212.726172798758;4213.641840443566;4220.620144187023;4221.770345685553;4121.712449590476;4159.83360665573;4186.246475634384;4192.182524407742;4212.515560710512;4150.27370210212;4160.2303573838935;4161.667066634662;4171.33258678613;4180.28935081037;3718.2563487302646;3727.6458736419995;3727.7763334200035;3732.64539868712;3752.5309336332957;5006.374203224597;5119.48805119485;5143.871403214949;5152.716600586966;5226.895462090737;4751.429828420577;4757.904841903177;4765.594924431428;4770.332153498513;4823.27650852375;5442.564594832404;5446.727663616843;5473.31583552056;5474.671519708831;5492.752826122834;3995.5005624296964;4087.652549533293;4100.097978444737;4112.136451345221;4136.234796562646;5665.943340566583;5668.206451096849;5669.270872383921;5674.354437213203;5689.7439615217;4190.601892820177;4206.179794940019;4212.389203565466;4223.345381465878;4228.936565951513;4137.689673274516;4290.50659174199;4295.78521073945;4296.570342965679;4303;4686.8125274988615;4741.976290118596;4797.856064318076;4811.109944660179;4823.517648235148;5461.644993075437;5503.257060296889;5536.75084621193;5554.694491802989;5555.30558680165;1331.6471135149;1432.4771458417601;1442.473497173524;1444.1335198880797;1445.756649281617;966.2704657983178;1005.2812239248972;1018.7367664096615;1021.5709402051157;1027.7225149209671;5172.379314482287;5191.143461328914;5191.195364718475;5216.478352164753;5223.268742376071;5311.389749324566;5351.86620334487;5381.892362152735;5487.945120548823;5495.642783219078;4946.579540739078;5140.46025167352;5184.585233181412;5188;5195.68825870449;2860.585215143834;2868.612737280482;2872.770178385724;2881.841498717549;2902.8548572571285;5311.7344132793205;5599.580031497654;5609.214709940612;5609.411011843697;5652.378238393773;5450.945490545044;5466.5900057495055;5480.314960629921;5495.670259784426;5499.560035197174;5574.303212098488;5606.663600183921;5609.354924183689;5618.550515958714;5632.549840819257;5181.81863634771;5354.892902142014;5368.677879327252;5398.352197736297;5412.269343638558;5339.733013349394;5355.866103347447;5358.571314294925;5359.544438722753;5372.892542149213;5387.595930305321;5595.944040559542;5626.2967129108865;5644.576656750678;5646.322441306905;4055.9594404055583;4075.3071977763257;4080.8775736727944;4089.91820163601;4095.5836056726675;5437.592180586551;5450.91823622638;5485.506304432461;5509.641873278224;5534.418886017072;5041.697498150123;5296.6292359536;5322.893542129058;5369.140937449989;5403.621746477709;5216.243644671577;5233.6074794389815;5251.081060814266;5271.736413179249;5309.407177846708;5229.450907654642;5231.11679795281;5238.302274328647;5369;5404.83785486436;5574.9442505574425;5588.469095435927;5595.216669666169;5596.160575913483;5606.486248712907;5391.353037635509;5434.510894019557;5450.672959622435;5452.945470545403;5485.424030476743 15:48:57 INFO - PROCESS | 29896 | |5;yui.html;781.8983532140821;828.0394742099159;834.324197400106;842.974710758677;901.6753968571313;13290.202587844724;13451.260180690058;13458.865411345883;13475.730485390286;13562.525311614096;14616.415343386252;14742.778858317133;14759.926200368984;14763;14786.926065369658;729.3108012927786;741.5550669598252;853.9316854651644;861.336770686571;879.2702057292432;881.2597418168737;882.1134759566627;884.3765145572356;885.5085427587686;887.951162686052;931.4085555672164;937.8218138553653;938.1650331205233;938.5166639180827;939.3565407695717;940.0928104379273;940.219617717293;941.8540126280437;942.0532364973227;942.6323733743811;686.5743239191734;686.6395142550151;687.5359132585535;689.8930665746817;691.7544271783495;15382;15393.692126157526;15402.46091386796;15427.30577124021;15466.690666186612;11540.076793856555;11867.991220746248;14622.341994610268;14761.55715328542;15567.532974010797;11903.642890713292;12051.758964820654;12125.151239413244;12270.38648067602;12322.075844311712;3899.1032062625754;3914.412838074283;3926.2343842950636;3989;4068.9389659155136;61830.690846545476;61852.38147618556;63026;64646;64869;11309.660710178709;11411.828822567668;11446.198766086294;11453.656390308304;11516.30902145874;19404.32084877051;20080.39758807238;20288.695669564968;20421;21059;7419.2209817969415;7537.54774713508;7681.500702454323;8011.439199256112;8577.828443431097;4401.163778882066;4402.262715357801;4579.885502862455;4602.079584083197;4708.929366059512;3290.440625093692;3389.6949274564913;3391.423458012144;3406.67636574525;3409.6931276185264;630.3570358234575;640.1741753138492;641.8555824939351;646.5118835279334;648.2318452633644;595.1578516399246;603.6800495737266;636.1570918532963;642.3063091860779;642.4731719989657;8623.525706086226;8815.074417186102;9010.954945225232;9053.094690530894;9077.863832042525;532.6005495878092;542;560.9186667933127;579.9333076696149;591.6124938165508;3993.7803420812133;4192.0777428965575;4212.262854000538;4258.339957306623;4415.227335216325;66779;70033;72759.63620181971;72855.27144728485;85060.57469712736;70223;73227.63386183036;74642.25357746353;75390;77248;66919;68856.6557167221;69140;70056.6497167511;70531.2946870535;8657.91342086571;9108.180263776292;9371.109744574254;9409.811803763749;9542.284328675238;46140;46658.76670616625;46989;47011;47777;58934;62899.37100628935;63399;64985;65180;12493.18794278369;12666.240025598125;12770.23378597287;12861.228326300452;12959;13158.40787164599;13569.457221711022;13855.930720346334;13902.374393152535;14510.347034383689;12958;13004.284764338052;13355.53255636048;13478.460861565427;13958.511452099128;13134.60596182116;13416.195028298333;13568.389422475815;13696.589102326947;14150.716985660434;12405.441755121221;12827.230366178059;12999.1550549214;13116.409761560944;13403.664908377368;10164.390136591828;10535.736606584896;10635.680929571816;10897.564097436014;11015.669529914116;9706.466144362128;10236.283460157718;10782.015981130235;10937.179711521827;11038.889611103787 15:48:57 INFO - PROCESS | 29896 | __end_tp_report 15:48:57 INFO - PROCESS | 29896 | __start_cc_report 15:48:57 INFO - PROCESS | 29896 | _x_x_mozilla_cycle_collect,1071 15:48:57 INFO - PROCESS | 29896 | __end_cc_report 15:48:57 INFO - PROCESS | 29896 | __startTimestamp1488412137732__endTimestamp 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | ------- Summary: start ------- 15:48:57 INFO - PROCESS | 29896 | Number of tests: 6 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | [#0] dojo.html Cycles:165 Average:5621.57 Median:5825.74 stddev:643.94 (11.1%) stddev-sans-first:617.07 15:48:57 INFO - PROCESS | 29896 | Values: 3192.4 3215.1 3218.9 3243.0 3265.8 5619.8 5725.5 5734.6 5739.9 5751.7 5658.3 5727.9 5735.3 5741.5 5744.5 5134.1 5169.0 5174.6 5210.2 5239.3 5052.1 5056.3 5227.8 5248.2 5278.0 5100.8 5114.2 5128.9 5130.1 5145.2 5142.5 5142.5 5189.4 5195.2 5215.9 4942.7 5047.5 5128.1 5169.9 5180.4 5478.6 5499.6 5560.1 5565.0 5583.2 6012.2 6021.8 6129.8 6139.0 6139.4 5906.4 6174.7 6175.7 6190.2 6192.1 5545.8 5571.8 5619.9 5634.3 5656.0 6196.4 6201.5 6201.5 6207.7 6238.0 5780.9 5987.3 5989.5 5995.4 6001.2 5903.9 5965.0 6025.7 6031.2 6128.2 6055.0 6069.8 6070.4 6071.9 6113.1 6018.6 6060.4 6071.2 6074.9 6082.1 4571.6 4599.8 4667.3 4683.8 4696.1 4150.6 4158.3 4159.6 4179.6 4183.1 5825.7 5834.3 5835.5 5835.8 5842.2 5809.1 5887.6 5936.4 5988.1 6138.6 5645.0 5658.7 5665.8 5673.5 5692.3 5173.9 5181.4 5182.3 5183.7 5213.6 6134.7 6138.0 6198.6 6273.1 6347.5 6098.5 6114.2 6115.0 6116.3 6118.6 6330.1 6336.6 6340.2 6341.7 6348.7 5856.8 5871.5 5900.2 6000.0 6005.8 5979.3 6077.0 6106.3 6130.3 6134.6 5830.5 5936.6 5944.1 5958.6 5958.9 5764.3 5819.9 5920.3 5934.9 5939.8 5764.3 5795.8 5796.1 5844.9 5881.5 5928.3 5933.0 5937.6 5939.2 5942.1 5671.6 5730.0 5752.8 5765.3 5787.8 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | [#1] ext.html Cycles:180 Average:62777.66 Median:22909.69 stddev:118784.32 (518.5%) stddev-sans-first:119025.75 15:48:57 INFO - PROCESS | 29896 | Values: 583.3 604.9 630.2 665.9 673.4 34003.7 44885.0 45785.0 46037.5 46110.3 46018.3 46143.8 46557.0 46740.3 46813.0 8905.4 9012.0 9040.0 9041.5 9049.0 8513.0 8747.2 10800.8 11044.6 11045.0 11158.8 11228.9 11233.0 11236.0 11239.0 10846.2 10897.0 10900.6 10910.2 10911.7 10803.5 10867.0 10871.4 10873.6 10882.1 44685.0 44999.1 45012.3 45102.0 45165.0 3732.6 3740.7 3834.9 3859.9 4100.2 32806.6 33395.8 33674.7 33762.7 33904.5 4523.1 4583.5 4593.9 4777.3 4791.3 360775.0 361310.0 363379.0 363812.0 364451.0 67043.0 67317.3 67355.7 67453.0 67601.3 70263.6 70317.6 70469.3 70471.3 70484.0 19163.9 19353.7 19423.9 19438.2 19449.7 12977.9 13222.0 14241.9 14341.1 14369.0 17945.0 18210.3 19298.4 20724.2 20852.4 6710.3 6815.1 6839.9 6898.0 7002.0 987.6 1049.2 1098.5 1114.1 1126.9 33186.7 33429.5 33543.3 33625.2 33739.8 1153.2 1154.0 1154.3 1163.7 1192.7 16831.7 17735.8 17854.1 17865.1 17917.9 510666.0 514706.0 516437.0 517710.0 520146.0 62197.4 62675.0 62771.1 62786.1 62793.0 1327.8 1363.3 1372.8 1377.5 1422.9 31701.2 35023.5 35084.8 35086.0 35124.5 56037.7 56569.0 56896.7 56983.4 57115.4 452417.0 456751.0 458266.0 459117.0 459140.0 37940.0 40076.4 40906.8 43831.6 47002.5 49487.5 49641.5 49642.3 49962.5 50193.5 41719.2 43360.0 44334.8 44414.8 44933.8 42732.0 43363.6 44125.6 44301.8 44843.0 42109.0 42850.8 43097.8 43173.8 43331.6 36190.1 37900.8 38116.2 38805.8 38839.8 27879.0 28323.9 29101.3 29122.1 29201.7 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | [#2] jquery.html Cycles:180 Average:26108.30 Median:2134.63 stddev:68222.61 (3196.0%) stddev-sans-first:68386.71 15:48:57 INFO - PROCESS | 29896 | Values: 413.6 422.9 432.6 434.2 438.2 18612.3 19758.5 20109.1 20184.6 20467.5 17626.9 17831.4 17923.6 19742.5 19983.5 5691.5 5857.9 5903.4 5935.6 5937.8 4198.9 4398.5 5727.9 5812.2 5818.9 6269.5 6348.5 6351.0 6366.7 6372.9 6189.5 6359.7 6371.9 6555.8 6557.4 5853.2 5854.4 5861.0 5862.7 5863.0 23135.8 23158.8 23161.9 23241.9 23507.8 2348.8 2442.3 2458.1 2493.7 2522.9 14246.6 14897.3 15175.5 15245.3 15683.8 1746.1 1796.5 1826.3 1869.8 1959.5 53743.0 76757.6 83406.0 85479.6 85642.6 11439.3 11652.9 11885.7 11913.0 12020.9 16817.2 16856.7 16860.3 16863.7 16863.9 8531.1 8809.6 8969.8 8987.1 9068.1 8486.2 8549.6 8559.0 8573.3 8798.7 5005.2 5043.7 5051.7 5081.6 5101.0 2046.1 2056.7 2071.8 2086.2 2183.1 888.9 929.7 975.6 1016.9 1025.5 17807.3 19258.2 19340.9 19677.8 19698.8 1023.7 1062.6 1066.9 1103.1 1140.5 10649.5 11022.7 11070.5 11130.2 11144.1 164130.2 167065.0 505045.0 510328.0 511398.0 75246.0 83928.0 102091.0 103271.0 107145.5 1031.9 1048.4 1084.9 1091.1 1096.3 16627.9 16924.8 16976.8 17712.1 17732.6 42681.6 42927.0 43055.0 43120.0 43838.6 56438.7 62300.0 63004.4 63288.7 64631.7 8790.1 8804.0 8816.8 8852.0 8865.2 11490.4 13224.3 13438.3 13498.9 13612.8 11433.1 11617.9 11692.9 11952.7 12092.8 11020.6 11234.5 11638.9 11654.3 11694.6 11561.2 11721.5 11806.4 11883.8 12000.2 8604.4 8632.6 8672.1 8750.7 8949.5 10736.8 11361.1 11635.4 11759.1 11819.6 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | [#3] mootools.html Cycles:180 Average:5953.43 Median:4981.05 stddev:3337.24 (67.0%) stddev-sans-first:3328.97 15:48:57 INFO - PROCESS | 29896 | Values: 1388.1 1461.6 1525.3 1666.3 1828.8 7885.6 8573.3 8878.6 8901.2 8909.6 8680.9 8818.9 8827.6 9038.7 9045.5 2469.0 2499.9 2508.4 2509.2 2515.0 2430.3 2551.3 2553.8 2560.6 2562.5 2458.0 2460.1 2461.9 2463.7 2485.2 2386.9 2408.2 2418.6 2424.6 2431.8 2463.4 2469.3 2487.0 2506.8 2508.2 8821.5 8840.8 8927.3 8934.5 8968.6 5489.1 5724.4 5725.3 5728.8 5733.9 3422.1 3537.5 3630.6 3694.4 3698.6 1171.9 1175.1 1233.2 1271.3 1283.1 12612.4 12880.4 12946.5 12960.6 12967.4 4832.3 4989.7 5046.0 5095.2 5120.4 10777.9 10786.8 10799.4 10811.5 10818.9 5669.7 5673.3 5682.1 5687.9 5706.3 4280.5 4610.8 4741.5 5162.8 5200.3 4229.2 4263.3 4308.8 4357.2 4380.8 1278.4 1288.3 1360.8 1361.7 1394.4 878.6 939.5 947.2 984.6 996.0 5721.5 5814.4 5824.6 6028.2 6067.2 1004.2 1070.5 1084.7 1092.6 1118.2 3914.7 4074.9 4092.8 4114.1 4192.0 10948.9 11223.4 11362.7 11443.0 11459.7 11033.8 11117.7 11257.5 11263.2 11308.8 8232.2 8506.0 8527.2 8595.4 8640.5 5672.3 5756.5 5851.0 5879.7 5925.8 10172.9 10184.5 10330.1 10358.4 10361.5 9256.7 9316.5 9413.2 9672.7 10284.7 7870.6 8002.4 8206.4 8249.0 8250.6 8504.5 8519.6 8588.4 8592.2 8608.5 7846.6 7881.7 7949.8 8011.0 8152.8 7645.2 7807.6 7831.8 7851.4 7872.8 7093.1 7159.6 7168.2 7190.2 7231.4 4972.5 5184.9 5197.9 5214.5 5404.3 5082.2 5092.8 5112.2 5117.5 5316.2 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | [#4] prototype.html Cycles:180 Average:4598.34 Median:5191.17 stddev:1296.05 (25.0%) stddev-sans-first:1259.71 15:48:57 INFO - PROCESS | 29896 | Values: 342.5 351.0 353.8 358.9 360.1 5256.3 5286.2 5295.3 5295.7 5313.9 5037.3 5088.2 5119.9 5172.4 5176.5 4182.9 4185.8 4186.3 4188.8 4216.7 4202.1 4212.7 4213.6 4220.6 4221.8 4121.7 4159.8 4186.2 4192.2 4212.5 4150.3 4160.2 4161.7 4171.3 4180.3 3718.3 3727.6 3727.8 3732.6 3752.5 5006.4 5119.5 5143.9 5152.7 5226.9 4751.4 4757.9 4765.6 4770.3 4823.3 5442.6 5446.7 5473.3 5474.7 5492.8 3995.5 4087.7 4100.1 4112.1 4136.2 5665.9 5668.2 5669.3 5674.4 5689.7 4190.6 4206.2 4212.4 4223.3 4228.9 4137.7 4290.5 4295.8 4296.6 4303.0 4686.8 4742.0 4797.9 4811.1 4823.5 5461.6 5503.3 5536.8 5554.7 5555.3 1331.6 1432.5 1442.5 1444.1 1445.8 966.3 1005.3 1018.7 1021.6 1027.7 5172.4 5191.1 5191.2 5216.5 5223.3 5311.4 5351.9 5381.9 5487.9 5495.6 4946.6 5140.5 5184.6 5188.0 5195.7 2860.6 2868.6 2872.8 2881.8 2902.9 5311.7 5599.6 5609.2 5609.4 5652.4 5450.9 5466.6 5480.3 5495.7 5499.6 5574.3 5606.7 5609.4 5618.6 5632.5 5181.8 5354.9 5368.7 5398.4 5412.3 5339.7 5355.9 5358.6 5359.5 5372.9 5387.6 5595.9 5626.3 5644.6 5646.3 4056.0 4075.3 4080.9 4089.9 4095.6 5437.6 5450.9 5485.5 5509.6 5534.4 5041.7 5296.6 5322.9 5369.1 5403.6 5216.2 5233.6 5251.1 5271.7 5309.4 5229.5 5231.1 5238.3 5369.0 5404.8 5574.9 5588.5 5595.2 5596.2 5606.5 5391.4 5434.5 5450.7 5452.9 5485.4 15:48:57 INFO - PROCESS | 29896 | 15:48:57 INFO - PROCESS | 29896 | [#5] yui.html Cycles:180 Average:17436.03 Median:47000.00 stddev:22349.59 (47.6%) stddev-sans-first:22377.30 15:48:57 INFO - PROCESS | 29896 | Values: 781.9 828.0 834.3 843.0 901.7 13290.2 13451.3 13458.9 13475.7 13562.5 14616.4 14742.8 14759.9 14763.0 14786.9 729.3 741.6 853.9 861.3 879.3 881.3 882.1 884.4 885.5 888.0 931.4 937.8 938.2 938.5 939.4 940.1 940.2 941.9 942.1 942.6 686.6 686.6 687.5 689.9 691.8 15382.0 15393.7 15402.5 15427.3 15466.7 11540.1 11868.0 14622.3 14761.6 15567.5 11903.6 12051.8 12125.2 12270.4 12322.1 3899.1 3914.4 3926.2 3989.0 4068.9 61830.7 61852.4 63026.0 64646.0 64869.0 11309.7 11411.8 11446.2 11453.7 11516.3 19404.3 20080.4 20288.7 20421.0 21059.0 7419.2 7537.5 7681.5 8011.4 8577.8 4401.2 4402.3 4579.9 4602.1 4708.9 3290.4 3389.7 3391.4 3406.7 3409.7 630.4 640.2 641.9 646.5 648.2 595.2 603.7 636.2 642.3 642.5 8623.5 8815.1 9011.0 9053.1 9077.9 532.6 542.0 560.9 579.9 591.6 3993.8 4192.1 4212.3 4258.3 4415.2 66779.0 70033.0 72759.6 72855.3 85060.6 70223.0 73227.6 74642.3 75390.0 77248.0 66919.0 68856.7 69140.0 70056.6 70531.3 8657.9 9108.2 9371.1 9409.8 9542.3 46140.0 46658.8 46989.0 47011.0 47777.0 58934.0 62899.4 63399.0 64985.0 65180.0 12493.2 12666.2 12770.2 12861.2 12959.0 13158.4 13569.5 13855.9 13902.4 14510.3 12958.0 13004.3 13355.5 13478.5 13958.5 13134.6 13416.2 13568.4 13696.6 14150.7 12405.4 12827.2 12999.2 13116.4 13403.7 10164.4 10535.7 10635.7 10897.6 11015.7 9706.5 10236.3 10782.0 10937.2 11038.9 15:48:57 INFO - PROCESS | 29896 | -------- Summary: end -------- 15:48:57 INFO - PROCESS | 29896 | 15:48:58 INFO - TEST-INFO | 29896: exit 0 15:48:58 INFO - TEST-OK | dromaeo_css | took 1115239ms 15:48:58 INFO - TEST-START | kraken 15:48:58 INFO - operating with platform_type : linux_ 15:48:58 INFO - Initialising browser for kraken test... 15:48:58 INFO - TEST-INFO | started process 30199 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjoH3Mr/profile http://localhost:51337/getInfo.html) 15:49:02 INFO - PROCESS | 30199 | __metrics Screen width/height:1600/1200 15:49:02 INFO - PROCESS | 30199 | colorDepth:24 15:49:02 INFO - PROCESS | 30199 | Browser inner width/height: 1024/697 15:49:02 INFO - PROCESS | 30199 | __metrics 15:49:03 INFO - PROCESS | 30199 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:49:03 INFO - PROCESS | 30199 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:49:04 INFO - TEST-INFO | 30199: exit 0 15:49:04 INFO - Browser initialized. 15:49:04 INFO - Running cycle 1/1 for kraken test... 15:49:04 INFO - TEST-INFO | started process 30290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjoH3Mr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:49:04 CRITICAL - PROCESS | 30290 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 15:49:04 INFO - Terminating psutil.Process(pid=30290, name='firefox') 15:49:04 INFO - TEST-INFO | 30290: exit 127 15:49:04 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 15:49:04 ERROR - Traceback (most recent call last): 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 15:49:04 INFO - talos_results.add(mytest.runTest(browser_config, test)) 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 15:49:04 INFO - return self._runTest(browser_config, test_config, setup) 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 15:49:04 INFO - else None) 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 15:49:04 INFO - global_counters=self.global_counters 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 15:49:04 INFO - self.parse() 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 15:49:04 INFO - % self.report_tokens) 15:49:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 15:49:04 INFO - raise utils.TalosError(message) 15:49:04 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 15:49:04 INFO - TEST-INFO took 5771ms 15:49:04 INFO - SUITE-END | took 1121s 15:49:04 ERROR - Return code: 2 15:49:04 WARNING - setting return code to 2 15:49:04 CRITICAL - # TBPL RETRY # 15:49:04 WARNING - setting return code to 4 15:49:04 INFO - Running post-action listener: _resource_record_post_action 15:49:04 INFO - [mozharness: 2017-03-01 23:49:04.422686Z] Finished run-tests step (success) 15:49:04 INFO - Running post-run listener: _resource_record_post_run 15:49:04 INFO - Total resource usage - Wall time: 1135s; CPU: 13.0%; Read bytes: 12288; Write bytes: 269553664; Read time: 208; Write time: 259448 15:49:04 INFO - TinderboxPrint: CPU usage
13.1% 15:49:04 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 208 15:49:04 INFO - TinderboxPrint: I/O write bytes / time
269,553,664 / 259,448 15:49:04 INFO - TinderboxPrint: CPU idle
7,869.6 (86.8%) 15:49:04 INFO - TinderboxPrint: CPU user
1,155.3 (12.7%) 15:49:04 INFO - TinderboxPrint: Swap in / out
0 / 0 15:49:04 INFO - install - Wall time: 14s; CPU: 14.0%; Read bytes: 0; Write bytes: 100962304; Read time: 0; Write time: 156312 15:49:04 INFO - run-tests - Wall time: 1121s; CPU: 13.0%; Read bytes: 12288; Write bytes: 168591360; Read time: 208; Write time: 103136 15:49:05 INFO - Running post-run listener: _upload_blobber_files 15:49:05 INFO - Blob upload gear active. 15:49:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:49:05 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 15:49:05 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'] 15:49:05 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 15:49:05 INFO - (blobuploader) - INFO - Open directory for files ... 15:49:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:49:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:49:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:06 INFO - (blobuploader) - INFO - Done attempting. 15:49:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:49:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:49:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:10 INFO - (blobuploader) - INFO - Done attempting. 15:49:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:49:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:49:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:49:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:49:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:49:10 INFO - (blobuploader) - INFO - Done attempting. 15:49:10 INFO - (blobuploader) - INFO - Iteration through files over. 15:49:10 INFO - Return code: 0 15:49:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:49:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:49:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8ee05ed750f606201b83ab112d9c488bc31328725448399780f976f5327209a1ed51d3d3848cc28382a0008b9306d63f5f79a6e10adb3ccc1be573b666c9570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e8565b0c784e0b5e5112505f0464bc60bb52859986949340b56c1f7bc6a53e0cfd116de8a54d7a200bc8908b4be4ded975fdff49bf7873fb25e27aacd3b7f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ecc4df77d0873aaac653bcdf6b71ecbcc7e2a2ee060c6b8836250f3fb05e984e2e69b5df078613b2720307f3ea38e9ddfd754aa63bd19f3c3454c7e3ace1019"} 15:49:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:49:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:49:10 INFO - Contents: 15:49:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8ee05ed750f606201b83ab112d9c488bc31328725448399780f976f5327209a1ed51d3d3848cc28382a0008b9306d63f5f79a6e10adb3ccc1be573b666c9570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e8565b0c784e0b5e5112505f0464bc60bb52859986949340b56c1f7bc6a53e0cfd116de8a54d7a200bc8908b4be4ded975fdff49bf7873fb25e27aacd3b7f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ecc4df77d0873aaac653bcdf6b71ecbcc7e2a2ee060c6b8836250f3fb05e984e2e69b5df078613b2720307f3ea38e9ddfd754aa63bd19f3c3454c7e3ace1019"} 15:49:10 INFO - Running post-run listener: copy_logs_to_upload_dir 15:49:10 INFO - Copying logs to upload dir... 15:49:10 INFO - mkdir: /builds/slave/test/build/upload/logs 15:49:10 INFO - Copying logs to upload dir... 15:49:10 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1182.026147 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 42 secs) (at 2017-03-01 15:49:10.712746) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 15:49:10.716262) ========= 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=826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8ee05ed750f606201b83ab112d9c488bc31328725448399780f976f5327209a1ed51d3d3848cc28382a0008b9306d63f5f79a6e10adb3ccc1be573b666c9570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e8565b0c784e0b5e5112505f0464bc60bb52859986949340b56c1f7bc6a53e0cfd116de8a54d7a200bc8908b4be4ded975fdff49bf7873fb25e27aacd3b7f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ecc4df77d0873aaac653bcdf6b71ecbcc7e2a2ee060c6b8836250f3fb05e984e2e69b5df078613b2720307f3ea38e9ddfd754aa63bd19f3c3454c7e3ace1019"} build_url:https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011229 build_url: 'https://queue.taskcluster.net/v1/task/fUrovAjkRRC2WGtUulPscQ/artifacts/public/build/firefox-52.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e8ee05ed750f606201b83ab112d9c488bc31328725448399780f976f5327209a1ed51d3d3848cc28382a0008b9306d63f5f79a6e10adb3ccc1be573b666c9570", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e8565b0c784e0b5e5112505f0464bc60bb52859986949340b56c1f7bc6a53e0cfd116de8a54d7a200bc8908b4be4ded975fdff49bf7873fb25e27aacd3b7f80", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ecc4df77d0873aaac653bcdf6b71ecbcc7e2a2ee060c6b8836250f3fb05e984e2e69b5df078613b2720307f3ea38e9ddfd754aa63bd19f3c3454c7e3ace1019"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-01 15:49:10.744000) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:49:10.744483) ========= 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=826874ccc97bd7bf541b4875000001d5-1488405338.536013-1292745095 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-01 15:49:10.827375) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 15:49:10.827871) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-01 15:49:10.828305) ========= ========= Total master_lag: 0.17 =========