builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1476360691.1 results: success (0) buildid: 20161013033014 builduid: 84e7533c17e24b868ced02c004860842 revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.100089) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.100584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.100884) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.116472) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.116881) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-13 05:11:31-- 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% 66.3M=0s 2016-10-13 05:11:31 (66.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.140826 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.273197) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.273753) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014161 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.325945) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.326501) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f03e2740d604d339ed553dad62a3fc54c317f8fa --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=56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-13 05:11:31,589 truncating revision to first 12 chars 2016-10-13 05:11:31,589 Setting DEBUG logging. 2016-10-13 05:11:31,589 attempt 1/10 2016-10-13 05:11:31,589 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f03e2740d604?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 05:11:31,896 unpacking tar archive at: fx-team-f03e2740d604/testing/mozharness/ program finished with exit code 0 elapsedTime=0.641056 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.983821) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.984485) ========= script_repo_revision: f03e2740d604d339ed553dad62a3fc54c317f8fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.985207) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.985707) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 05:11:31.997539) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-13 05:11:31.998190) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:11:32 INFO - MultiFileLogger online at 20161013 05:11:32 in /builds/slave/test 05:11:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:11:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:11:32 INFO - {'append_to_log': False, 05:11:32 INFO - 'base_work_dir': '/builds/slave/test', 05:11:32 INFO - 'blob_upload_branch': 'Fx-Team', 05:11:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:11:32 INFO - 'branch': 'Fx-Team', 05:11:32 INFO - 'buildbot_json_path': 'buildprops.json', 05:11:32 INFO - 'config_files': ('talos/linux_config.py',), 05:11:32 INFO - 'default_actions': ('clobber', 05:11:32 INFO - 'read-buildbot-config', 05:11:32 INFO - 'download-and-extract', 05:11:32 INFO - 'populate-webroot', 05:11:32 INFO - 'create-virtualenv', 05:11:32 INFO - 'install', 05:11:32 INFO - 'run-tests'), 05:11:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:11:32 INFO - 'download_minidump_stackwalk': True, 05:11:32 INFO - 'download_symbols': 'ondemand', 05:11:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:11:32 INFO - 'tooltool.py': '/tools/tooltool.py', 05:11:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:11:32 INFO - '/tools/misc-python/virtualenv.py')}, 05:11:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:11:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:11:32 INFO - 'installer_path': 'installer.exe', 05:11:32 INFO - 'log_level': 'info', 05:11:32 INFO - 'log_name': 'talos', 05:11:32 INFO - 'log_to_console': True, 05:11:32 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:11:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:11:32 INFO - 'opt_config_files': (), 05:11:32 INFO - 'pip_index': False, 05:11:32 INFO - 'sps_profile': False, 05:11:32 INFO - 'sps_profile_interval': 0, 05:11:32 INFO - 'suite': 'dromaeojs', 05:11:32 INFO - 'system_bits': '32', 05:11:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:11:32 INFO - 'title': 'talos-linux64-ix-037', 05:11:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:11:32 INFO - 'use_talos_json': True, 05:11:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:11:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:11:32 INFO - 'work_dir': 'build'} 05:11:32 INFO - [mozharness: 2016-10-13 12:11:32.362520Z] Running clobber step. 05:11:32 INFO - Running pre-action listener: _resource_record_pre_action 05:11:32 INFO - Running main action method: clobber 05:11:32 INFO - rmtree: /builds/slave/test/build 05:11:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:11:32 INFO - Running post-action listener: _resource_record_post_action 05:11:32 INFO - [mozharness: 2016-10-13 12:11:32.820311Z] Finished clobber step (success) 05:11:32 INFO - [mozharness: 2016-10-13 12:11:32.820413Z] Running read-buildbot-config step. 05:11:32 INFO - Running pre-action listener: _resource_record_pre_action 05:11:32 INFO - Running main action method: read_buildbot_config 05:11:32 INFO - Using buildbot properties: 05:11:32 INFO - { 05:11:32 INFO - "project": "", 05:11:32 INFO - "product": "firefox", 05:11:32 INFO - "script_repo_revision": "production", 05:11:32 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:11:32 INFO - "repository": "", 05:11:32 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:11:32 INFO - "buildid": "20161013033014", 05:11:32 INFO - "pgo_build": "True", 05:11:32 INFO - "basedir": "/builds/slave/test", 05:11:32 INFO - "buildnumber": 1123, 05:11:32 INFO - "slavename": "talos-linux64-ix-037", 05:11:32 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:11:32 INFO - "platform": "ubuntu64_hw", 05:11:32 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:11:32 INFO - "branch": "fx-team", 05:11:32 INFO - "repo_path": "integration/fx-team", 05:11:32 INFO - "revision": "f03e2740d604d339ed553dad62a3fc54c317f8fa", 05:11:32 INFO - "stage_platform": "linux64-pgo", 05:11:32 INFO - "builduid": "84e7533c17e24b868ced02c004860842", 05:11:32 INFO - "slavebuilddir": "test" 05:11:32 INFO - } 05:11:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 05:11:32 INFO - Running post-action listener: _resource_record_post_action 05:11:32 INFO - [mozharness: 2016-10-13 12:11:32.822844Z] Finished read-buildbot-config step (success) 05:11:32 INFO - [mozharness: 2016-10-13 12:11:32.822932Z] Running download-and-extract step. 05:11:32 INFO - Running pre-action listener: _resource_record_pre_action 05:11:32 INFO - Running main action method: download_and_extract 05:11:32 INFO - mkdir: /builds/slave/test/build/tests 05:11:32 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')]} 05:11:32 INFO - trying https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 05:11:32 INFO - Downloading https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 05:11:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:11:33 INFO - Downloaded 1361 bytes. 05:11:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 05:11:33 INFO - Using the following test package requirements: 05:11:33 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:33 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:11:33 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:11:33 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'jsshell-linux-x86_64.zip'], 05:11:33 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:11:33 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 05:11:33 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:11:33 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:11:33 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:11:33 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 05:11:33 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:11:33 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:11:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 05:11:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:11:33 INFO - Fetch https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:11:33 INFO - Http code: 200 05:11:33 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 05:11:33 INFO - x-amz-cf-id: 2nHLQxW74BlYEm-Gnv_lsHXL_licNkgTJyL_rXNvhLS0lc14hq-FsA== 05:11:33 INFO - x-amz-version-id: bwD85P3gUfbIxtvQnbyXcFpAZ_VuN7aG 05:11:34 INFO - Expected file size: 22126924 05:11:34 INFO - Obtained file size: 22126924 05:11:38 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:11:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 05:11:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:11:38 INFO - Fetch https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 05:11:38 INFO - Http code: 200 05:11:38 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 05:11:38 INFO - x-amz-cf-id: Ny3juQFQ4QwgVnltceKpGHNY7aVAYGx085h8eCQZ6hVrb17NcLVBgA== 05:11:38 INFO - x-amz-version-id: bwD85P3gUfbIxtvQnbyXcFpAZ_VuN7aG 05:11:39 INFO - Expected file size: 22126924 05:11:39 INFO - Obtained file size: 22126924 05:11:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 05:11:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:11:43 INFO - Fetch https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 05:11:44 INFO - Http code: 200 05:11:44 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 05:11:44 INFO - x-amz-cf-id: HrqrDodO8T9OGnmOsiqWCZ0PYthxpqa-ZIJEvpBN4qBu2oAJV0R5jA== 05:11:44 INFO - x-amz-version-id: 6py5cBEEIPoNvPLbhIZTrNm8Qsu9mAIb 05:11:44 INFO - Expected file size: 14409006 05:11:44 INFO - Obtained file size: 14409006 05:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:44 INFO - trying https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:11:44 INFO - Downloading https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:11:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:11:45 INFO - Downloaded 59770454 bytes. 05:11:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:11:45 INFO - mkdir: /builds/slave/test/properties 05:11:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:11:45 INFO - Writing to file /builds/slave/test/properties/build_url 05:11:45 INFO - Contents: 05:11:45 INFO - build_url:https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 05:11:46 INFO - Running post-action listener: _resource_record_post_action 05:11:46 INFO - Running post-action listener: set_extra_try_arguments 05:11:46 INFO - [mozharness: 2016-10-13 12:11:46.502141Z] Finished download-and-extract step (success) 05:11:46 INFO - [mozharness: 2016-10-13 12:11:46.502410Z] Running populate-webroot step. 05:11:46 INFO - Running pre-action listener: _resource_record_pre_action 05:11:46 INFO - Running main action method: populate_webroot 05:11:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:11:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:11:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:11:46 INFO - '--tppagecycles', 05:11:46 INFO - '7'], 05:11:46 INFO - 'tests': ['tsvgm']}}, 05:11:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:11:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:11:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:11:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:11:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['damp', 'tps']}, 05:11:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'tests': ['damp', 'tps']}, 05:11:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['dromaeo_dom']}, 05:11:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:11:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['basic_compositor_video']}, 05:11:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:11:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['a11yr', 05:11:46 INFO - 'ts_paint', 05:11:46 INFO - 'tpaint', 05:11:46 INFO - 'sessionrestore', 05:11:46 INFO - 'sessionrestore_no_auto_restore', 05:11:46 INFO - 'tabpaint']}, 05:11:46 INFO - 'other-e10s': {'tests': ['a11yr', 05:11:46 INFO - 'ts_paint', 05:11:46 INFO - 'tpaint', 05:11:46 INFO - 'sessionrestore', 05:11:46 INFO - 'sessionrestore_no_auto_restore', 05:11:46 INFO - 'tabpaint']}, 05:11:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:11:46 INFO - 'ts_paint', 05:11:46 INFO - 'tpaint', 05:11:46 INFO - 'sessionrestore', 05:11:46 INFO - 'sessionrestore_no_auto_restore', 05:11:46 INFO - 'tabpaint']}, 05:11:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:11:46 INFO - 'ts_paint', 05:11:46 INFO - 'tpaint', 05:11:46 INFO - 'sessionrestore', 05:11:46 INFO - 'sessionrestore_no_auto_restore', 05:11:46 INFO - 'tabpaint']}, 05:11:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['a11yr', 05:11:46 INFO - 'ts_paint', 05:11:46 INFO - 'tpaint', 05:11:46 INFO - 'sessionrestore', 05:11:46 INFO - 'sessionrestore_no_auto_restore', 05:11:46 INFO - 'tabpaint']}, 05:11:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['a11yr', 05:11:46 INFO - 'ts_paint', 05:11:46 INFO - 'tpaint', 05:11:46 INFO - 'sessionrestore', 05:11:46 INFO - 'sessionrestore_no_auto_restore', 05:11:46 INFO - 'tabpaint']}, 05:11:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['tsvgx', 05:11:46 INFO - 'tsvgr_opacity', 05:11:46 INFO - 'tart', 05:11:46 INFO - 'tscrollx', 05:11:46 INFO - 'cart']}, 05:11:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:11:46 INFO - 'tsvgr_opacity', 05:11:46 INFO - 'tart', 05:11:46 INFO - 'tscrollx', 05:11:46 INFO - 'cart']}, 05:11:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'talos_options': ['--disable-e10s'], 05:11:46 INFO - 'tests': ['tp5o']}, 05:11:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'tests': ['tp5o']}, 05:11:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'talos_options': ['--disable-e10s', 05:11:46 INFO - '--xperf_path', 05:11:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:46 INFO - 'tests': ['tp5n']}, 05:11:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:46 INFO - 'talos_options': ['--xperf_path', 05:11:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:46 INFO - 'tests': ['tp5n']}}, 05:11:46 INFO - 'talos.zip': {'path': '', 05:11:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:11:46 INFO - Running post-action listener: _resource_record_post_action 05:11:46 INFO - [mozharness: 2016-10-13 12:11:46.518214Z] Finished populate-webroot step (success) 05:11:46 INFO - [mozharness: 2016-10-13 12:11:46.518319Z] Running create-virtualenv step. 05:11:46 INFO - Running pre-action listener: _resource_record_pre_action 05:11:46 INFO - Running main action method: create_virtualenv 05:11:46 INFO - Creating virtualenv /builds/slave/test/build/venv 05:11: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 05:11:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:11:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:11:46 INFO - Using real prefix '/usr' 05:11:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:11:47 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:49 INFO - Installing pip.................done. 05:11:49 INFO - Return code: 0 05:11:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:11:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:11:49 INFO - Reading from file tmpfile_stdout 05:11:49 INFO - Output received: 05:11: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) 05:11:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:11: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 05:11: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 05:11:49 INFO - Using env: {'DISPLAY': ':0', 05:11:49 INFO - 'HOME': '/home/cltbld', 05:11:49 INFO - 'LANG': 'en_US.UTF-8', 05:11:49 INFO - 'LANGUAGE': 'en_US:en', 05:11:49 INFO - 'LOGNAME': 'cltbld', 05:11:49 INFO - 'MAIL': '/var/mail/cltbld', 05:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:49 INFO - 'MOZ_NO_REMOTE': '1', 05:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:49 INFO - 'NO_EM_RESTART': '1', 05:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:49 INFO - 'PWD': '/builds/slave/test', 05:11:49 INFO - 'SHELL': '/bin/bash', 05:11:49 INFO - 'SHLVL': '1', 05:11:49 INFO - 'TERM': 'linux', 05:11:49 INFO - 'TMOUT': '86400', 05:11:49 INFO - 'USER': 'cltbld', 05:11:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:49 INFO - '_': '/tools/buildbot/bin/python'} 05:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11: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 05:11:49 INFO - Cleaning up... 05:11:49 INFO - Return code: 0 05:11:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:11: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 05:11: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 05:11:49 INFO - Using env: {'DISPLAY': ':0', 05:11:49 INFO - 'HOME': '/home/cltbld', 05:11:49 INFO - 'LANG': 'en_US.UTF-8', 05:11:49 INFO - 'LANGUAGE': 'en_US:en', 05:11:49 INFO - 'LOGNAME': 'cltbld', 05:11:49 INFO - 'MAIL': '/var/mail/cltbld', 05:11:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:49 INFO - 'MOZ_NO_REMOTE': '1', 05:11:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:49 INFO - 'NO_EM_RESTART': '1', 05:11:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:49 INFO - 'PWD': '/builds/slave/test', 05:11:49 INFO - 'SHELL': '/bin/bash', 05:11:49 INFO - 'SHLVL': '1', 05:11:49 INFO - 'TERM': 'linux', 05:11:49 INFO - 'TMOUT': '86400', 05:11:49 INFO - 'USER': 'cltbld', 05:11:49 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:11:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:49 INFO - '_': '/tools/buildbot/bin/python'} 05:11:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:49 INFO - Downloading/unpacking psutil>=3.1.1 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:11:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:51 INFO - Installing collected packages: psutil 05:11:51 INFO - Running setup.py install for psutil 05:11:51 INFO - building 'psutil._psutil_linux' extension 05:11: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 05:11: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 05:11:52 INFO - building 'psutil._psutil_posix' extension 05:11: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 05:11: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 05:11:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:52 INFO - Successfully installed psutil 05:11:52 INFO - Cleaning up... 05:11:52 INFO - Return code: 0 05:11:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:11: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 05:11: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 05:11:52 INFO - Using env: {'DISPLAY': ':0', 05:11:52 INFO - 'HOME': '/home/cltbld', 05:11:52 INFO - 'LANG': 'en_US.UTF-8', 05:11:52 INFO - 'LANGUAGE': 'en_US:en', 05:11:52 INFO - 'LOGNAME': 'cltbld', 05:11:52 INFO - 'MAIL': '/var/mail/cltbld', 05:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:52 INFO - 'MOZ_NO_REMOTE': '1', 05:11:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:52 INFO - 'NO_EM_RESTART': '1', 05:11:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:52 INFO - 'PWD': '/builds/slave/test', 05:11:52 INFO - 'SHELL': '/bin/bash', 05:11:52 INFO - 'SHLVL': '1', 05:11:52 INFO - 'TERM': 'linux', 05:11:52 INFO - 'TMOUT': '86400', 05:11:52 INFO - 'USER': 'cltbld', 05:11:52 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:52 INFO - '_': '/tools/buildbot/bin/python'} 05:11:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:11:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:11:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:11:54 INFO - Installing collected packages: mozsystemmonitor 05:11:54 INFO - Running setup.py install for mozsystemmonitor 05:11:54 INFO - Successfully installed mozsystemmonitor 05:11:54 INFO - Cleaning up... 05:11:54 INFO - Return code: 0 05:11:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:11: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 05:11: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 05:11:54 INFO - Using env: {'DISPLAY': ':0', 05:11:54 INFO - 'HOME': '/home/cltbld', 05:11:54 INFO - 'LANG': 'en_US.UTF-8', 05:11:54 INFO - 'LANGUAGE': 'en_US:en', 05:11:54 INFO - 'LOGNAME': 'cltbld', 05:11:54 INFO - 'MAIL': '/var/mail/cltbld', 05:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:54 INFO - 'MOZ_NO_REMOTE': '1', 05:11:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:54 INFO - 'NO_EM_RESTART': '1', 05:11:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:54 INFO - 'PWD': '/builds/slave/test', 05:11:54 INFO - 'SHELL': '/bin/bash', 05:11:54 INFO - 'SHLVL': '1', 05:11:54 INFO - 'TERM': 'linux', 05:11:54 INFO - 'TMOUT': '86400', 05:11:54 INFO - 'USER': 'cltbld', 05:11:54 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:54 INFO - '_': '/tools/buildbot/bin/python'} 05:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:54 INFO - Downloading/unpacking jsonschema==2.5.1 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:57 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:57 INFO - warning: no files found matching '*.txt' 05:11:57 INFO - no previously-included directories found matching 'build' 05:11:57 INFO - no previously-included directories found matching 'dist' 05:11:57 INFO - no previously-included directories found matching '.git*' 05:11:57 INFO - Installing collected packages: jsonschema, functools32 05:11:57 INFO - Running setup.py install for functools32 05:11:57 INFO - warning: no files found matching '*.txt' 05:11:57 INFO - no previously-included directories found matching 'build' 05:11:57 INFO - no previously-included directories found matching 'dist' 05:11:57 INFO - no previously-included directories found matching '.git*' 05:11:57 INFO - Successfully installed jsonschema functools32 05:11:57 INFO - Cleaning up... 05:11:57 INFO - Return code: 0 05:11:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:11: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 05:11: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 05:11:57 INFO - Using env: {'DISPLAY': ':0', 05:11:57 INFO - 'HOME': '/home/cltbld', 05:11:57 INFO - 'LANG': 'en_US.UTF-8', 05:11:57 INFO - 'LANGUAGE': 'en_US:en', 05:11:57 INFO - 'LOGNAME': 'cltbld', 05:11:57 INFO - 'MAIL': '/var/mail/cltbld', 05:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:57 INFO - 'MOZ_NO_REMOTE': '1', 05:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:57 INFO - 'NO_EM_RESTART': '1', 05:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:57 INFO - 'PWD': '/builds/slave/test', 05:11:57 INFO - 'SHELL': '/bin/bash', 05:11:57 INFO - 'SHLVL': '1', 05:11:57 INFO - 'TERM': 'linux', 05:11:57 INFO - 'TMOUT': '86400', 05:11:57 INFO - 'USER': 'cltbld', 05:11:57 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:57 INFO - '_': '/tools/buildbot/bin/python'} 05:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:11:57 INFO - Cleaning up... 05:11:57 INFO - Return code: 0 05:11:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:11: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 05:11: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 05:11:57 INFO - Using env: {'DISPLAY': ':0', 05:11:57 INFO - 'HOME': '/home/cltbld', 05:11:57 INFO - 'LANG': 'en_US.UTF-8', 05:11:57 INFO - 'LANGUAGE': 'en_US:en', 05:11:57 INFO - 'LOGNAME': 'cltbld', 05:11:57 INFO - 'MAIL': '/var/mail/cltbld', 05:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:57 INFO - 'MOZ_NO_REMOTE': '1', 05:11:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:11:57 INFO - 'NO_EM_RESTART': '1', 05:11:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:57 INFO - 'PWD': '/builds/slave/test', 05:11:57 INFO - 'SHELL': '/bin/bash', 05:11:57 INFO - 'SHLVL': '1', 05:11:57 INFO - 'TERM': 'linux', 05:11:57 INFO - 'TMOUT': '86400', 05:11:57 INFO - 'USER': 'cltbld', 05:11:57 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:57 INFO - '_': '/tools/buildbot/bin/python'} 05:11:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:57 INFO - Downloading/unpacking blobuploader==1.2.4 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:11:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:11:59 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 05:11:59 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 05:11:59 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 05:11:59 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 05:11:59 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 05:11:59 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 05:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:12:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:12: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 05:12: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 05:12: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 05:12: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 05:12: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 05:12: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 05:12:00 INFO - Downloading docopt-0.6.1.tar.gz 05:12:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:12:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:12:00 INFO - Running setup.py install for blobuploader 05:12:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:12:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:12:00 INFO - Running setup.py install for requests 05:12:00 INFO - Running setup.py install for docopt 05:12:01 INFO - Successfully installed blobuploader requests docopt 05:12:01 INFO - Cleaning up... 05:12:01 INFO - Return code: 0 05:12:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:12: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 05:12: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 05:12:01 INFO - Using env: {'DISPLAY': ':0', 05:12:01 INFO - 'HOME': '/home/cltbld', 05:12:01 INFO - 'LANG': 'en_US.UTF-8', 05:12:01 INFO - 'LANGUAGE': 'en_US:en', 05:12:01 INFO - 'LOGNAME': 'cltbld', 05:12:01 INFO - 'MAIL': '/var/mail/cltbld', 05:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:01 INFO - 'MOZ_NO_REMOTE': '1', 05:12:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:01 INFO - 'NO_EM_RESTART': '1', 05:12:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:01 INFO - 'PWD': '/builds/slave/test', 05:12:01 INFO - 'SHELL': '/bin/bash', 05:12:01 INFO - 'SHLVL': '1', 05:12:01 INFO - 'TERM': 'linux', 05:12:01 INFO - 'TMOUT': '86400', 05:12:01 INFO - 'USER': 'cltbld', 05:12:01 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:01 INFO - '_': '/tools/buildbot/bin/python'} 05:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:01 INFO - Running setup.py (path:/tmp/pip-TSBlGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:01 INFO - Running setup.py (path:/tmp/pip-5ntm6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:01 INFO - Running setup.py (path:/tmp/pip-V_wcuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:01 INFO - Running setup.py (path:/tmp/pip-2GWF7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:01 INFO - Running setup.py (path:/tmp/pip-8rMI7G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:01 INFO - Running setup.py (path:/tmp/pip-nilLFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:01 INFO - Running setup.py (path:/tmp/pip-LAmeSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:01 INFO - Running setup.py (path:/tmp/pip-2gpnBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:01 INFO - Running setup.py (path:/tmp/pip-vPj5nZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:01 INFO - Running setup.py (path:/tmp/pip-wvmZZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:02 INFO - Running setup.py (path:/tmp/pip-2_ru4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:02 INFO - Running setup.py (path:/tmp/pip-ocOCVp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:02 INFO - Running setup.py (path:/tmp/pip-o7e1f1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:02 INFO - Running setup.py (path:/tmp/pip-LZLU7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:02 INFO - Running setup.py (path:/tmp/pip-8IKORO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:02 INFO - Running setup.py (path:/tmp/pip-sOJ75R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:02 INFO - Running setup.py (path:/tmp/pip-4NhoRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:12:02 INFO - Running setup.py install for manifestparser 05:12:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:12:02 INFO - Running setup.py install for mozcrash 05:12:02 INFO - Running setup.py install for mozdebug 05:12:02 INFO - Running setup.py install for mozdevice 05:12:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:12:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:12:02 INFO - Running setup.py install for mozfile 05:12:03 INFO - Running setup.py install for mozhttpd 05:12:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Running setup.py install for mozinfo 05:12:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Running setup.py install for mozInstall 05:12:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Running setup.py install for mozleak 05:12:03 INFO - Running setup.py install for mozlog 05:12:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Running setup.py install for moznetwork 05:12:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Running setup.py install for mozprocess 05:12:03 INFO - Running setup.py install for mozprofile 05:12:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:12:03 INFO - Running setup.py install for mozrunner 05:12:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:12:04 INFO - Running setup.py install for mozscreenshot 05:12:04 INFO - Running setup.py install for moztest 05:12:04 INFO - Running setup.py install for mozversion 05:12:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:12:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:12:04 INFO - Cleaning up... 05:12:04 INFO - Return code: 0 05:12:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:12: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 05:12: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 05:12:04 INFO - Using env: {'DISPLAY': ':0', 05:12:04 INFO - 'HOME': '/home/cltbld', 05:12:04 INFO - 'LANG': 'en_US.UTF-8', 05:12:04 INFO - 'LANGUAGE': 'en_US:en', 05:12:04 INFO - 'LOGNAME': 'cltbld', 05:12:04 INFO - 'MAIL': '/var/mail/cltbld', 05:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:04 INFO - 'MOZ_NO_REMOTE': '1', 05:12:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:04 INFO - 'NO_EM_RESTART': '1', 05:12:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:04 INFO - 'PWD': '/builds/slave/test', 05:12:04 INFO - 'SHELL': '/bin/bash', 05:12:04 INFO - 'SHLVL': '1', 05:12:04 INFO - 'TERM': 'linux', 05:12:04 INFO - 'TMOUT': '86400', 05:12:04 INFO - 'USER': 'cltbld', 05:12:04 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:04 INFO - '_': '/tools/buildbot/bin/python'} 05:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:12:04 INFO - Running setup.py (path:/tmp/pip-9Yin3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:12: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)) 05:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:12:04 INFO - Running setup.py (path:/tmp/pip-UH6PZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:12:04 INFO - Running setup.py (path:/tmp/pip-6U7Ftg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:12:04 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)) 05:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:12:04 INFO - Running setup.py (path:/tmp/pip-Jetzmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:12:04 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)) 05:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:12:04 INFO - Running setup.py (path:/tmp/pip-ThFPLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:12:04 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)) 05:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:12:04 INFO - Running setup.py (path:/tmp/pip-TzS_mJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:12:05 INFO - Running setup.py (path:/tmp/pip-V7Fj_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:12:05 INFO - Running setup.py (path:/tmp/pip-UK2bOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:12:05 INFO - Running setup.py (path:/tmp/pip-QUAo7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:12:05 INFO - Running setup.py (path:/tmp/pip-UXWLRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:12:05 INFO - Running setup.py (path:/tmp/pip-XsxLhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:12:05 INFO - Running setup.py (path:/tmp/pip-bHw8WN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:12:05 INFO - Running setup.py (path:/tmp/pip-pNt5i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:12:05 INFO - Running setup.py (path:/tmp/pip-M__qMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:12:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:12:05 INFO - Running setup.py (path:/tmp/pip-RitfoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:12: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)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:12:05 INFO - Running setup.py (path:/tmp/pip-pmmgmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:12:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:12:05 INFO - Running setup.py (path:/tmp/pip-W1XOw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:12:05 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)) 05:12:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:12:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:12:05 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)) 05:12:05 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)) 05:12:05 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)) 05:12:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:12:05 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 05:12:05 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 05:12:05 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 05:12:05 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 05:12:05 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 05:12:05 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 05:12:07 INFO - Downloading blessings-1.6.tar.gz 05:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:12:07 INFO - Installing collected packages: blessings 05:12:07 INFO - Running setup.py install for blessings 05:12:07 INFO - Successfully installed blessings 05:12:07 INFO - Cleaning up... 05:12:07 INFO - Return code: 0 05:12:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:12:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:08 INFO - Reading from file tmpfile_stdout 05:12:08 INFO - Current package versions: 05:12:08 INFO - argparse == 1.2.1 05:12:08 INFO - blessings == 1.6 05:12:08 INFO - blobuploader == 1.2.4 05:12:08 INFO - docopt == 0.6.1 05:12:08 INFO - functools32 == 3.2.3-2 05:12:08 INFO - jsonschema == 2.5.1 05:12:08 INFO - manifestparser == 1.1 05:12:08 INFO - mozInstall == 1.12 05:12:08 INFO - mozcrash == 0.17 05:12:08 INFO - mozdebug == 0.1 05:12:08 INFO - mozdevice == 0.48 05:12:08 INFO - mozfile == 1.2 05:12:08 INFO - mozhttpd == 0.7 05:12:08 INFO - mozinfo == 0.9 05:12:08 INFO - mozleak == 0.1 05:12:08 INFO - mozlog == 3.4 05:12:08 INFO - moznetwork == 0.27 05:12:08 INFO - mozprocess == 0.23 05:12:08 INFO - mozprofile == 0.28 05:12:08 INFO - mozrunner == 6.12 05:12:08 INFO - mozscreenshot == 0.1 05:12:08 INFO - mozsystemmonitor == 0.3 05:12:08 INFO - moztest == 0.7 05:12:08 INFO - mozversion == 1.4 05:12:08 INFO - psutil == 3.1.1 05:12:08 INFO - requests == 1.2.3 05:12:08 INFO - wsgiref == 0.1.2 05:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:12: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 05:12: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 05:12:08 INFO - Using env: {'DISPLAY': ':0', 05:12:08 INFO - 'HOME': '/home/cltbld', 05:12:08 INFO - 'LANG': 'en_US.UTF-8', 05:12:08 INFO - 'LANGUAGE': 'en_US:en', 05:12:08 INFO - 'LOGNAME': 'cltbld', 05:12:08 INFO - 'MAIL': '/var/mail/cltbld', 05:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:08 INFO - 'MOZ_NO_REMOTE': '1', 05:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:08 INFO - 'NO_EM_RESTART': '1', 05:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:08 INFO - 'PWD': '/builds/slave/test', 05:12:08 INFO - 'SHELL': '/bin/bash', 05:12:08 INFO - 'SHLVL': '1', 05:12:08 INFO - 'TERM': 'linux', 05:12:08 INFO - 'TMOUT': '86400', 05:12:08 INFO - 'USER': 'cltbld', 05:12:08 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:08 INFO - '_': '/tools/buildbot/bin/python'} 05:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12: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)) 05:12:08 INFO - Cleaning up... 05:12:08 INFO - Return code: 0 05:12:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:12: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')]} 05:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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')]} 05:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:12: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 0x7f952e46ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12048a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x12d07b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, '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': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', '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 05:12: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 05:12: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 05:12:08 INFO - Using env: {'DISPLAY': ':0', 05:12:08 INFO - 'HOME': '/home/cltbld', 05:12:08 INFO - 'LANG': 'en_US.UTF-8', 05:12:08 INFO - 'LANGUAGE': 'en_US:en', 05:12:08 INFO - 'LOGNAME': 'cltbld', 05:12:08 INFO - 'MAIL': '/var/mail/cltbld', 05:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:08 INFO - 'MOZ_NO_REMOTE': '1', 05:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:08 INFO - 'NO_EM_RESTART': '1', 05:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:08 INFO - 'PWD': '/builds/slave/test', 05:12:08 INFO - 'SHELL': '/bin/bash', 05:12:08 INFO - 'SHLVL': '1', 05:12:08 INFO - 'TERM': 'linux', 05:12:08 INFO - 'TMOUT': '86400', 05:12:08 INFO - 'USER': 'cltbld', 05:12:08 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:08 INFO - '_': '/tools/buildbot/bin/python'} 05:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:12:08 INFO - Cleaning up... 05:12:08 INFO - Return code: 0 05:12:08 INFO - Running post-action listener: _resource_record_post_action 05:12:08 INFO - Running post-action listener: _start_resource_monitoring 05:12:08 INFO - Starting resource monitoring. 05:12:08 INFO - [mozharness: 2016-10-13 12:12:08.709271Z] Finished create-virtualenv step (success) 05:12:08 INFO - [mozharness: 2016-10-13 12:12:08.709735Z] Running install step. 05:12:08 INFO - Running pre-action listener: _resource_record_pre_action 05:12:08 INFO - Running main action method: install 05:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:12:08 INFO - Reading from file tmpfile_stdout 05:12:08 INFO - Detecting whether we're running mozinstall >=1.0... 05:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:12:09 INFO - Reading from file tmpfile_stdout 05:12:09 INFO - Output received: 05:12:09 INFO - Usage: mozinstall [options] installer 05:12:09 INFO - Options: 05:12:09 INFO - -h, --help show this help message and exit 05:12:09 INFO - -d DEST, --destination=DEST 05:12:09 INFO - Directory to install application into. [default: 05:12:09 INFO - "/builds/slave/test"] 05:12:09 INFO - --app=APP Application being installed. [default: firefox] 05:12:09 INFO - mkdir: /builds/slave/test/build/application 05:12:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:12:26 INFO - Reading from file tmpfile_stdout 05:12:26 INFO - Output received: 05:12:26 INFO - /builds/slave/test/build/application/firefox/firefox 05:12:26 INFO - Running post-action listener: _resource_record_post_action 05:12:26 INFO - [mozharness: 2016-10-13 12:12:26.029285Z] Finished install step (success) 05:12:26 INFO - [mozharness: 2016-10-13 12:12:26.029534Z] Running run-tests step. 05:12:26 INFO - Running pre-action listener: _resource_record_pre_action 05:12:26 INFO - Running main action method: run_tests 05:12:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:12:26 INFO - Python 2.7.3 05:12:26 INFO - Return code: 0 05:12:26 INFO - grabbing minidump binary from tooltool 05:12:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:12:26 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 0x135f790>, '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 0x1360690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1360b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:12:26 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 05:12:26 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 05:12:26 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 05:12:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:12:26 INFO - Return code: 0 05:12:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:12:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:12:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:12:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:12:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:12:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 05:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --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 05:12:26 INFO - Using env: {'DISPLAY': ':0', 05:12:26 INFO - 'HOME': '/home/cltbld', 05:12:26 INFO - 'LANG': 'en_US.UTF-8', 05:12:26 INFO - 'LANGUAGE': 'en_US:en', 05:12:26 INFO - 'LOGNAME': 'cltbld', 05:12:26 INFO - 'MAIL': '/var/mail/cltbld', 05:12:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:12:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:12:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:26 INFO - 'MOZ_NO_REMOTE': '1', 05:12:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:12:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:12:26 INFO - 'NO_EM_RESTART': '1', 05:12:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:12:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:26 INFO - 'PWD': '/builds/slave/test', 05:12:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:12:26 INFO - 'SHELL': '/bin/bash', 05:12:26 INFO - 'SHLVL': '1', 05:12:26 INFO - 'TERM': 'linux', 05:12:26 INFO - 'TMOUT': '86400', 05:12:26 INFO - 'USER': 'cltbld', 05:12:26 INFO - 'XDG_SESSION_COOKIE': '56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920', 05:12:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:26 INFO - '_': '/tools/buildbot/bin/python'} 05:12:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--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 05:12:26 INFO - mozversion application_buildid: 20161013033014 05:12:26 INFO - mozversion application_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 05:12:26 INFO - mozversion application_display_name: Nightly 05:12:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:12:26 INFO - mozversion application_name: Firefox 05:12:26 INFO - mozversion application_remotingname: firefox 05:12:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:12:26 INFO - mozversion application_vendor: Mozilla 05:12:26 INFO - mozversion application_version: 52.0a1 05:12:26 INFO - mozversion platform_buildid: 20161013033014 05:12:26 INFO - mozversion platform_changeset: f03e2740d604d339ed553dad62a3fc54c317f8fa 05:12:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:12:26 INFO - mozversion platform_version: 52.0a1 05:12:26 INFO - using testdate: 1476360746 05:12:26 INFO - actual date: 1476360746 05:12:26 INFO - starting webserver on 'localhost:50150' 05:12:26 INFO - SUITE-START | Running 2 tests 05:12:26 INFO - TEST-START | dromaeo_css 05:12:26 INFO - operating with platform_type : linux_ 05:12:26 INFO - Initialising browser for dromaeo_css test... 05:12:26 INFO - TEST-INFO | started process 3095 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5M0Rnp/profile http://localhost:50150/getInfo.html) 05:12:30 INFO - PROCESS | 3095 | __metrics Screen width/height:1600/1200 05:12:30 INFO - PROCESS | 3095 | colorDepth:24 05:12:30 INFO - PROCESS | 3095 | Browser inner width/height: 1024/697 05:12:30 INFO - PROCESS | 3095 | __metrics 05:12:30 INFO - PROCESS | 3095 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:12:30 INFO - PROCESS | 3095 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:12:31 INFO - PROCESS | 3095 | console.error: PushService: 05:12:31 INFO - PROCESS | 3095 | stateChangeProcessEnqueue: Error transitioning state 05:12:31 INFO - PROCESS | 3095 | UnknownError 05:12:32 INFO - TEST-INFO | 3095: exit 0 05:12:32 INFO - Browser initialized. 05:12:32 INFO - Running cycle 1/1 for dromaeo_css test... 05:12:32 INFO - TEST-INFO | started process 3177 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5M0Rnp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:44 INFO - PROCESS | 3177 | RSS: Main: 174522368 05:12:44 INFO - PROCESS | 3177 | 05:13:35 INFO - PROCESS | 3177 | 1476360815960 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:15:45 INFO - PROCESS | 3177 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50150/tests/dromaeo/cssquery-ext.html) 05:15:47 INFO - PROCESS | 3177 | RSS: Main: 329129984 05:15:47 INFO - PROCESS | 3177 | 05:18:50 INFO - PROCESS | 3177 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-ext.html (next: http://localhost:50150/tests/dromaeo/cssquery-jquery.html) 05:18:50 INFO - PROCESS | 3177 | RSS: Main: 271859712 05:18:50 INFO - PROCESS | 3177 | 05:21:52 INFO - PROCESS | 3177 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50150/tests/dromaeo/cssquery-mootools.html) 05:21:53 INFO - PROCESS | 3177 | RSS: Main: 215384064 05:21:53 INFO - PROCESS | 3177 | 05:24:55 INFO - PROCESS | 3177 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50150/tests/dromaeo/cssquery-prototype.html) 05:24:55 INFO - PROCESS | 3177 | RSS: Main: 261472256 05:24:55 INFO - PROCESS | 3177 | 05:27:58 INFO - PROCESS | 3177 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50150/tests/dromaeo/cssquery-yui.html) 05:27:59 INFO - PROCESS | 3177 | RSS: Main: 219107328 05:27:59 INFO - PROCESS | 3177 | 05:31:01 INFO - PROCESS | 3177 | Cycle 1(1): loaded http://localhost:50150/tests/dromaeo/cssquery-yui.html (next: http://localhost:50150/tests/dromaeo/cssquery-dojo.html) 05:31:02 INFO - PROCESS | 3177 | RSS: Main: 309182464 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | __start_tp_report 05:31:02 INFO - PROCESS | 3177 | _x_x_mozilla_page_load 05:31:02 INFO - PROCESS | 3177 | _x_x_mozilla_page_load_details 05:31:02 INFO - PROCESS | 3177 | |i|pagename|runs| 05:31:02 INFO - PROCESS | 3177 | |0;dojo.html;2998.7451066659337;3047.817130972142;3050.4814181589127;3073.431415188189;3097.1947293703643;5110.310108135401;5218.895622087556;5249.055170069386;5323.9733801330985;5363.8122665706705;5240.711760853152;5246.081935661263;5247.921281180776;5248.448912864152;5248.973755131219;4739.454962679293;4747.620190384779;4773.641976851733;4801.7118972861745;4831.323614693945;4815.494373090827;4842.443119041302;4877.414710234777;4988.077205716936;4995.875103122415;4858.076965376585;4912.017596480719;4913.459519452857;4925.507449255082;4929.383827021622;4208.621224089847;4844.246349331436;4895.828645997373;4951.108800415924;4951.5543601075715;4866.245731911524;4878.317035615017;4927.063857866994;4933.260010998343;4937.629677774145;5103.23451482277;5144.382674079097;5147.279380886679;5233.188855727369;5302.310699609065;5556.16657501374;5569.8607534811545;5581.413951535112;5667.4332566743;5667.971660141714;5649.406812284692;5684.573656975702;5701;5711.600187986843;5715.399883012307;5200.6619569727845;5304.549113325372;5332.200169974535;5339.172428273624;5386.165144402624;5669.688167150846;5693.4021927696995;5737.168110623936;5750.7987220447085;5764.394738552392;5526.58550608706;5545.500904918576;5553.500184983371;5561.304836895388;5566.304211973504;5493.807716729975;5613.045782217033;5615.494605485526;5619.690916999522;5623.156526521055;5515.09001014828;5609.691466969274;5685.346185188673;5686.459786320293;5688.317401911714;5603.943960560342;5609.915851262234;5610.85972850682;5632.662040277596;5661.3206415229615;4339.891502712458;4346.413234213341;4346.760928148982;4354.477462704433;4397.956020439755;3769.396896496602;3826.947589412878;3836.769793812324;3848.230353929225;3850.114473671071;5280.762365693477;5293.100172970604;5308.973455132699;5310.9734451327495;5355.357357117093;5648.51987581052;5661.320641523043;5696.743646535916;5707.4577915098;5709.714514274269;5195.506426889439;5199.584033277329;5249.265102885599;5349.919751203735;5402.594805389612;4674.859754207379;4709.8351557696005;4730.148573256847;4772.665913386099;4780.378550788375;5771.30744310677;5775.364709881909;5779.826605201851;5780.71096445176;5796.652200867793;5389.434109418455;5507.421720719267;5588.748506317307;5592.133219351007;5631.690257035739;5784;5833.154192642128;5837.6497410155525;5884.382139875252;5956.315023772322;5506.284183056177;5553;5557.083081291703;5561.610687252015;5571.359293681278;5434.809781657623;5624.943750562442;5638.689872057076;5642.689652069175;5642.943570564242;5321.680699157908;5442.265294185234;5464.4808743170925;5465.617406781487;5554.277943867433;5510.256115424527;5512.5589952802875;5520.309961254843;5520.585956053232;5542.224088627515;5511.559075274049;5515.283013208257;5527.53015993645;5530.917036244379;5538.750756215899;5363.19552067193;5418.918716219181;5469.234307196916;5487.862803429946;5509;5401.756920938488;5446.618736688394;5451.236826844166;5451.536619387238;5493.533049690821 05:31:02 INFO - PROCESS | 3177 | |1;ext.html;546.7303381736673;591.3672370563497;591.8165368735689;599.8830228105516;612.8896798576258;33423.832880835595;45932.770336148315;46869.53130468694;46903;46903.53096469043;44970.325445118266;45104.32343514841;45324.320135197995;45390.319145212765;45470.77264613681;8058.4359094863385;8835.469871807696;8983.865242021375;8993.640254389817;8998;8179.100298967109;10364.067233948943;10633.946830265837;10651.946740266289;11019.118470522339;10788.244822862403;10830.512626931806;10850.945745271301;10853.348799072042;10857;10711.678649640524;10760.946195269053;10769.51537180821;10777.245592808507;10779.137668986463;10497.107745841613;10571.577136914515;10583;10583.73540661482;10590.841137382946;42501;42738.57261427408;42766.358504622454;42856.57143428557;42858.142837143394;3491.423915054013;3519.5952465466544;3639.2357604903004;3649.051246675857;3753.8123093845197;31818.363632727214;31918.042458726275;32007;32280.515792263133;32922;4257.552956939508;4385.671074669381;4411.404460397837;4479.641628669699;4550.089982003613;229957;230838;231188;231514;231768;57838.7108064457;58123.4187658118;58165;58194.7090264546;58269;35958.64041359553;36844;37213.441798373045;39549.406758898636;39609.40585891214;15909;16203.594910127105;16218.67562648764;16223.675526489404;16230.321996870383;10696.732581685364;10938.890611093946;11551.711207219718;11572.074234061254;11775.76448471043;18243.908780456277;18831.905840470983;18879.90560047191;18952.336668216547;18961;7169.928300717031;7243.89134162988;7273.309035641606;7313.524620899624;7407.481476296715;940.5156344482597;968.7626531499827;1037.5123691864812;1074.0709286467306;1145.6505765741363;31566.526502102486;31792;32962.67037329644;33066.338673226885;33345;1073.3077165228383;1084.1164450972433;1106.9280496767685;1113.8162203236375;1161.8838116188313;15986;16768.58078548046;16992.74510882338;17112.40106596288;17269.913650431918;512658;516787;517073;517175;517776;56541.151882721795;57121;57121.428785711614;57121.428785711614;57132;1195.2231049817688;1212.6907638552113;1297.9740405192033;1336.1181620130862;1371.2869307959804;28398.574021390108;32271.67728322687;32919.34161316808;33711.32577348488;33813.49279760756;49203.26195107148;49486.50513494963;49555.75222123866;49796.25305620346;49855.501444985086;380249.88875501225;380630;381389;382493;382838;43479.347809782244;43544.56455435405;43617.127657446035;44559;44561.77719111383;44104.12414039151;44824.32763508485;48018.5198148028;48161.75919120382;49603;39468;39801.80099099486;40928.79535602303;40943.7952810246;42430.57569424266;40531;40872.38691419572;41154;41901;42983.355249670654;40727;40729.59270407258;41658.583414166686;42301.78849105735;42582.36126458044;35090;36275.81862090673;36589.81705091458;37624;37948.43077353787;28597;29044;29118.417631647673;29428;29644.40711185721 05:31:02 INFO - PROCESS | 3177 | |2;jquery.html;383.6259646844325;403.328458117235;406.72952486596427;408.91412803311306;423.7626928919805;12936;15150.242487875617;18847.340343088013;19497.610047799037;19753.11111000005;17936.551586210353;18219.72670409945;18904.338348157817;19415.4175374739;19451.221951121937;5696.914546281809;5699.002674531961;5945.37573554777;5947.791827286046;5956.16613674086;5805.216295800055;5811.099279611667;5843.357230704619;5848.707564621752;5892.88214235715;5588.91616625751;5696.231008813819;5812.82561523155;5817.389174136718;5817.534597232232;5766.221560089376;5792.536597072224;5812.6512409255365;5817.680027598481;5818.4763371296785;5000;5177.534021938043;5180.870478238036;5194.6104042196985;5212.687238765686;22647.77352226473;22669.77330226693;22717.204897828662;22748.431289217737;22896.19863304793;2364.645303204533;2388.7850093491497;2451.852888826676;2543.4023004593905;2839.5030869597945;14185.929070354685;14296.285185740671;14340.211288379134;14635.853641463556;15018.324175412023;1760.4102625620433;1786.8302511261409;1833.0559761722723;1857.117869012222;1894.990525047366;51657.74171129158;83038.75441868437;83150.58424707898;83790;83873.58063209706;10531.315464494783;10579.31234469757;10582.417967011888;10988.560457581767;11166.944165279121;15266.084034957938;15277.777777777752;15317;15326.463573774865;15342.309596068204;7615.276548727862;7838.294553490213;7929;7962.243586859239;8037.919620803717;7242.239564845615;7333.669984850694;7348.669309881068;7509.7371591994015;7612.429067819825;6353.428191462791;6493.480521558358;6601.471882249409;6611.966940165268;6712.53616345125;1872.788168291387;1933.1977229449622;1940.408175506484;1952.9707054394196;2012.7282816819834;896.6189369517929;934.72425634438;1023.7491814505494;1039.4387031003173;1048.6277371532997;19799.802001980082;19852.801471985385;19987.300444484663;20094.89952550228;20117;990.9752256193652;1008.5511947183433;1011.7824667696481;1021.6475316015963;1030.8402197659375;10649.467526623794;10690.946545267223;11360.204785664924;11399.601013964471;11527.308361498337;411977;432738;450294;452880;453404;95208.67078609;96556.51721741346;96947.51526242464;97767;98881;979.9657012004545;1021.5352014833085;1037.3620223562455;1049.0872940541624;1063.5214153630898;16547.751783723495;17028.659426811642;17461.30154793794;17713.20290586901;18201.180946857687;42810.78594607007;43062;43940;44344.57453083904;44938;58138;59421.405785941584;60556;60584;61474;8196.754097377088;8336.874521939702;8444;8454.36592255571;8989.146031127295;8855.822883542165;11909.880901190878;12990.545330913372;13581.728365432438;13768.862311376757;11369.883552401287;11937.3396757405;12000.539889322843;12092;12176.756464870476;11463.541458341573;11673.12451566119;11843.348615825867;11876.524939002344;11927.105467090176;11925.231791362845;12088.818667720167;12344.506219751109;12516.499340026297;12589;7646.541207527566;7919.366450683816;8670.91329086701;8732.519711415678;8821.470711757314;11608.535658573563;11949.462274197507;11965.162438629213;12062.396880155782;12437.378131093228 05:31:02 INFO - PROCESS | 3177 | |3;mootools.html;1288.4459682336592;1408.9205080079453;1453.622058264851;1556.3541130430872;1748.527897467683;7721.30508254257;8622.698205562807;8792.51641159736;8838.160374764404;9110.544472776368;8964.50695211763;9045.3668243223;9048.285514345687;9054.185123338899;9058.637654493812;2143.7320334958167;2151.9784802151935;2175.3256490487997;2182.23621732394;2186.1146235774536;2058.2196400113867;2065.4216819290623;2093.2045822587397;2098.8635738676935;2107.251925566417;2041.4488088216247;2049.6003279360457;2053.16846677096;2063.370671945056;2137.454948988006;2035.7862424445443;2045.1308194017558;2048.4469193317723;2050.9794902051085;2061.072517367183;2024.44327809852;2042.816146546818;2080.843936704753;2083.020980139332;2084.343431818972;8931.687390941286;8940.8658870117;9004.234640055603;9049;9071.138241867013;4957.603391728653;5073.847784566469;5112.207607820794;5169.844904652867;5199.14214154664;3260.2175477885376;3378.6283508814013;3437.7077948374417;3437.9484307735406;3439.3465241604267;1009.4246279620619;1037.4294138223947;1041.9062284394365;1050.9264351495399;1061.7770268243678;12262.754744905053;12321.5071397144;12405.193662411913;12407.565735199225;12497.312647804367;4174.686898482626;4273.615374616299;4361.890952726208;4457.955420445754;4462.799174037176;9823.263255255884;9836.311458198;9847.655332063487;9848.803023939481;9854.704358869245;5091.210862316347;5094.5669618082875;5100.26046223303;5103.923441148386;5105.2597373381395;4181.665466762652;4232.492100947844;4740.146773580788;4763.142634325785;4775.928361074587;3582.8387722552548;3602.5496812898386;3677.062349100962;3724.050367156412;3731.776093434403;1066.6213494209599;1068.823644098715;1073.6993641780314;1076.8815430302661;1086.0931122512632;769.1424062170752;854.2013217641525;885.9911400885909;909.2453263791037;938.6573900526396;5634.42236173872;5659.236003139606;5669.064604340238;5690.5163061140265;5877.147813567094;936.1106948399048;951.9857202141974;967.970960871175;968.2592816495387;982.8574856645889;3769.2838360711376;3854.402567602026;3895.8831235062994;3927.4501569780255;3970.265500882346;11016.559337626566;11269.323840569592;11297.604583839526;11320.26418282809;11334.149938754626;11249.887501125047;11251.212415130862;11252;11276.943615282034;11285.43572821372;7861.606919653995;8223.506589604644;8231.835363292701;8245.051819040887;8399.37004724648;5701.230333904869;5977.701114944322;6019.548533860066;6104.17593624855;6192.164057852132;10045.49772511386;10089.192864570963;10094.343867648578;10248.38509689421;10252.231082668979;9690.903090969001;9867.851982220416;9911.389561440903;9980.850287245552;10335.379877207391;7204.351608355273;7440.7023719052795;7965.203479651988;8037.919620803717;8121;8384.748457546284;8669.739907802776;8679;8875.156860098281;8878.73363799087;7734.82860097036;7782.766517004499;7934.2859142677435;8089;8160.510369377619;7639.274268944664;7650.1584825668015;7774.6890124394395;7819.609019548886;7968;6631.734730610914;6751.797446076625;6753.459723222229;6757.526973111835;6758;4985.900281994267;5041.092603331289;5064.873378165428;5073.213651883964;5323.946760532345;5031.7735701894235;5121.948780512296;5177.948220517746;5206.219067139959;5285.497877701612 05:31:02 INFO - PROCESS | 3177 | |4;prototype.html;330.37516928006994;333.0690719439166;334.8292370890848;343.08567667167;346.44741637088845;5072.188449848016;5177.818776342829;5236.554892834114;5278.129108697065;5280.207968804676;5042.82350117746;5136;5158.845234642958;5190.377154741427;5209.609279304048;4152.86127644262;4157.50109986802;4160.625543701066;4168.624823765853;4171.436856024428;3940.2119576084756;4147.294959856817;4185.22573323936;4186.56041115683;4191.979040104795;4094.242565125461;4101.487314085739;4112.930638034118;4126.257273690734;4135.8759237222785;4095.651869591088;4106.938395924063;4115.382692596119;4123.731957422758;4128.4632997710405;3643.9635603644156;3648.2521083177885;3650.7444478886487;3650.8317338451707;3663.4687970244167;5014.924776128361;5109.4379618241965;5116.897662046738;5128.871778205575;5129.384473863123;4694.29585562165;4705.200115980258;4711.175544279738;4711.905761884777;4735.1713450146435;5444.863878403032;5450.836474905759;5474.233607294982;5482.286268297178;5484.588655850787;3775.716821238418;3968.801559922021;3980.980095099535;3981.820818063194;3986;5595.4124816894455;5620.803271885465;5634.7182640867795;5655.208270842086;5687.573431992576;4044.5550989391145;4054.594540545922;4057.4725285713257;4073.2057248836786;4079.265732168238;3924.3917192835156;4111.588841115924;4124.072083781125;4125.53543492056;4158.896027599334;4681.789319480631;4685.320628508847;4685.531446855355;4690.6012988895645;4705.56480273513;5498.422665620133;5508.421615730371;5521.69630670317;5533.312339736339;5535.363433205233;1332.6335257803987;1348.8381394232754;1407.8310602727533;1418.971620567583;1429.2139323372312;886.4105369929053;897.8922529296526;926.3654396738256;927.2442958988403;929.149827907457;4665.300204969281;4665.533446655308;4688.249880019249;4706.647001474835;4723.95276047242;4909.877253068702;4923.532264434873;4929.457759646508;4941.75291235444;4990.426100998359;4387;4659.324397962277;4667.206574882278;4687.906241875144;4696.976515117471;2601.843889366644;2627.3037645024083;2631.3421644588852;2640.9568220552774;2655.4423571050297;5067.086425305464;5068.898622027539;5084.313617661568;5095.967899100029;5098.674344670412;4789.760511974457;4980.626453016038;4988.825391111293;4993.226049962262;4996.250562415594;5034.8237811677145;5053.31780209667;5081.577158395655;5084.898302033938;5089.92365114531;4879.170311133325;4916.631252655993;4922.944777923103;4935.629827763004;4946.505349465025;4560.977195114003;4783.904321913612;4824.228123500223;4866.343043689057;4868.829590964299;4997.800087996585;5004.274380214922;5054.69671819692;5061.9493805061475;5076.213186956028;3688.870889518854;3690.7047436205576;3708.2398108388256;3717.219383929405;3738.831752571195;4849.854504364875;4850.442199147002;4893.314935909047;4913.877153071202;5379.87966979159;5493.121100623881;5548.223248745259;5548.583856210881;5560.555155587463;5561.08142690771;5488.231647569263;5540.667559946416;5541.392996031267;5543.334799824047;5580.469855363734;5381.165919282517;5546.473085056913;5566.192902029102;5569.805056823154;5572.91640625399;5578.246936663499;5592.580556458201;5600.775968961196;5608.130739735348;5635.239242702347;5497.570631635884;5500.614956953135;5509.118541033576;5515.172724091419;5525.281713377235 05:31:02 INFO - PROCESS | 3177 | |5;yui.html;623.8253289079057;640.3628389752196;686.6738299307826;701.5510073552927;735.1215297719224;13475.460981560738;14314.284285785721;14589.56231313061;14656.70686586269;15119.244037798093;14549.636259093528;14632.853671463283;14672.853271467282;14682.779758303634;14683.412663493475;795.3676826922596;818.0796603820702;820.1962077164383;820.9096999330069;821.0393839208141;659.6734616364906;679.5582871133778;794.4478587381772;806.2179685704857;813.4915677701437;856.1139220906363;858.3047731337605;858.5492616376421;858.8840506531632;859.1408591408591;851.1488511488511;852.2925971443686;852.5864955496579;852.8550146475114;857.4769390671685;505.7850413574216;518.730260264664;599.5143933413949;619.1981384107592;627.9497640188774;12195.93902030493;12219.816702749466;12222.327771972537;12225.510979560808;12241.20432171906;13119;13541.322933853267;13543.119697219749;13628;13640.86359136406;12154.453049612788;12710.364481775967;12760.680982975407;12984.537702758598;13002.804957925639;4142.171565686845;4152.709310348277;4186.3092589722655;4241.448611680493;4243.299855523835;78250.21749782487;78463.6076819618;79473.20526794715;79479.60260198663;79513.20486795116;11248.212625116326;11332.716682083013;11389.886101139049;11485.483153258123;11522;17776.733348999776;17856.821431785775;18222.271109155485;18265.178066987017;18270.26918923228;7898.68405263783;8048.235417635316;8118.10700822909;8120.512769233865;8624.310055195569;4670.95329046712;4739.227551882119;4818.156822556109;4918.1884988977135;4936.03747269279;3560.8041557714055;3642.5446819147605;3643.216708407705;3643.872464463731;3651.2149887773726;608.6743592178252;612.381494690357;626.123427201921;629.9149614802035;632.6393955445444;532.3053415293002;657.044000978575;662.1722846441947;669.1234482827512;670.5339788846704;8657.653693852175;9049.864252036225;9327.847734694049;9399.483028433502;10138.898611013796;617.237711426388;651.9087327774115;652.8988006858945;664.0780565511786;677.2685499660454;4421.557844215553;4532.79602417892;4649.95350046502;4711.246200607886;4714.764261786897;122840;125422;125623.37188314;127994.36002819927;130829.34585327012;62033;104924;109533.45233273943;111323.44338278419;112997;97413.51293243488;97510.99999999857;98717;98754.50622746984;99347;8437.87343189865;8833.29333653319;8864.911350886408;9085;9178.072020789506;35253.31709921591;37202.39583229148;43954.56045439505;44957.325640116076;57670;52608.21087683764;54573.72713136409;68234.65882670555;81376.59311703403;86483.5675821642;12799.680007999874;13160.078794484678;13205.471781128646;13471.865281347062;14018.649533761738;15055.623609409415;15103.39586416575;15613;15705.293261803476;15973.121478318804;13575.456981720621;13739.381727822072;13857.79213311781;14437;15295.770563441334;12168.391580420766;13016.674583135497;13072.150310230185;13530.594082177551;13920.303984800517;11779.352135632622;12499.375031248583;12710.364481775689;12721.745565088831;13546.784487972005;11193.851068077265;11413;11705.531778728755;11709.707257318636;12334.321612311409;11445.427728613702;11606.88393116058;11668.241564298294;11694.649160525198;11928 05:31:02 INFO - PROCESS | 3177 | __end_tp_report 05:31:02 INFO - PROCESS | 3177 | __start_cc_report 05:31:02 INFO - PROCESS | 3177 | _x_x_mozilla_cycle_collect,1558 05:31:02 INFO - PROCESS | 3177 | __end_cc_report 05:31:02 INFO - PROCESS | 3177 | __startTimestamp1476361862238__endTimestamp 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | ------- Summary: start ------- 05:31:02 INFO - PROCESS | 3177 | Number of tests: 6 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | [#0] dojo.html Cycles:165 Average:5232.21 Median:5446.62 stddev:587.76 (10.8%) stddev-sans-first:562.85 05:31:02 INFO - PROCESS | 3177 | Values: 2998.7 3047.8 3050.5 3073.4 3097.2 5110.3 5218.9 5249.1 5324.0 5363.8 5240.7 5246.1 5247.9 5248.4 5249.0 4739.5 4747.6 4773.6 4801.7 4831.3 4815.5 4842.4 4877.4 4988.1 4995.9 4858.1 4912.0 4913.5 4925.5 4929.4 4208.6 4844.2 4895.8 4951.1 4951.6 4866.2 4878.3 4927.1 4933.3 4937.6 5103.2 5144.4 5147.3 5233.2 5302.3 5556.2 5569.9 5581.4 5667.4 5668.0 5649.4 5684.6 5701.0 5711.6 5715.4 5200.7 5304.5 5332.2 5339.2 5386.2 5669.7 5693.4 5737.2 5750.8 5764.4 5526.6 5545.5 5553.5 5561.3 5566.3 5493.8 5613.0 5615.5 5619.7 5623.2 5515.1 5609.7 5685.3 5686.5 5688.3 5603.9 5609.9 5610.9 5632.7 5661.3 4339.9 4346.4 4346.8 4354.5 4398.0 3769.4 3826.9 3836.8 3848.2 3850.1 5280.8 5293.1 5309.0 5311.0 5355.4 5648.5 5661.3 5696.7 5707.5 5709.7 5195.5 5199.6 5249.3 5349.9 5402.6 4674.9 4709.8 4730.1 4772.7 4780.4 5771.3 5775.4 5779.8 5780.7 5796.7 5389.4 5507.4 5588.7 5592.1 5631.7 5784.0 5833.2 5837.6 5884.4 5956.3 5506.3 5553.0 5557.1 5561.6 5571.4 5434.8 5624.9 5638.7 5642.7 5642.9 5321.7 5442.3 5464.5 5465.6 5554.3 5510.3 5512.6 5520.3 5520.6 5542.2 5511.6 5515.3 5527.5 5530.9 5538.8 5363.2 5418.9 5469.2 5487.9 5509.0 5401.8 5446.6 5451.2 5451.5 5493.5 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | [#1] ext.html Cycles:180 Average:54845.21 Median:209099.16 stddev:104348.08 (49.9%) stddev-sans-first:104561.16 05:31:02 INFO - PROCESS | 3177 | Values: 546.7 591.4 591.8 599.9 612.9 33423.8 45932.8 46869.5 46903.0 46903.5 44970.3 45104.3 45324.3 45390.3 45470.8 8058.4 8835.5 8983.9 8993.6 8998.0 8179.1 10364.1 10633.9 10651.9 11019.1 10788.2 10830.5 10850.9 10853.3 10857.0 10711.7 10760.9 10769.5 10777.2 10779.1 10497.1 10571.6 10583.0 10583.7 10590.8 42501.0 42738.6 42766.4 42856.6 42858.1 3491.4 3519.6 3639.2 3649.1 3753.8 31818.4 31918.0 32007.0 32280.5 32922.0 4257.6 4385.7 4411.4 4479.6 4550.1 229957.0 230838.0 231188.0 231514.0 231768.0 57838.7 58123.4 58165.0 58194.7 58269.0 35958.6 36844.0 37213.4 39549.4 39609.4 15909.0 16203.6 16218.7 16223.7 16230.3 10696.7 10938.9 11551.7 11572.1 11775.8 18243.9 18831.9 18879.9 18952.3 18961.0 7169.9 7243.9 7273.3 7313.5 7407.5 940.5 968.8 1037.5 1074.1 1145.7 31566.5 31792.0 32962.7 33066.3 33345.0 1073.3 1084.1 1106.9 1113.8 1161.9 15986.0 16768.6 16992.7 17112.4 17269.9 512658.0 516787.0 517073.0 517175.0 517776.0 56541.2 57121.0 57121.4 57121.4 57132.0 1195.2 1212.7 1298.0 1336.1 1371.3 28398.6 32271.7 32919.3 33711.3 33813.5 49203.3 49486.5 49555.8 49796.3 49855.5 380249.9 380630.0 381389.0 382493.0 382838.0 43479.3 43544.6 43617.1 44559.0 44561.8 44104.1 44824.3 48018.5 48161.8 49603.0 39468.0 39801.8 40928.8 40943.8 42430.6 40531.0 40872.4 41154.0 41901.0 42983.4 40727.0 40729.6 41658.6 42301.8 42582.4 35090.0 36275.8 36589.8 37624.0 37948.4 28597.0 29044.0 29118.4 29428.0 29644.4 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | [#2] jquery.html Cycles:180 Average:27863.60 Median:2376.72 stddev:73035.27 (3073.0%) stddev-sans-first:73211.01 05:31:02 INFO - PROCESS | 3177 | Values: 383.6 403.3 406.7 408.9 423.8 12936.0 15150.2 18847.3 19497.6 19753.1 17936.6 18219.7 18904.3 19415.4 19451.2 5696.9 5699.0 5945.4 5947.8 5956.2 5805.2 5811.1 5843.4 5848.7 5892.9 5588.9 5696.2 5812.8 5817.4 5817.5 5766.2 5792.5 5812.7 5817.7 5818.5 5000.0 5177.5 5180.9 5194.6 5212.7 22647.8 22669.8 22717.2 22748.4 22896.2 2364.6 2388.8 2451.9 2543.4 2839.5 14185.9 14296.3 14340.2 14635.9 15018.3 1760.4 1786.8 1833.1 1857.1 1895.0 51657.7 83038.8 83150.6 83790.0 83873.6 10531.3 10579.3 10582.4 10988.6 11166.9 15266.1 15277.8 15317.0 15326.5 15342.3 7615.3 7838.3 7929.0 7962.2 8037.9 7242.2 7333.7 7348.7 7509.7 7612.4 6353.4 6493.5 6601.5 6612.0 6712.5 1872.8 1933.2 1940.4 1953.0 2012.7 896.6 934.7 1023.7 1039.4 1048.6 19799.8 19852.8 19987.3 20094.9 20117.0 991.0 1008.6 1011.8 1021.6 1030.8 10649.5 10690.9 11360.2 11399.6 11527.3 411977.0 432738.0 450294.0 452880.0 453404.0 95208.7 96556.5 96947.5 97767.0 98881.0 980.0 1021.5 1037.4 1049.1 1063.5 16547.8 17028.7 17461.3 17713.2 18201.2 42810.8 43062.0 43940.0 44344.6 44938.0 58138.0 59421.4 60556.0 60584.0 61474.0 8196.8 8336.9 8444.0 8454.4 8989.1 8855.8 11909.9 12990.5 13581.7 13768.9 11369.9 11937.3 12000.5 12092.0 12176.8 11463.5 11673.1 11843.3 11876.5 11927.1 11925.2 12088.8 12344.5 12516.5 12589.0 7646.5 7919.4 8670.9 8732.5 8821.5 11608.5 11949.5 11965.2 12062.4 12437.4 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | [#3] mootools.html Cycles:180 Average:5734.27 Median:5069.04 stddev:3391.04 (66.9%) stddev-sans-first:3384.09 05:31:02 INFO - PROCESS | 3177 | Values: 1288.4 1408.9 1453.6 1556.4 1748.5 7721.3 8622.7 8792.5 8838.2 9110.5 8964.5 9045.4 9048.3 9054.2 9058.6 2143.7 2152.0 2175.3 2182.2 2186.1 2058.2 2065.4 2093.2 2098.9 2107.3 2041.4 2049.6 2053.2 2063.4 2137.5 2035.8 2045.1 2048.4 2051.0 2061.1 2024.4 2042.8 2080.8 2083.0 2084.3 8931.7 8940.9 9004.2 9049.0 9071.1 4957.6 5073.8 5112.2 5169.8 5199.1 3260.2 3378.6 3437.7 3437.9 3439.3 1009.4 1037.4 1041.9 1050.9 1061.8 12262.8 12321.5 12405.2 12407.6 12497.3 4174.7 4273.6 4361.9 4458.0 4462.8 9823.3 9836.3 9847.7 9848.8 9854.7 5091.2 5094.6 5100.3 5103.9 5105.3 4181.7 4232.5 4740.1 4763.1 4775.9 3582.8 3602.5 3677.1 3724.1 3731.8 1066.6 1068.8 1073.7 1076.9 1086.1 769.1 854.2 886.0 909.2 938.7 5634.4 5659.2 5669.1 5690.5 5877.1 936.1 952.0 968.0 968.3 982.9 3769.3 3854.4 3895.9 3927.5 3970.3 11016.6 11269.3 11297.6 11320.3 11334.1 11249.9 11251.2 11252.0 11276.9 11285.4 7861.6 8223.5 8231.8 8245.1 8399.4 5701.2 5977.7 6019.5 6104.2 6192.2 10045.5 10089.2 10094.3 10248.4 10252.2 9690.9 9867.9 9911.4 9980.9 10335.4 7204.4 7440.7 7965.2 8037.9 8121.0 8384.7 8669.7 8679.0 8875.2 8878.7 7734.8 7782.8 7934.3 8089.0 8160.5 7639.3 7650.2 7774.7 7819.6 7968.0 6631.7 6751.8 6753.5 6757.5 6758.0 4985.9 5041.1 5064.9 5073.2 5323.9 5031.8 5121.9 5177.9 5206.2 5285.5 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | [#4] prototype.html Cycles:180 Average:4430.89 Median:4911.88 stddev:1271.37 (25.9%) stddev-sans-first:1237.12 05:31:02 INFO - PROCESS | 3177 | Values: 330.4 333.1 334.8 343.1 346.4 5072.2 5177.8 5236.6 5278.1 5280.2 5042.8 5136.0 5158.8 5190.4 5209.6 4152.9 4157.5 4160.6 4168.6 4171.4 3940.2 4147.3 4185.2 4186.6 4192.0 4094.2 4101.5 4112.9 4126.3 4135.9 4095.7 4106.9 4115.4 4123.7 4128.5 3644.0 3648.3 3650.7 3650.8 3663.5 5014.9 5109.4 5116.9 5128.9 5129.4 4694.3 4705.2 4711.2 4711.9 4735.2 5444.9 5450.8 5474.2 5482.3 5484.6 3775.7 3968.8 3981.0 3981.8 3986.0 5595.4 5620.8 5634.7 5655.2 5687.6 4044.6 4054.6 4057.5 4073.2 4079.3 3924.4 4111.6 4124.1 4125.5 4158.9 4681.8 4685.3 4685.5 4690.6 4705.6 5498.4 5508.4 5521.7 5533.3 5535.4 1332.6 1348.8 1407.8 1419.0 1429.2 886.4 897.9 926.4 927.2 929.1 4665.3 4665.5 4688.2 4706.6 4724.0 4909.9 4923.5 4929.5 4941.8 4990.4 4387.0 4659.3 4667.2 4687.9 4697.0 2601.8 2627.3 2631.3 2641.0 2655.4 5067.1 5068.9 5084.3 5096.0 5098.7 4789.8 4980.6 4988.8 4993.2 4996.3 5034.8 5053.3 5081.6 5084.9 5089.9 4879.2 4916.6 4922.9 4935.6 4946.5 4561.0 4783.9 4824.2 4866.3 4868.8 4997.8 5004.3 5054.7 5061.9 5076.2 3688.9 3690.7 3708.2 3717.2 3738.8 4849.9 4850.4 4893.3 4913.9 5379.9 5493.1 5548.2 5548.6 5560.6 5561.1 5488.2 5540.7 5541.4 5543.3 5580.5 5381.2 5546.5 5566.2 5569.8 5572.9 5578.2 5592.6 5600.8 5608.1 5635.2 5497.6 5500.6 5509.1 5515.2 5525.3 05:31:02 INFO - PROCESS | 3177 | 05:31:02 INFO - PROCESS | 3177 | [#5] yui.html Cycles:180 Average:20992.13 Median:4242.37 stddev:31830.63 (750.3%) stddev-sans-first:31883.18 05:31:02 INFO - PROCESS | 3177 | Values: 623.8 640.4 686.7 701.6 735.1 13475.5 14314.3 14589.6 14656.7 15119.2 14549.6 14632.9 14672.9 14682.8 14683.4 795.4 818.1 820.2 820.9 821.0 659.7 679.6 794.4 806.2 813.5 856.1 858.3 858.5 858.9 859.1 851.1 852.3 852.6 852.9 857.5 505.8 518.7 599.5 619.2 627.9 12195.9 12219.8 12222.3 12225.5 12241.2 13119.0 13541.3 13543.1 13628.0 13640.9 12154.5 12710.4 12760.7 12984.5 13002.8 4142.2 4152.7 4186.3 4241.4 4243.3 78250.2 78463.6 79473.2 79479.6 79513.2 11248.2 11332.7 11389.9 11485.5 11522.0 17776.7 17856.8 18222.3 18265.2 18270.3 7898.7 8048.2 8118.1 8120.5 8624.3 4671.0 4739.2 4818.2 4918.2 4936.0 3560.8 3642.5 3643.2 3643.9 3651.2 608.7 612.4 626.1 629.9 632.6 532.3 657.0 662.2 669.1 670.5 8657.7 9049.9 9327.8 9399.5 10138.9 617.2 651.9 652.9 664.1 677.3 4421.6 4532.8 4650.0 4711.2 4714.8 122840.0 125422.0 125623.4 127994.4 130829.3 62033.0 104924.0 109533.5 111323.4 112997.0 97413.5 97511.0 98717.0 98754.5 99347.0 8437.9 8833.3 8864.9 9085.0 9178.1 35253.3 37202.4 43954.6 44957.3 57670.0 52608.2 54573.7 68234.7 81376.6 86483.6 12799.7 13160.1 13205.5 13471.9 14018.6 15055.6 15103.4 15613.0 15705.3 15973.1 13575.5 13739.4 13857.8 14437.0 15295.8 12168.4 13016.7 13072.2 13530.6 13920.3 11779.4 12499.4 12710.4 12721.7 13546.8 11193.9 11413.0 11705.5 11709.7 12334.3 11445.4 11606.9 11668.2 11694.6 11928.0 05:31:02 INFO - PROCESS | 3177 | -------- Summary: end -------- 05:31:02 INFO - PROCESS | 3177 | 05:31:03 INFO - TEST-INFO | 3177: exit 0 05:31:03 INFO - TEST-OK | dromaeo_css | took 1116794ms 05:31:03 INFO - TEST-START | kraken 05:31:03 INFO - operating with platform_type : linux_ 05:31:03 INFO - Initialising browser for kraken test... 05:31:03 INFO - TEST-INFO | started process 3492 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjYuJUd/profile http://localhost:50150/getInfo.html) 05:31:07 INFO - PROCESS | 3492 | __metrics Screen width/height:1600/1200 05:31:07 INFO - PROCESS | 3492 | colorDepth:24 05:31:07 INFO - PROCESS | 3492 | Browser inner width/height: 1024/697 05:31:07 INFO - PROCESS | 3492 | __metrics 05:31:07 INFO - PROCESS | 3492 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:31:07 INFO - PROCESS | 3492 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:31:07 INFO - PROCESS | 3492 | console.error: 05:31:07 INFO - PROCESS | 3492 | Message: Error: Connection closed before committing the transaction. 05:31:07 INFO - PROCESS | 3492 | Stack: 05:31:07 INFO - PROCESS | 3492 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 05:31:07 INFO - PROCESS | 3492 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 05:31:07 INFO - PROCESS | 3492 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 05:31:07 INFO - PROCESS | 3492 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 05:31:07 INFO - PROCESS | 3492 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 05:31:07 INFO - PROCESS | 3492 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 05:31:07 INFO - PROCESS | 3492 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 05:31:07 INFO - PROCESS | 3492 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 05:31:07 INFO - PROCESS | 3492 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 05:31:07 INFO - PROCESS | 3492 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 05:31:07 INFO - PROCESS | 3492 | Barrier/this.client.addBlocker/promise13.1% 05:32:05 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 05:32:05 INFO - TinderboxPrint: I/O write bytes / time
399,732,736 / 355,204 05:32:05 INFO - TinderboxPrint: CPU idle
8,291.8 (86.9%) 05:32:05 INFO - TinderboxPrint: CPU user
1,198.4 (12.6%) 05:32:05 INFO - TinderboxPrint: Swap in / out
0 / 0 05:32:05 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110174208; Read time: 0; Write time: 93344 05:32:05 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 288661504; Read time: 0; Write time: 261844 05:32:06 INFO - Running post-run listener: _upload_blobber_files 05:32:06 INFO - Blob upload gear active. 05:32:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:32:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:32:06 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:32:06 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:32:06 INFO - (blobuploader) - INFO - Open directory for files ... 05:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:32:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:07 INFO - (blobuploader) - INFO - Done attempting. 05:32:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:32:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:32:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:08 INFO - (blobuploader) - INFO - Done attempting. 05:32:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:32:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:32:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:32:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:32:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:32:11 INFO - (blobuploader) - INFO - Done attempting. 05:32:11 INFO - (blobuploader) - INFO - Iteration through files over. 05:32:11 INFO - Return code: 0 05:32:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:32:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:32:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15493fc558714440ce9562896437b589874f060b5a085d1044a6d692fca9ca42c46d6b5dba0218ada09182f3959d1d052d599b90729567891ffef28794c6055d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84867afc8b086c5dce50db8930d729f3702991d7925472eda627d22c81d7a2b230f8953050d0b4e086b2adae6f75a0bdd1760811486608a91dca34f7d163aa2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ac7e0ab618c18b45afee227068ad01563aa3333fe74a6d77697776ac7e2b3639df3a7645dd27838e629b316462f9979ad6d748a9ab3fc01e81fc511e19bbc02"} 05:32:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:32:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:32:11 INFO - Contents: 05:32:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15493fc558714440ce9562896437b589874f060b5a085d1044a6d692fca9ca42c46d6b5dba0218ada09182f3959d1d052d599b90729567891ffef28794c6055d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84867afc8b086c5dce50db8930d729f3702991d7925472eda627d22c81d7a2b230f8953050d0b4e086b2adae6f75a0bdd1760811486608a91dca34f7d163aa2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ac7e0ab618c18b45afee227068ad01563aa3333fe74a6d77697776ac7e2b3639df3a7645dd27838e629b316462f9979ad6d748a9ab3fc01e81fc511e19bbc02"} 05:32:11 INFO - Running post-run listener: copy_logs_to_upload_dir 05:32:11 INFO - Copying logs to upload dir... 05:32:11 INFO - mkdir: /builds/slave/test/build/upload/logs 05:32:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.235971 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-13 05:32:11.252693) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:32:11.256040) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15493fc558714440ce9562896437b589874f060b5a085d1044a6d692fca9ca42c46d6b5dba0218ada09182f3959d1d052d599b90729567891ffef28794c6055d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84867afc8b086c5dce50db8930d729f3702991d7925472eda627d22c81d7a2b230f8953050d0b4e086b2adae6f75a0bdd1760811486608a91dca34f7d163aa2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ac7e0ab618c18b45afee227068ad01563aa3333fe74a6d77697776ac7e2b3639df3a7645dd27838e629b316462f9979ad6d748a9ab3fc01e81fc511e19bbc02"} build_url:https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011522 build_url: 'https://queue.taskcluster.net/v1/task/LjylYEbeQaeQwYKj5PLSuw/artifacts/public/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/15493fc558714440ce9562896437b589874f060b5a085d1044a6d692fca9ca42c46d6b5dba0218ada09182f3959d1d052d599b90729567891ffef28794c6055d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/84867afc8b086c5dce50db8930d729f3702991d7925472eda627d22c81d7a2b230f8953050d0b4e086b2adae6f75a0bdd1760811486608a91dca34f7d163aa2e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3ac7e0ab618c18b45afee227068ad01563aa3333fe74a6d77697776ac7e2b3639df3a7645dd27838e629b316462f9979ad6d748a9ab3fc01e81fc511e19bbc02"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 05:32:11.280947) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 05:32:11.281241) ========= 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=56c518bb7cbc29fb3c9463e2000001c4-1476360690.369788-1502697920 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 2016-10-13 05:32:11.365470) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:32:11.365782) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 05:32:11.366052) ========= ========= Total master_lag: 0.18 =========