builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1479957152.02 results: success (0) buildid: 20161123163004 builduid: 19a714f80e004617a5acbb2d8c653d8f revision: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.021563) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.022046) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.022344) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.038188) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.038521) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-23 19:12:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 88.1M=0s 2016-11-23 19:12:32 (88.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.080025 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.132462) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.132770) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019354 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.181822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:32.182177) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 --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=d6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-23 19:12:32,527 truncating revision to first 12 chars 2016-11-23 19:12:32,527 Setting DEBUG logging. 2016-11-23 19:12:32,527 attempt 1/10 2016-11-23 19:12:32,527 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/34fce7c12173?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-23 19:12:33,182 unpacking tar archive at: mozilla-central-34fce7c12173/testing/mozharness/ program finished with exit code 0 elapsedTime=0.980101 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:33.175490) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:33.175807) ========= script_repo_revision: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:33.176208) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:33.176472) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-23 19:12:33.184216) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-11-23 19:12:33.184510) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=d6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:12:33 INFO - MultiFileLogger online at 20161123 19:12:33 in /builds/slave/test 19:12:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:12:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:12:33 INFO - {'append_to_log': False, 19:12:33 INFO - 'base_work_dir': '/builds/slave/test', 19:12:33 INFO - 'blob_upload_branch': 'Firefox', 19:12:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:12:33 INFO - 'branch': 'Firefox', 19:12:33 INFO - 'buildbot_json_path': 'buildprops.json', 19:12:33 INFO - 'config_files': ('talos/linux_config.py',), 19:12:33 INFO - 'default_actions': ('clobber', 19:12:33 INFO - 'read-buildbot-config', 19:12:33 INFO - 'download-and-extract', 19:12:33 INFO - 'populate-webroot', 19:12:33 INFO - 'create-virtualenv', 19:12:33 INFO - 'install', 19:12:33 INFO - 'run-tests'), 19:12:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:12:33 INFO - 'download_minidump_stackwalk': True, 19:12:33 INFO - 'download_symbols': 'ondemand', 19:12:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:12:33 INFO - 'tooltool.py': '/tools/tooltool.py', 19:12:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:12:33 INFO - '/tools/misc-python/virtualenv.py')}, 19:12:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:12:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:12:33 INFO - 'installer_path': 'installer.exe', 19:12:33 INFO - 'log_level': 'info', 19:12:33 INFO - 'log_name': 'talos', 19:12:33 INFO - 'log_to_console': True, 19:12:33 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:12:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:12:33 INFO - 'opt_config_files': (), 19:12:33 INFO - 'pip_index': False, 19:12:33 INFO - 'sps_profile': False, 19:12:33 INFO - 'sps_profile_interval': 0, 19:12:33 INFO - 'suite': 'dromaeojs', 19:12:33 INFO - 'system_bits': '32', 19:12:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:12:33 INFO - 'title': 'talos-linux64-ix-034', 19:12:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:12:33 INFO - 'use_talos_json': True, 19:12:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:12:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:12:33 INFO - 'work_dir': 'build'} 19:12:33 INFO - [mozharness: 2016-11-24 03:12:33.626223Z] Running clobber step. 19:12:33 INFO - Running pre-action listener: _resource_record_pre_action 19:12:33 INFO - Running main action method: clobber 19:12:33 INFO - rmtree: /builds/slave/test/build 19:12:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:12:34 INFO - Running post-action listener: _resource_record_post_action 19:12:34 INFO - [mozharness: 2016-11-24 03:12:34.665239Z] Finished clobber step (success) 19:12:34 INFO - [mozharness: 2016-11-24 03:12:34.665339Z] Running read-buildbot-config step. 19:12:34 INFO - Running pre-action listener: _resource_record_pre_action 19:12:34 INFO - Running main action method: read_buildbot_config 19:12:34 INFO - Using buildbot properties: 19:12:34 INFO - { 19:12:34 INFO - "project": "", 19:12:34 INFO - "product": "firefox", 19:12:34 INFO - "script_repo_revision": "production", 19:12:34 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 19:12:34 INFO - "repository": "", 19:12:34 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 19:12:34 INFO - "buildid": "20161123163004", 19:12:34 INFO - "pgo_build": "True", 19:12:34 INFO - "basedir": "/builds/slave/test", 19:12:34 INFO - "buildnumber": 80, 19:12:34 INFO - "slavename": "talos-linux64-ix-034", 19:12:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:12:34 INFO - "platform": "ubuntu64_hw", 19:12:34 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 19:12:34 INFO - "branch": "mozilla-central", 19:12:34 INFO - "repo_path": "mozilla-central", 19:12:34 INFO - "revision": "34fce7c12173bdd6dda54c2ebf6d344252f1ac48", 19:12:34 INFO - "stage_platform": "linux64-pgo", 19:12:34 INFO - "builduid": "19a714f80e004617a5acbb2d8c653d8f", 19:12:34 INFO - "slavebuilddir": "test" 19:12:34 INFO - } 19:12:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 19:12:34 INFO - Running post-action listener: _resource_record_post_action 19:12:34 INFO - [mozharness: 2016-11-24 03:12:34.667735Z] Finished read-buildbot-config step (success) 19:12:34 INFO - [mozharness: 2016-11-24 03:12:34.667822Z] Running download-and-extract step. 19:12:34 INFO - Running pre-action listener: _resource_record_pre_action 19:12:34 INFO - Running main action method: download_and_extract 19:12:34 INFO - mkdir: /builds/slave/test/build/tests 19:12:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:34 INFO - trying https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:12:34 INFO - Downloading https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:12:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 19:12:35 INFO - Downloaded 1361 bytes. 19:12:35 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 19:12:35 INFO - Using the following test package requirements: 19:12:35 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:12:35 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:12:35 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:12:35 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'jsshell-linux-x86_64.zip'], 19:12:35 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:12:35 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 19:12:35 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:12:35 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:12:35 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:12:35 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 19:12:35 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:12:35 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:12:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:12:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:12:35 INFO - Fetch https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:12:35 INFO - Http code: 200 19:12:35 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 19:12:35 INFO - x-amz-cf-id: ScAysd93K6E2mir5HNcGEtpdKUAkTicPL3YhQB_OTwUNlCh29aiPUw== 19:12:35 INFO - x-amz-version-id: vhtMeFtHny.1NVs8O_jSeiNhHlecRSNZ 19:12:36 INFO - Expected file size: 21765717 19:12:36 INFO - Obtained file size: 21765717 19:12:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 19:12:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 19:12:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:12:40 INFO - Fetch https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 19:12:40 INFO - Http code: 200 19:12:40 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 19:12:40 INFO - x-amz-cf-id: UT2b7pHvW1Tq4lbrih2AXVVFPQoAuctJGO2AJCbza18iddUi9RtlnQ== 19:12:40 INFO - x-amz-version-id: vhtMeFtHny.1NVs8O_jSeiNhHlecRSNZ 19:12:40 INFO - Expected file size: 21765717 19:12:40 INFO - Obtained file size: 21765717 19:12:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 19:12:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:12:45 INFO - Fetch https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 19:12:45 INFO - Http code: 200 19:12:45 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 19:12:45 INFO - x-amz-cf-id: 1YFRxW2Dtdg8N2ibtV647J5ywJ25lV-t_ZgpK_UPpDkefa9U_EB99w== 19:12:45 INFO - x-amz-version-id: .jXs7JnNrY6Qq_ce0XMQbLFrd0OeQrQI 19:12:46 INFO - Expected file size: 14409749 19:12:46 INFO - Obtained file size: 14409749 19:12:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:46 INFO - trying https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:12:46 INFO - Downloading https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:12:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 19:12:47 INFO - Downloaded 59718034 bytes. 19:12:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:12:47 INFO - mkdir: /builds/slave/test/properties 19:12:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:12:47 INFO - Writing to file /builds/slave/test/properties/build_url 19:12:47 INFO - Contents: 19:12:47 INFO - build_url:https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 19:12:48 INFO - Running post-action listener: _resource_record_post_action 19:12:48 INFO - Running post-action listener: set_extra_try_arguments 19:12:48 INFO - [mozharness: 2016-11-24 03:12:48.133502Z] Finished download-and-extract step (success) 19:12:48 INFO - [mozharness: 2016-11-24 03:12:48.133728Z] Running populate-webroot step. 19:12:48 INFO - Running pre-action listener: _resource_record_pre_action 19:12:48 INFO - Running main action method: populate_webroot 19:12:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:12:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:12:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:12:48 INFO - '--tppagecycles', 19:12:48 INFO - '7'], 19:12:48 INFO - 'tests': ['tsvgm']}}, 19:12:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:12:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:12:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:12:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:12:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:12:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['damp', 'tps']}, 19:12:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'tests': ['damp', 'tps']}, 19:12:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['dromaeo_dom']}, 19:12:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:12:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['basic_compositor_video']}, 19:12:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:12:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['a11yr', 19:12:48 INFO - 'ts_paint', 19:12:48 INFO - 'tpaint', 19:12:48 INFO - 'sessionrestore', 19:12:48 INFO - 'sessionrestore_no_auto_restore', 19:12:48 INFO - 'tabpaint']}, 19:12:48 INFO - 'other-e10s': {'tests': ['a11yr', 19:12:48 INFO - 'ts_paint', 19:12:48 INFO - 'tpaint', 19:12:48 INFO - 'sessionrestore', 19:12:48 INFO - 'sessionrestore_no_auto_restore', 19:12:48 INFO - 'tabpaint']}, 19:12:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:12:48 INFO - 'ts_paint', 19:12:48 INFO - 'tpaint', 19:12:48 INFO - 'sessionrestore', 19:12:48 INFO - 'sessionrestore_no_auto_restore', 19:12:48 INFO - 'tabpaint']}, 19:12:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:12:48 INFO - 'ts_paint', 19:12:48 INFO - 'tpaint', 19:12:48 INFO - 'sessionrestore', 19:12:48 INFO - 'sessionrestore_no_auto_restore', 19:12:48 INFO - 'tabpaint']}, 19:12:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['a11yr', 19:12:48 INFO - 'ts_paint', 19:12:48 INFO - 'tpaint', 19:12:48 INFO - 'sessionrestore', 19:12:48 INFO - 'sessionrestore_no_auto_restore', 19:12:48 INFO - 'tabpaint']}, 19:12:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['a11yr', 19:12:48 INFO - 'ts_paint', 19:12:48 INFO - 'tpaint', 19:12:48 INFO - 'sessionrestore', 19:12:48 INFO - 'sessionrestore_no_auto_restore', 19:12:48 INFO - 'tabpaint']}, 19:12:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['tsvgx', 19:12:48 INFO - 'tsvgr_opacity', 19:12:48 INFO - 'tart', 19:12:48 INFO - 'tscrollx', 19:12:48 INFO - 'cart', 19:12:48 INFO - 'tsvg_static']}, 19:12:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:12:48 INFO - 'tsvgr_opacity', 19:12:48 INFO - 'tart', 19:12:48 INFO - 'tscrollx', 19:12:48 INFO - 'cart', 19:12:48 INFO - 'tsvg_static']}, 19:12:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'talos_options': ['--disable-e10s'], 19:12:48 INFO - 'tests': ['tp5o']}, 19:12:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:12:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'talos_options': ['--disable-e10s', 19:12:48 INFO - '--xperf_path', 19:12:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:48 INFO - 'tests': ['tp5n']}, 19:12:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:12:48 INFO - 'talos_options': ['--xperf_path', 19:12:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:12:48 INFO - 'tests': ['tp5n']}}, 19:12:48 INFO - 'talos.zip': {'path': '', 19:12:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:12:48 INFO - Running post-action listener: _resource_record_post_action 19:12:48 INFO - [mozharness: 2016-11-24 03:12:48.154756Z] Finished populate-webroot step (success) 19:12:48 INFO - [mozharness: 2016-11-24 03:12:48.154843Z] Running create-virtualenv step. 19:12:48 INFO - Running pre-action listener: _resource_record_pre_action 19:12:48 INFO - Running main action method: create_virtualenv 19:12:48 INFO - Creating virtualenv /builds/slave/test/build/venv 19:12:48 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 19:12:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:12:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:12:48 INFO - Using real prefix '/usr' 19:12:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:12:48 INFO - Installing distribute.............................................................................................................................................................................................done. 19:12:50 INFO - Installing pip.................done. 19:12:50 INFO - Return code: 0 19:12:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:12:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:12:50 INFO - Reading from file tmpfile_stdout 19:12:50 INFO - Output received: 19:12:50 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) 19:12:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:12:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:12:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:12:50 INFO - Using env: {'DISPLAY': ':0', 19:12:50 INFO - 'HOME': '/home/cltbld', 19:12:50 INFO - 'LANG': 'en_US.UTF-8', 19:12:50 INFO - 'LANGUAGE': 'en_US:en', 19:12:50 INFO - 'LOGNAME': 'cltbld', 19:12:50 INFO - 'MAIL': '/var/mail/cltbld', 19:12:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:50 INFO - 'MOZ_NO_REMOTE': '1', 19:12:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:50 INFO - 'NO_EM_RESTART': '1', 19:12:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:50 INFO - 'PWD': '/builds/slave/test', 19:12:50 INFO - 'SHELL': '/bin/bash', 19:12:50 INFO - 'SHLVL': '1', 19:12:50 INFO - 'TERM': 'linux', 19:12:50 INFO - 'TMOUT': '86400', 19:12:50 INFO - 'USER': 'cltbld', 19:12:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:12:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:50 INFO - '_': '/tools/buildbot/bin/python'} 19:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:51 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 19:12:51 INFO - Cleaning up... 19:12:51 INFO - Return code: 0 19:12:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:12:51 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')]} 19:12:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:51 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')]} 19:12:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:51 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:12:51 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 19:12:51 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 19:12:51 INFO - Using env: {'DISPLAY': ':0', 19:12:51 INFO - 'HOME': '/home/cltbld', 19:12:51 INFO - 'LANG': 'en_US.UTF-8', 19:12:51 INFO - 'LANGUAGE': 'en_US:en', 19:12:51 INFO - 'LOGNAME': 'cltbld', 19:12:51 INFO - 'MAIL': '/var/mail/cltbld', 19:12:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:51 INFO - 'MOZ_NO_REMOTE': '1', 19:12:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:51 INFO - 'NO_EM_RESTART': '1', 19:12:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:51 INFO - 'PWD': '/builds/slave/test', 19:12:51 INFO - 'SHELL': '/bin/bash', 19:12:51 INFO - 'SHLVL': '1', 19:12:51 INFO - 'TERM': 'linux', 19:12:51 INFO - 'TMOUT': '86400', 19:12:51 INFO - 'USER': 'cltbld', 19:12:51 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:12:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:51 INFO - '_': '/tools/buildbot/bin/python'} 19:12:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:51 INFO - Downloading/unpacking psutil>=3.1.1 19:12:51 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 19:12:51 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 19:12:51 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 19:12:51 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 19:12:51 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 19:12:51 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 19:12:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:12:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:53 INFO - Installing collected packages: psutil 19:12:53 INFO - Running setup.py install for psutil 19:12:53 INFO - building 'psutil._psutil_linux' extension 19:12:53 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 19:12:53 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 19:12:53 INFO - building 'psutil._psutil_posix' extension 19:12:53 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 19:12:53 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 19:12:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:12:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:12:53 INFO - Successfully installed psutil 19:12:53 INFO - Cleaning up... 19:12:54 INFO - Return code: 0 19:12:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:12: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')]} 19:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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')]} 19:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:12: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:12: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 mozsystemmonitor==0.3 19:12:54 INFO - Using env: {'DISPLAY': ':0', 19:12:54 INFO - 'HOME': '/home/cltbld', 19:12:54 INFO - 'LANG': 'en_US.UTF-8', 19:12:54 INFO - 'LANGUAGE': 'en_US:en', 19:12:54 INFO - 'LOGNAME': 'cltbld', 19:12:54 INFO - 'MAIL': '/var/mail/cltbld', 19:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:54 INFO - 'MOZ_NO_REMOTE': '1', 19:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:54 INFO - 'NO_EM_RESTART': '1', 19:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:54 INFO - 'PWD': '/builds/slave/test', 19:12:54 INFO - 'SHELL': '/bin/bash', 19:12:54 INFO - 'SHLVL': '1', 19:12:54 INFO - 'TERM': 'linux', 19:12:54 INFO - 'TMOUT': '86400', 19:12:54 INFO - 'USER': 'cltbld', 19:12:54 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:54 INFO - '_': '/tools/buildbot/bin/python'} 19:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:12:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:12:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:12:56 INFO - Installing collected packages: mozsystemmonitor 19:12:56 INFO - Running setup.py install for mozsystemmonitor 19:12:56 INFO - Successfully installed mozsystemmonitor 19:12:56 INFO - Cleaning up... 19:12:56 INFO - Return code: 0 19:12:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:12:56 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')]} 19:12:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:56 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')]} 19:12:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:56 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:12:56 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 19:12:56 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 19:12:56 INFO - Using env: {'DISPLAY': ':0', 19:12:56 INFO - 'HOME': '/home/cltbld', 19:12:56 INFO - 'LANG': 'en_US.UTF-8', 19:12:56 INFO - 'LANGUAGE': 'en_US:en', 19:12:56 INFO - 'LOGNAME': 'cltbld', 19:12:56 INFO - 'MAIL': '/var/mail/cltbld', 19:12:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:56 INFO - 'MOZ_NO_REMOTE': '1', 19:12:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:56 INFO - 'NO_EM_RESTART': '1', 19:12:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:56 INFO - 'PWD': '/builds/slave/test', 19:12:56 INFO - 'SHELL': '/bin/bash', 19:12:56 INFO - 'SHLVL': '1', 19:12:56 INFO - 'TERM': 'linux', 19:12:56 INFO - 'TMOUT': '86400', 19:12:56 INFO - 'USER': 'cltbld', 19:12:56 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:12:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:56 INFO - '_': '/tools/buildbot/bin/python'} 19:12:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:56 INFO - Downloading/unpacking jsonschema==2.5.1 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12:58 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:12:58 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:12:58 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 19:12:58 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 19:12:58 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 19:12:58 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 19:12:58 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 19:12:58 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 19:12:58 INFO - Downloading functools32-3.2.3-2.tar.gz 19:12:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:12:58 INFO - warning: no files found matching '*.txt' 19:12:58 INFO - no previously-included directories found matching 'build' 19:12:58 INFO - no previously-included directories found matching 'dist' 19:12:58 INFO - no previously-included directories found matching '.git*' 19:12:58 INFO - Installing collected packages: jsonschema, functools32 19:12:58 INFO - Running setup.py install for functools32 19:12:59 INFO - warning: no files found matching '*.txt' 19:12:59 INFO - no previously-included directories found matching 'build' 19:12:59 INFO - no previously-included directories found matching 'dist' 19:12:59 INFO - no previously-included directories found matching '.git*' 19:12:59 INFO - Successfully installed jsonschema functools32 19:12:59 INFO - Cleaning up... 19:12:59 INFO - Return code: 0 19:12:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:12:59 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')]} 19:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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')]} 19:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:12:59 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 19:12:59 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 19:12:59 INFO - Using env: {'DISPLAY': ':0', 19:12:59 INFO - 'HOME': '/home/cltbld', 19:12:59 INFO - 'LANG': 'en_US.UTF-8', 19:12:59 INFO - 'LANGUAGE': 'en_US:en', 19:12:59 INFO - 'LOGNAME': 'cltbld', 19:12:59 INFO - 'MAIL': '/var/mail/cltbld', 19:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:59 INFO - 'MOZ_NO_REMOTE': '1', 19:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:59 INFO - 'NO_EM_RESTART': '1', 19:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:59 INFO - 'PWD': '/builds/slave/test', 19:12:59 INFO - 'SHELL': '/bin/bash', 19:12:59 INFO - 'SHLVL': '1', 19:12:59 INFO - 'TERM': 'linux', 19:12:59 INFO - 'TMOUT': '86400', 19:12:59 INFO - 'USER': 'cltbld', 19:12:59 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:59 INFO - '_': '/tools/buildbot/bin/python'} 19:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:12:59 INFO - Cleaning up... 19:12:59 INFO - Return code: 0 19:12:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:12:59 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')]} 19:12:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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')]} 19:12:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:12:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:12:59 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:12:59 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 19:12:59 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 19:12:59 INFO - Using env: {'DISPLAY': ':0', 19:12:59 INFO - 'HOME': '/home/cltbld', 19:12:59 INFO - 'LANG': 'en_US.UTF-8', 19:12:59 INFO - 'LANGUAGE': 'en_US:en', 19:12:59 INFO - 'LOGNAME': 'cltbld', 19:12:59 INFO - 'MAIL': '/var/mail/cltbld', 19:12:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:12:59 INFO - 'MOZ_NO_REMOTE': '1', 19:12:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:12:59 INFO - 'NO_EM_RESTART': '1', 19:12:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:12:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:12:59 INFO - 'PWD': '/builds/slave/test', 19:12:59 INFO - 'SHELL': '/bin/bash', 19:12:59 INFO - 'SHLVL': '1', 19:12:59 INFO - 'TERM': 'linux', 19:12:59 INFO - 'TMOUT': '86400', 19:12:59 INFO - 'USER': 'cltbld', 19:12:59 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:12:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:12:59 INFO - '_': '/tools/buildbot/bin/python'} 19:12:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:12:59 INFO - Downloading/unpacking blobuploader==1.2.4 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:12: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 19:13:01 INFO - Downloading blobuploader-1.2.4.tar.gz 19:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:13:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:13:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:02 INFO - Downloading docopt-0.6.1.tar.gz 19:13:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:13:02 INFO - Installing collected packages: blobuploader, requests, docopt 19:13:02 INFO - Running setup.py install for blobuploader 19:13:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:13:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:13:02 INFO - Running setup.py install for requests 19:13:02 INFO - Running setup.py install for docopt 19:13:02 INFO - Successfully installed blobuploader requests docopt 19:13:02 INFO - Cleaning up... 19:13:02 INFO - Return code: 0 19:13:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:02 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:13:02 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 19:13:02 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 19:13:02 INFO - Using env: {'DISPLAY': ':0', 19:13:02 INFO - 'HOME': '/home/cltbld', 19:13:02 INFO - 'LANG': 'en_US.UTF-8', 19:13:02 INFO - 'LANGUAGE': 'en_US:en', 19:13:02 INFO - 'LOGNAME': 'cltbld', 19:13:02 INFO - 'MAIL': '/var/mail/cltbld', 19:13:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:02 INFO - 'MOZ_NO_REMOTE': '1', 19:13:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:02 INFO - 'NO_EM_RESTART': '1', 19:13:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:02 INFO - 'PWD': '/builds/slave/test', 19:13:02 INFO - 'SHELL': '/bin/bash', 19:13:02 INFO - 'SHLVL': '1', 19:13:02 INFO - 'TERM': 'linux', 19:13:02 INFO - 'TMOUT': '86400', 19:13:02 INFO - 'USER': 'cltbld', 19:13:02 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:13:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:02 INFO - '_': '/tools/buildbot/bin/python'} 19:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:13:03 INFO - Running setup.py (path:/tmp/pip-7TpiVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:13:03 INFO - Running setup.py (path:/tmp/pip-1WlQ2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:13:03 INFO - Running setup.py (path:/tmp/pip-2qdziF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:13:03 INFO - Running setup.py (path:/tmp/pip-6Nq7Ql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:13:03 INFO - Running setup.py (path:/tmp/pip-k3mKBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:13:03 INFO - Running setup.py (path:/tmp/pip-GWq20F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:13:03 INFO - Running setup.py (path:/tmp/pip-RuIlp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:13:03 INFO - Running setup.py (path:/tmp/pip-z2553R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:13:03 INFO - Running setup.py (path:/tmp/pip-wedSqF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:13:03 INFO - Running setup.py (path:/tmp/pip-XRGGlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:13:03 INFO - Running setup.py (path:/tmp/pip-xj1Ibr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:13:03 INFO - Running setup.py (path:/tmp/pip-kCeAHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:13:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:13:03 INFO - Running setup.py (path:/tmp/pip-h4MuBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:13:04 INFO - Running setup.py (path:/tmp/pip-SgN5kT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:04 INFO - Running setup.py (path:/tmp/pip-j66UmP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:13:04 INFO - Running setup.py (path:/tmp/pip-iAtYe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:13:04 INFO - Running setup.py (path:/tmp/pip-5LbmRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:13:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:13:04 INFO - Running setup.py install for manifestparser 19:13:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:13:04 INFO - Running setup.py install for mozcrash 19:13:04 INFO - Running setup.py install for mozdebug 19:13:04 INFO - Running setup.py install for mozdevice 19:13:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:13:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:13:04 INFO - Running setup.py install for mozfile 19:13:04 INFO - Running setup.py install for mozhttpd 19:13:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:13:04 INFO - Running setup.py install for mozinfo 19:13:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Running setup.py install for mozInstall 19:13:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Running setup.py install for mozleak 19:13:05 INFO - Running setup.py install for mozlog 19:13:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Running setup.py install for moznetwork 19:13:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Running setup.py install for mozprocess 19:13:05 INFO - Running setup.py install for mozprofile 19:13:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Running setup.py install for mozrunner 19:13:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:13:05 INFO - Running setup.py install for mozscreenshot 19:13:05 INFO - Running setup.py install for moztest 19:13:05 INFO - Running setup.py install for mozversion 19:13:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:13:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:13:06 INFO - Cleaning up... 19:13:06 INFO - Return code: 0 19:13:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:06 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:13:06 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 19:13:06 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 19:13:06 INFO - Using env: {'DISPLAY': ':0', 19:13:06 INFO - 'HOME': '/home/cltbld', 19:13:06 INFO - 'LANG': 'en_US.UTF-8', 19:13:06 INFO - 'LANGUAGE': 'en_US:en', 19:13:06 INFO - 'LOGNAME': 'cltbld', 19:13:06 INFO - 'MAIL': '/var/mail/cltbld', 19:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:06 INFO - 'MOZ_NO_REMOTE': '1', 19:13:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:06 INFO - 'NO_EM_RESTART': '1', 19:13:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:06 INFO - 'PWD': '/builds/slave/test', 19:13:06 INFO - 'SHELL': '/bin/bash', 19:13:06 INFO - 'SHLVL': '1', 19:13:06 INFO - 'TERM': 'linux', 19:13:06 INFO - 'TMOUT': '86400', 19:13:06 INFO - 'USER': 'cltbld', 19:13:06 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:06 INFO - '_': '/tools/buildbot/bin/python'} 19:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:13:06 INFO - Running setup.py (path:/tmp/pip-cbtMzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:13:06 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)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:13:06 INFO - Running setup.py (path:/tmp/pip-LMIEs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:13:06 INFO - Running setup.py (path:/tmp/pip-UzYpNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:13:06 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)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:13:06 INFO - Running setup.py (path:/tmp/pip-t3mPRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:13:06 INFO - Running setup.py (path:/tmp/pip-m5n4qT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:13:06 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)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:13:06 INFO - Running setup.py (path:/tmp/pip-XUjfjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:13:06 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)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:13:06 INFO - Running setup.py (path:/tmp/pip-N9SLaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:13:06 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)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:13:06 INFO - Running setup.py (path:/tmp/pip-i69XV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:13:06 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)) 19:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:13:06 INFO - Running setup.py (path:/tmp/pip-jPM15s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:13:07 INFO - Running setup.py (path:/tmp/pip-977ud_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:13:07 INFO - Running setup.py (path:/tmp/pip-fxsanO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:13:07 INFO - Running setup.py (path:/tmp/pip-6Hmrif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:13:07 INFO - Running setup.py (path:/tmp/pip-qHEvTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:13:07 INFO - Running setup.py (path:/tmp/pip-jPFDFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:07 INFO - Running setup.py (path:/tmp/pip-ZKsVj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:13:07 INFO - Running setup.py (path:/tmp/pip-Qh7nhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:13:07 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)) 19:13:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:13:07 INFO - Running setup.py (path:/tmp/pip-sj0v2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:13:07 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)) 19:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:13:07 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)) 19:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:13:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:13:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:09 INFO - Downloading blessings-1.6.tar.gz 19:13:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:13:09 INFO - Installing collected packages: blessings 19:13:09 INFO - Running setup.py install for blessings 19:13:09 INFO - Successfully installed blessings 19:13:09 INFO - Cleaning up... 19:13:09 INFO - Return code: 0 19:13:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:13:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:13:10 INFO - Reading from file tmpfile_stdout 19:13:10 INFO - Current package versions: 19:13:10 INFO - argparse == 1.2.1 19:13:10 INFO - blessings == 1.6 19:13:10 INFO - blobuploader == 1.2.4 19:13:10 INFO - docopt == 0.6.1 19:13:10 INFO - functools32 == 3.2.3-2 19:13:10 INFO - jsonschema == 2.5.1 19:13:10 INFO - manifestparser == 1.1 19:13:10 INFO - mozInstall == 1.12 19:13:10 INFO - mozcrash == 1.0 19:13:10 INFO - mozdebug == 0.1 19:13:10 INFO - mozdevice == 0.49 19:13:10 INFO - mozfile == 1.2 19:13:10 INFO - mozhttpd == 0.7 19:13:10 INFO - mozinfo == 0.9 19:13:10 INFO - mozleak == 0.1 19:13:10 INFO - mozlog == 3.4 19:13:10 INFO - moznetwork == 0.27 19:13:10 INFO - mozprocess == 0.23 19:13:10 INFO - mozprofile == 0.28 19:13:10 INFO - mozrunner == 6.13 19:13:10 INFO - mozscreenshot == 0.1 19:13:10 INFO - mozsystemmonitor == 0.3 19:13:10 INFO - moztest == 0.7 19:13:10 INFO - mozversion == 1.4 19:13:10 INFO - psutil == 3.1.1 19:13:10 INFO - requests == 1.2.3 19:13:10 INFO - wsgiref == 0.1.2 19:13:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:10 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 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:13:10 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 19:13:10 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 19:13:10 INFO - Using env: {'DISPLAY': ':0', 19:13:10 INFO - 'HOME': '/home/cltbld', 19:13:10 INFO - 'LANG': 'en_US.UTF-8', 19:13:10 INFO - 'LANGUAGE': 'en_US:en', 19:13:10 INFO - 'LOGNAME': 'cltbld', 19:13:10 INFO - 'MAIL': '/var/mail/cltbld', 19:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:10 INFO - 'MOZ_NO_REMOTE': '1', 19:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:10 INFO - 'NO_EM_RESTART': '1', 19:13:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:10 INFO - 'PWD': '/builds/slave/test', 19:13:10 INFO - 'SHELL': '/bin/bash', 19:13:10 INFO - 'SHLVL': '1', 19:13:10 INFO - 'TERM': 'linux', 19:13:10 INFO - 'TMOUT': '86400', 19:13:10 INFO - 'USER': 'cltbld', 19:13:10 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:10 INFO - '_': '/tools/buildbot/bin/python'} 19:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 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)) 19:13:10 INFO - Cleaning up... 19:13:10 INFO - Return code: 0 19:13:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f44c2905e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2811b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29ad130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', '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 19:13:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:13:10 INFO - Using env: {'DISPLAY': ':0', 19:13:10 INFO - 'HOME': '/home/cltbld', 19:13:10 INFO - 'LANG': 'en_US.UTF-8', 19:13:10 INFO - 'LANGUAGE': 'en_US:en', 19:13:10 INFO - 'LOGNAME': 'cltbld', 19:13:10 INFO - 'MAIL': '/var/mail/cltbld', 19:13:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:10 INFO - 'MOZ_NO_REMOTE': '1', 19:13:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:10 INFO - 'NO_EM_RESTART': '1', 19:13:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:10 INFO - 'PWD': '/builds/slave/test', 19:13:10 INFO - 'SHELL': '/bin/bash', 19:13:10 INFO - 'SHLVL': '1', 19:13:10 INFO - 'TERM': 'linux', 19:13:10 INFO - 'TMOUT': '86400', 19:13:10 INFO - 'USER': 'cltbld', 19:13:10 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:13:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:10 INFO - '_': '/tools/buildbot/bin/python'} 19:13:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:13:10 INFO - Cleaning up... 19:13:10 INFO - Return code: 0 19:13:10 INFO - Running post-action listener: _resource_record_post_action 19:13:10 INFO - Running post-action listener: _start_resource_monitoring 19:13:10 INFO - Starting resource monitoring. 19:13:10 INFO - [mozharness: 2016-11-24 03:13:10.567613Z] Finished create-virtualenv step (success) 19:13:10 INFO - [mozharness: 2016-11-24 03:13:10.568097Z] Running install step. 19:13:10 INFO - Running pre-action listener: _resource_record_pre_action 19:13:10 INFO - Running main action method: install 19:13:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:13:10 INFO - Reading from file tmpfile_stdout 19:13:10 INFO - Detecting whether we're running mozinstall >=1.0... 19:13:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:13:10 INFO - Reading from file tmpfile_stdout 19:13:10 INFO - Output received: 19:13:10 INFO - Usage: mozinstall [options] installer 19:13:10 INFO - Options: 19:13:10 INFO - -h, --help show this help message and exit 19:13:10 INFO - -d DEST, --destination=DEST 19:13:10 INFO - Directory to install application into. [default: 19:13:10 INFO - "/builds/slave/test"] 19:13:10 INFO - --app=APP Application being installed. [default: firefox] 19:13:10 INFO - mkdir: /builds/slave/test/build/application 19:13:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:13:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:13:27 INFO - Reading from file tmpfile_stdout 19:13:27 INFO - Output received: 19:13:27 INFO - /builds/slave/test/build/application/firefox/firefox 19:13:27 INFO - Running post-action listener: _resource_record_post_action 19:13:27 INFO - [mozharness: 2016-11-24 03:13:27.792485Z] Finished install step (success) 19:13:27 INFO - [mozharness: 2016-11-24 03:13:27.792728Z] Running run-tests step. 19:13:27 INFO - Running pre-action listener: _resource_record_pre_action 19:13:27 INFO - Running main action method: run_tests 19:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:13:27 INFO - Python 2.7.3 19:13:27 INFO - Return code: 0 19:13:27 INFO - grabbing minidump binary from tooltool 19:13:27 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')]} 19:13:27 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 0x2a3bfc0>, '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 0x2a3cec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a3d350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:13:27 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 19:13:27 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 19:13:27 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 19:13:27 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:13:27 INFO - Return code: 0 19:13:27 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:13:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:13:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:13:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:13:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:13:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 19:13:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --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 19:13:27 INFO - Using env: {'DISPLAY': ':0', 19:13:27 INFO - 'HOME': '/home/cltbld', 19:13:27 INFO - 'LANG': 'en_US.UTF-8', 19:13:27 INFO - 'LANGUAGE': 'en_US:en', 19:13:27 INFO - 'LOGNAME': 'cltbld', 19:13:27 INFO - 'MAIL': '/var/mail/cltbld', 19:13:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:13:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:13:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:27 INFO - 'MOZ_NO_REMOTE': '1', 19:13:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:13:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:13:27 INFO - 'NO_EM_RESTART': '1', 19:13:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:13:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:27 INFO - 'PWD': '/builds/slave/test', 19:13:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:13:27 INFO - 'SHELL': '/bin/bash', 19:13:27 INFO - 'SHLVL': '1', 19:13:27 INFO - 'TERM': 'linux', 19:13:27 INFO - 'TMOUT': '86400', 19:13:27 INFO - 'USER': 'cltbld', 19:13:27 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281', 19:13:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:27 INFO - '_': '/tools/buildbot/bin/python'} 19:13:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--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 19:13:28 INFO - mozversion application_buildid: 20161123163004 19:13:28 INFO - mozversion application_changeset: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 19:13:28 INFO - mozversion application_display_name: Nightly 19:13:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:13:28 INFO - mozversion application_name: Firefox 19:13:28 INFO - mozversion application_remotingname: firefox 19:13:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:13:28 INFO - mozversion application_vendor: Mozilla 19:13:28 INFO - mozversion application_version: 53.0a1 19:13:28 INFO - mozversion platform_buildid: 20161123163004 19:13:28 INFO - mozversion platform_changeset: 34fce7c12173bdd6dda54c2ebf6d344252f1ac48 19:13:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:13:28 INFO - mozversion platform_version: 53.0a1 19:13:28 INFO - using testdate: 1479957208 19:13:28 INFO - actual date: 1479957208 19:13:28 INFO - starting webserver on 'localhost:43759' 19:13:28 INFO - SUITE-START | Running 2 tests 19:13:28 INFO - TEST-START | dromaeo_css 19:13:28 INFO - operating with platform_type : linux_ 19:13:28 INFO - Initialising browser for dromaeo_css test... 19:13:28 INFO - TEST-INFO | started process 12910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaO54Y5/profile http://localhost:43759/getInfo.html) 19:13:32 INFO - PROCESS | 12910 | __metrics Screen width/height:1600/1200 19:13:32 INFO - PROCESS | 12910 | colorDepth:24 19:13:32 INFO - PROCESS | 12910 | Browser inner width/height: 1024/697 19:13:32 INFO - PROCESS | 12910 | __metrics 19:13:32 INFO - PROCESS | 12910 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:13:32 INFO - PROCESS | 12910 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:13:33 INFO - PROCESS | 12910 | console.error: PushService: 19:13:33 INFO - PROCESS | 12910 | stateChangeProcessEnqueue: Error transitioning state 19:13:33 INFO - PROCESS | 12910 | UnknownError 19:13:34 INFO - TEST-INFO | 12910: exit 0 19:13:34 INFO - Browser initialized. 19:13:34 INFO - Running cycle 1/1 for dromaeo_css test... 19:13:34 INFO - TEST-INFO | started process 12995 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpaO54Y5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:46 INFO - PROCESS | 12995 | RSS: Main: 172855296 19:13:46 INFO - PROCESS | 12995 | 19:14:36 INFO - PROCESS | 12995 | 1479957276941 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] 19:16:48 INFO - PROCESS | 12995 | Cycle 1(1): loaded http://localhost:43759/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43759/tests/dromaeo/cssquery-ext.html) 19:16:50 INFO - PROCESS | 12995 | RSS: Main: 324005888 19:16:50 INFO - PROCESS | 12995 | 19:19:52 INFO - PROCESS | 12995 | Cycle 1(1): loaded http://localhost:43759/tests/dromaeo/cssquery-ext.html (next: http://localhost:43759/tests/dromaeo/cssquery-jquery.html) 19:19:53 INFO - PROCESS | 12995 | RSS: Main: 282927104 19:19:53 INFO - PROCESS | 12995 | 19:22:55 INFO - PROCESS | 12995 | Cycle 1(1): loaded http://localhost:43759/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43759/tests/dromaeo/cssquery-mootools.html) 19:22:55 INFO - PROCESS | 12995 | RSS: Main: 263208960 19:22:55 INFO - PROCESS | 12995 | 19:25:58 INFO - PROCESS | 12995 | Cycle 1(1): loaded http://localhost:43759/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43759/tests/dromaeo/cssquery-prototype.html) 19:25:58 INFO - PROCESS | 12995 | RSS: Main: 219590656 19:25:58 INFO - PROCESS | 12995 | 19:29:01 INFO - PROCESS | 12995 | Cycle 1(1): loaded http://localhost:43759/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43759/tests/dromaeo/cssquery-yui.html) 19:29:02 INFO - PROCESS | 12995 | RSS: Main: 219832320 19:29:02 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | Cycle 1(1): loaded http://localhost:43759/tests/dromaeo/cssquery-yui.html (next: http://localhost:43759/tests/dromaeo/cssquery-dojo.html) 19:32:05 INFO - PROCESS | 12995 | RSS: Main: 315707392 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | __start_tp_report 19:32:05 INFO - PROCESS | 12995 | _x_x_mozilla_page_load 19:32:05 INFO - PROCESS | 12995 | _x_x_mozilla_page_load_details 19:32:05 INFO - PROCESS | 12995 | |i|pagename|runs| 19:32:05 INFO - PROCESS | 12995 | |0;dojo.html;3047.75617950564;3066.2640966168115;3073.8463076846165;3101.720845123941;3113.2995076107877;5267.341582302212;5330.173823057427;5340.62615616907;5397.163439666858;5397.352317721874;5384.757685904134;5385.057614917393;5389.299391079154;5400.540954018913;5429.701366424845;4897.3388592539995;4936.925946110811;4945.480724523927;4976.0794253063295;4999.700017998932;4914.852554423373;4944.134776414131;4950.331705219804;4969.329140566031;5017.724025178613;5032.4212715537815;5035.043341765053;5040.59675225981;5057.620678449112;5080.974595127038;702.7670619612326;5044.772985215674;5061.063703214917;5063.518965698253;5120.052790233819;3937.4093885917055;4849.442314133885;4849.75751212438;4904.485029071968;4992.750362481861;5346.117890548055;5456.236126942231;5458.372287186985;5462.754176062087;5484.91772623411;5730.885382292373;5799.304083509964;5805.332386775491;5812.796552120655;5866.14940833581;5683.914741278884;5715.571332150063;5716.542676585863;5766.336871259817;5794.101914203306;5409.5672346212605;5417.51242388187;5447.972760136253;5465.863353416156;5470.042742520043;5975.073863551156;5980.37206093354;5986.850328741729;5988.790392336247;5990.041593345044;5587.497125258747;5631.605787594829;5631.71841407928;5706.144078388275;5726.713664316768;5749.1663708762835;5780.739866706008;5834.299884013861;5883.17635531026;5883.411658834082;5761.5102716269585;5771.220885180531;5774.566907481955;5774.62464939786;5786.913196302059;5720.914186287209;5756.453136951983;5830.533557315404;5831.329397119385;5838.649681019152;4419.867403977886;4421.270490369053;4433.756143412079;4475.530069342737;4492.910141797146;3944.7041471889725;3953.2884080865147;3957.465742124834;3993.860214892523;3996.7402118862183;5491.835244942658;5531.419200983839;5539.723013849291;5546.528545073714;5560.082586373203;5640.266765320482;5680.687562184037;5682.7442765075675;5700.686462244616;5767.106098554731;5437.157240627709;5443.700596467153;5444.700541470178;5445.237666726662;5449.482299181572;4889.902201955941;4923.704577725348;4930.5069493050405;4933.9506604933495;4935.358403407534;5951.821445356646;5953.791617293374;5954.255718035246;5965.7613695452565;5971.223740913653;5757.021306378009;5762.394948530344;5762.884742305214;5768.913466297925;5781.306243250837;5997.490213331916;6018.759249629966;6019.819405417845;6020.066889632114;6023.728932198149;5637.915431268452;5649.802256921153;5664.207011018379;5708.686022268815;5746.942530574808;5860.619059761102;5863.2670916135485;5870.0607902735355;5870.236869206976;5875.265591801025;5570.136628822539;5610.803621873215;5672.064109422065;5722.971385143048;5724.570657200643;5656.915146272891;5722.62802917809;5734.7132643368495;5767.221425107725;5776.075827867521;5709.942900570941;5713.514351280188;5715.8571035724435;5719.628224165416;5720.08478643415;5598.524125449382;5632.464915832989;5768.278965129359;5795.710214489343;5806.68063256525;5609.27079479665;5618.381977982337;5636.323641163087;5758.740856661377;5760.596758226884 19:32:05 INFO - PROCESS | 12995 | |1;ext.html;542.5359360143186;591.7485068845741;591.9640628899426;602.860593478326;603.387561624951;31336.21659458515;40254.39618405722;40904;41004.79497602508;44922.101557968905;42200;42565.36151957723;42592.78703606485;42597.574024259746;42641.360379594335;7949.40379471539;8612.09572994836;8761.693340733076;8763.605637746285;8766.386352955296;7784.221577842199;9988.200943924468;10242.487875606228;10244.180465562775;10560.630377936774;10457.058864702176;10503.264771466;10513.52689128991;10522.526486308134;10526.210534209926;10402;10442.843357349646;10452.895471045269;10463.581456741722;10469.31949423285;10293.639722609747;10331.32846364984;10358.844617330746;10366.792664146675;10366.844497332546;40034.399484007765;40399.39400908989;40569.391459128135;40697.18605627871;40709.389359159635;3355.228297491566;3376.2403459221723;3403.829808509565;3438.346714124333;3500.4049311617086;28260.43479130406;30917.22706932322;30994.845025774954;31040;31046.379072418425;4102.343625019982;4144.41978123063;4231.788410579489;4272.102858399709;4421.292593185075;354953;355681;356246.3753624643;358121;359151;57738.71130644404;58077;58181.709091455115;58194.7090264546;58213.4178658208;60831.69584152051;62143.6892815533;62357.68821155956;62496.68751656303;62516.68741656263;15673.294701738449;16405.343786248653;16417.42539011129;16421.178941052898;16428.342866285453;11550.480228389742;11802.704932376759;12356.752864942651;12388;12538.310392928293;18554.257829686932;18920.24319027251;19415.90292048559;19619.509512262306;19632.214711411383;7241.493095483265;7353.860151676498;7366.447516436288;7371.410287177013;7465.552066876005;900.6397441023643;986.5757724178673;1014.9391036537831;1026.5431882812077;1052.5684469367523;30767.69232307693;31330;31383.686163138078;31555.526667100014;31585.684143158272;1081.6538707613645;1092.3882625729616;1098.9120870330355;1111.994440027811;1120.4845770945294;15977.680446391007;16503.422380216874;16675.49973500797;16684.49946501607;16953.23710433009;573262;577855;578051;578094;580246;55401;55693;55770.72114639482;55836;55844.72077639667;1231.846019247594;1267.3219827392118;1286.3311078239262;1342.5636668082716;1350.4800651748951;28078.719212808428;31797.523037154;31836.204094897814;32132.518012229368;32929;47316.52683473259;47587.52412475831;47696;47720.2841957364;47872.281915770596;324649;325638;325695;326236;326343;27693;28244.435111297247;28379.716202837706;29873.402531948803;40743.388849166695;45388.77305613451;45474.77262613798;45591;46206.53793462157;46781.2982805265;38655;38680;38950.41574376443;39935;40340.1931961365;38445.807770962085;38861.6113838858;39540;39708.801455992536;40948.79525602353;39484.21031579295;39635.80182099071;40477.595224047385;40723;40868.79565602153;32118.67881321157;35250.823745881105;35488.46767298441;36003;36350.8182459086;27088;27286.863565682044;27552.44895102127;27638.44723105567;27711.83826967643 19:32:05 INFO - PROCESS | 12995 | |2;jquery.html;363.1520399866312;390.56256992168755;401.83323920572957;402.13942163769536;415.1587708512746;13249.536266230683;15200.54398368048;16030.43893463729;18965.715514267264;19330.806691933078;18553;18656.62686746264;19129.52176195596;19278.51803704908;19282.228710851585;5312.096943519592;5537.695426751527;5542.584306177033;5544.99999999998;5546.889062218753;5225.555827754645;5557.861053473675;5565.833025009236;5566.330100969713;5567.691592475765;5486.945130548684;5490.56075513958;5490.780368785244;5491.176323551459;5495.807646732345;5415.268938693285;5693.857653558653;5722.484976352128;5792.536597072224;5803.129530570406;5441.401445840955;5610.186522954149;5618.634788738719;5620.606557540974;5621.437856214387;13925.651858703613;14149.08030977983;14174.43302267908;14206.07660502064;19109.9044504778;2104.715863358442;2291.415688999311;2312.814974802012;2327.8719670418122;2389.2712722619594;10015.44915029673;10238.436885971269;10287.691369258859;10510.894891051144;10671.092957098655;1704.4886534039863;1786.6248087901556;1791.4894255137353;1871.1205733305326;2030.4010316956385;78275.60862195652;86424.13575864224;86559.56720216421;88751.55624221903;89536;10507.264491485676;11054;11112.666200139925;11161.967960185193;11357;15887.205639717968;15888.205589720468;15899.761503577456;15914.52256432309;15940.601484962968;7999.760007199793;8101.473404228706;8197.918020819834;8253.33973282136;8281.130481299497;7594;7665.731699390606;7693.1922148173635;7711.0746710395115;7869.121144890205;4684.74233917131;4833.32333473314;4865.756712164378;4935.161022626162;4992.803166606682;1948.1720268885667;1968.1241847378067;1977.4166620846884;1980.6823570519323;2016.2338311441558;736.8415790605046;885.1032130324821;887.3034667785757;912.9634814607474;975.6438899801664;17533.298668053136;19438.222471101;19452.693042182862;19518.902405487883;19601.90199049024;971.2909576009553;992.731962370156;995.8406654935176;997.8602995580625;1017.8269694152012;10195.847062294071;10474;10684.679459616224;10725.088367488695;10742.838857417146;430812;444698;444767;445315;445684;82300;91252.5437372809;91335;91858.54070729604;93337;985.8126955878361;995.7162208770321;997.2670087485692;1013.5439052426437;1013.9239557033252;16316.347346106022;16548.17259137014;16673.49979500617;17208.483745487654;17497.82502175013;40853.38719919263;41313.79343103265;41792;42287.365689515296;43393.13213735771;56867.30362526423;56897.43102569087;58122.7093864528;58828.70585647044;60369.698151508965;6618.1065556151225;8002.559859207567;8192.918070819216;8667.739967800977;8679.522626255364;11628.88371116278;12446.37768111609;12730.23618582888;13110.082294239312;13301.534446294334;10902;11050.960985728989;11192.608258710907;11205.60780372683;11954.163208575665;11180.552777888794;11214.15893807984;11305.7738845221;11692.77382814818;11815.76368472643;11255.606053788078;11428.885711143115;11631.418429078682;11717;11822.290662560274;7486.5508069516;8552.315814734931;8553.187447192508;8611.870821937551;8950.910490895008;9668.903310967082;11041.668749937515;11224.66326010221;11707.2975621463;11800.940995295312 19:32:05 INFO - PROCESS | 12995 | |3;mootools.html;1411.0827961824816;1460.6859525202083;1560.9297581608826;1651.620127370704;1723.5518765121064;7422.628868556578;7659.655315510801;8134.837303253938;8717.259032982203;8718.0410154883;8204.466709663882;8480.575971201431;8544.615492302846;8745.693900713477;8746.169113934182;2273.943151421215;2310.1683393978155;2311.3874823171873;2312.9537409251807;2312.9653055204103;2012.1046134470166;2090.644590419625;2321.315212012452;2329.10329523134;2352.811775058;2187.7921597448217;2286.4398222435525;2288.942776430586;2310.8266879984067;2311.9422014449606;2346.389938615972;2348.3189874936247;2368.3250273672093;2374.631932050535;2380.6548050532574;2337.555864385761;2351.4239011442137;2359.634256690216;2360.586897292967;2370.7747763962398;8270.8345833083;8401.285890699299;8404.117567655368;8409.873851892227;8415.83168336636;5264.078786212436;5393.649412788195;5414.83755487336;5416.0792665247;5418.376886658046;3374.375740487993;3515.2793677296095;3524.22467057247;3578.928421431583;3619.348517266891;1101.3226865477754;1168.0830548019765;1196.904247660185;1206.4269472000817;1224.7489264700714;12004.819927701092;12027.09796765246;12030.458629361612;12037.217580857216;12038.097142714332;4783.282507623815;4821.011692603008;4902.8774280643265;4922.187839006524;4933.827316043921;10286;10288.691339259834;10291.742706432398;10293.845592316122;10305.639302624371;5433.809816656398;5438.592105592096;5438.755256013489;5439.891202175934;5443.265159203536;4898.828541001048;4942.20924652054;4991.001799640014;4995.050940321327;4997.750112494361;3894.6494815466176;3963.9009024774036;3979.7015223857748;4013.8595149169637;4070.653994410449;1152.8040233160382;1213.1932265043647;1281.51943021367;1283.3262537168061;1298.2275546050084;930.7486978515763;935.6631612619451;945.8439357506072;946.7917058247175;954.6801821389773;5815.273090863642;6006.489448396847;6138.938610613926;6200.596961197508;6230.657313847782;948.2887834124407;969.3553786731885;970.6505657963128;972.8686627305224;994.6568433890296;4043.6360727534075;4062.532808727034;4069.064115253508;4077.4699289092227;4116.423700681907;10542.472876356305;10789.514471848786;10883.891161088446;10891.83662245067;10897.782044359226;10494.108000819862;10532.842007369896;10742.624008159677;10798.352098874093;10951.616693415692;7776.455648104578;7992.240737129847;8066.991626046744;8201.343892488587;8212.096669366365;5825.126231065374;5830.416958304305;5983.252093488314;6030.396960303934;6055.273367195965;9417.199538039069;9623.374480658735;9661.275404344562;9670.274729395183;9850.408975461496;9421.057894210524;9747.122758951728;9753.9024609753;9841.114299713061;9845.60617575289;7236.855262894818;7377.077865266841;7381.188069312264;7394.408447324308;7403.259674032553;8068.919310806817;8120.634571444181;8122.472039317662;8126.634301456567;8127.634256458356;7172.677229524579;7374.151972523228;7413.518121322097;7511.624418779148;7605.88591171144;7157;7296.927030729625;7311.4150867929375;7376.4098872089035;7532.510386824839;6436.356364363526;6580.177477815273;6584.144061272004;6586.670666466562;6604.9669751652855;5071.366079240095;5117.2579975902545;5155.381354237516;5169.147090730138;5370.865728356666;4955.752212389294;4996.650234483552;5061.367329083864;5100.158473851921;5265.473452654704 19:32:05 INFO - PROCESS | 12995 | |4;prototype.html;328.44820701221937;337.4651177883056;342.27437831796595;345.09240696966975;348.5260046336983;5232.947670523294;5289.65113895956;5293.57651387889;5294;5298.89402211956;5172.870678233046;5210.400803907552;5248.816291429801;5255.894882102356;5267.235476115506;3867.5552311484116;3996.18078293949;4011.696198735408;4034.11249525104;4041.2321658884866;4074.5925407459313;4079.6940229482757;4091.2635725569385;4098.6516146127615;4109.486314210723;4056.776877271749;4065.26825171469;4070.8778736637946;4081.6530594899323;4081.9795051237193;3867.7872717000555;3876.2556849417874;4046.6964977626794;4051.022498463627;4061.3298805697023;3623.5359955230433;3633.7819730816236;3643.1620727232885;3647.781133131994;3649.4525821126776;5057.2161314995865;5068.239764035387;5076.416212135615;5091.1090559152;5100.15847385181;4521.367008618796;4688.671792974493;4698.342232087476;4708.199606066977;4713.457952335491;5393.78424863005;5425.918611220795;5427.972860135714;5429.239906413105;5437.700926449043;3957.485526881487;3997.160596274786;3997.8001099944886;3998.460207871929;4021.959780402188;5491.725413729338;5601.075822489284;5651.2935883014625;5675.177099320616;5679.744411501451;4085.305498065336;4088.795560221977;4091.5499295077516;4094.2630326541507;4096.754194748265;4137.131202447459;4142.943549394885;4148.398482220061;4162.5629308922735;4171.937420938689;4408.162449134718;4682.672212945061;4700.354875793513;4714.15145273854;4738.905221895612;5459.372172200247;5473.644213126134;5492.80775172875;5494.478024587658;5527.944720552743;1348.649351168703;1360.9863901361057;1369.486442584031;1379.6412932637586;1379.7926814037717;897.9595918183697;944.544185118686;950.2113246005798;967.7516004354445;968.7287559483356;5049.368828896388;5138.66394737371;5176.870578235575;5280.630355875129;5301.363836339664;5590.372524913284;5613.831585052455;5616.691081990448;5616.9157462638095;5627.155926610959;5169.612279079009;5270.183121616155;5275.841724748264;5303.575713942875;5308.867278318073;2803.2010876899985;2819.2810833237395;2838.44650293195;2865.813722108098;2868.913932582026;5583.664980101207;5595.468430499096;5612.382637909826;5645.491905728504;5655.465334166146;5360.624756267024;5497.993232470119;5515.876387590806;5526.889462210734;5559.638623489541;5598.776048957996;5629.662220266797;5647.971760141173;5651;5652.519535839418;5269.604779641466;5430.7013114277515;5457.645253058538;5462.371827239918;5474.315710536183;5347.609621498361;5365.087935051129;5371.060064488777;5394.2448057272795;5402.540784033401;5587.608867379245;5597.972010139923;5598.384177740364;5611.773230392459;5657.377688454185;3792.544894612664;3887.591802860659;3907.882763517099;3909.179072394829;3933.9315334039493;5366.758495867617;5376.365580865426;5396.622236443412;5404.378496472798;5437.3747019091725;5236.005159019774;5239.869003274948;5261.500157485033;5317.680939143664;5328.120860058202;4968;5195.92206116901;5241.240020196974;5279.366476022902;5280.683159010472;5222.563794956265;5241.764120614658;5271.262023316815;5283.0754617942475;5294.385212510221;5261.158214685632;5295.285136506523;5320.627556071038;5421.2139239810795;5488.929658716512;5361.544268737201;5390.757415916371;5398.299535646259;5399.2980912481125;5404.621676482609 19:32:05 INFO - PROCESS | 12995 | |5;yui.html;701.5650296815975;719.704920982397;742.5730205132047;761.1589193940696;781.9413543984201;14205.573832785007;15417.3833046678;15455.92272038641;15481.380744770224;15563.610909727262;13867.791983120262;14055.437782488714;14057.226852523107;14057.367418466169;14079;647.2556559956041;663.2902794010411;743.22704387437;757.3524636435836;767.1714548287836;761.1779278379365;763.2787016269627;767.1561282589136;768.1703759939252;768.2471178245323;823.6787652815407;823.7487566292278;824.7195953375851;824.814416756231;825.1418524734269;691.6196092149298;814.1695470619934;820.6430202861774;820.7127505373131;820.9179082091773;520.4639221601756;574.2104606166521;586.5483577645232;589.8643312038255;592.8162269696408;14292.714145717131;14365.640858978504;14374.20941848198;14509.129452232793;14565.781513277308;12791.808122878165;12916.87083129166;13138.4087716053;13178;13367.732645347136;11676.94161529187;11731.17881748278;11825.467853946593;12010.579629713005;12065.215760975596;4152.626263636287;4178.39413285075;4247.702660813744;4286.335617979218;4421.823127074913;63058.684706576634;63330.683346583435;63649;63869.3613063868;65188;10527.105196058421;10660.253782235324;10701.357918524758;10819.459027048617;10840.241183117107;20028.89985550092;20137.798622013885;20169;20253;20571.4857128573;7560.810979725551;7645.464817462725;7698.807529811799;7729.768106956801;8286.337093032544;3953.940690889639;4033.9193216135513;4174.3947127666315;4179.477565304337;4257.233697934402;3406.9659303407143;3507.596626387947;3528.788272703646;3543.8582456701442;3550.4851796489365;609.8749756300036;609.9237595300588;613.9048447490646;618.8916939535563;623.4451338308909;581.3605034462142;625.1435533319382;638.4955884850916;655.0992385469979;655.8425977765302;8162.918370816335;8937.419067760575;8969.820603588023;9259.67591134307;9307.860382094274;546.3825876759237;598.6856900127381;619.8884200843892;625.7559551774812;637.3976592120495;3839.0247558142105;4148.730332528376;4162.708610397243;4185.393117997874;4194.2869712149;72478;84996.57501712452;86337.56831215804;86814;87525.562372189;74050;75255;75731.62134189405;76209;76541.61729191318;45113.54886451159;61417.38582614206;67813.32186678168;68414.65792671005;68557.14678463462;7969.811621728028;8230.711925082593;8231.742308940034;8437.704680336405;8573.442726222776;47383.76308118437;47794;48281.27578086261;48294.27558586554;49190.75404622954;55769.721151393984;64142.35857641364;64409;65748.34251657422;65789.67105164443;10076.949615251877;10138.847917281395;10281.948590257001;10490.737731556772;10647.520861561094;11070.944645276722;11474.77050459003;11891.82162267584;12145.878541214475;12549.247045177319;11143.275687080313;11376.374299413612;11802.586909458129;11909.702257443287;12192.268463892195;9662.516874156123;9902.554385052834;9914.40513569188;9994.700158995242;10807.513661885077;9970.152537034433;10225.948870255601;10656.840147397641;11069.904135469927;11084.057855082408;9172.770680733034;9511.429314241168;9564.76088097803;9683.564239609093;9823.410595364301;9672.129508344282;9826;9849.852252216078;9856.802863942825;9875.358101723365 19:32:05 INFO - PROCESS | 12995 | __end_tp_report 19:32:05 INFO - PROCESS | 12995 | __start_cc_report 19:32:05 INFO - PROCESS | 12995 | _x_x_mozilla_cycle_collect,1461 19:32:05 INFO - PROCESS | 12995 | __end_cc_report 19:32:05 INFO - PROCESS | 12995 | __startTimestamp1479958325289__endTimestamp 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | ------- Summary: start ------- 19:32:05 INFO - PROCESS | 12995 | Number of tests: 6 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | [#0] dojo.html Cycles:165 Average:5362.33 Median:5632.46 stddev:721.20 (12.8%) stddev-sans-first:700.18 19:32:05 INFO - PROCESS | 12995 | Values: 3047.8 3066.3 3073.8 3101.7 3113.3 5267.3 5330.2 5340.6 5397.2 5397.4 5384.8 5385.1 5389.3 5400.5 5429.7 4897.3 4936.9 4945.5 4976.1 4999.7 4914.9 4944.1 4950.3 4969.3 5017.7 5032.4 5035.0 5040.6 5057.6 5081.0 702.8 5044.8 5061.1 5063.5 5120.1 3937.4 4849.4 4849.8 4904.5 4992.8 5346.1 5456.2 5458.4 5462.8 5484.9 5730.9 5799.3 5805.3 5812.8 5866.1 5683.9 5715.6 5716.5 5766.3 5794.1 5409.6 5417.5 5448.0 5465.9 5470.0 5975.1 5980.4 5986.9 5988.8 5990.0 5587.5 5631.6 5631.7 5706.1 5726.7 5749.2 5780.7 5834.3 5883.2 5883.4 5761.5 5771.2 5774.6 5774.6 5786.9 5720.9 5756.5 5830.5 5831.3 5838.6 4419.9 4421.3 4433.8 4475.5 4492.9 3944.7 3953.3 3957.5 3993.9 3996.7 5491.8 5531.4 5539.7 5546.5 5560.1 5640.3 5680.7 5682.7 5700.7 5767.1 5437.2 5443.7 5444.7 5445.2 5449.5 4889.9 4923.7 4930.5 4934.0 4935.4 5951.8 5953.8 5954.3 5965.8 5971.2 5757.0 5762.4 5762.9 5768.9 5781.3 5997.5 6018.8 6019.8 6020.1 6023.7 5637.9 5649.8 5664.2 5708.7 5746.9 5860.6 5863.3 5870.1 5870.2 5875.3 5570.1 5610.8 5672.1 5723.0 5724.6 5656.9 5722.6 5734.7 5767.2 5776.1 5709.9 5713.5 5715.9 5719.6 5720.1 5598.5 5632.5 5768.3 5795.7 5806.7 5609.3 5618.4 5636.3 5758.7 5760.6 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | [#1] ext.html Cycles:180 Average:58071.78 Median:355963.69 stddev:115491.09 (32.4%) stddev-sans-first:115734.30 19:32:05 INFO - PROCESS | 12995 | Values: 542.5 591.7 592.0 602.9 603.4 31336.2 40254.4 40904.0 41004.8 44922.1 42200.0 42565.4 42592.8 42597.6 42641.4 7949.4 8612.1 8761.7 8763.6 8766.4 7784.2 9988.2 10242.5 10244.2 10560.6 10457.1 10503.3 10513.5 10522.5 10526.2 10402.0 10442.8 10452.9 10463.6 10469.3 10293.6 10331.3 10358.8 10366.8 10366.8 40034.4 40399.4 40569.4 40697.2 40709.4 3355.2 3376.2 3403.8 3438.3 3500.4 28260.4 30917.2 30994.8 31040.0 31046.4 4102.3 4144.4 4231.8 4272.1 4421.3 354953.0 355681.0 356246.4 358121.0 359151.0 57738.7 58077.0 58181.7 58194.7 58213.4 60831.7 62143.7 62357.7 62496.7 62516.7 15673.3 16405.3 16417.4 16421.2 16428.3 11550.5 11802.7 12356.8 12388.0 12538.3 18554.3 18920.2 19415.9 19619.5 19632.2 7241.5 7353.9 7366.4 7371.4 7465.6 900.6 986.6 1014.9 1026.5 1052.6 30767.7 31330.0 31383.7 31555.5 31585.7 1081.7 1092.4 1098.9 1112.0 1120.5 15977.7 16503.4 16675.5 16684.5 16953.2 573262.0 577855.0 578051.0 578094.0 580246.0 55401.0 55693.0 55770.7 55836.0 55844.7 1231.8 1267.3 1286.3 1342.6 1350.5 28078.7 31797.5 31836.2 32132.5 32929.0 47316.5 47587.5 47696.0 47720.3 47872.3 324649.0 325638.0 325695.0 326236.0 326343.0 27693.0 28244.4 28379.7 29873.4 40743.4 45388.8 45474.8 45591.0 46206.5 46781.3 38655.0 38680.0 38950.4 39935.0 40340.2 38445.8 38861.6 39540.0 39708.8 40948.8 39484.2 39635.8 40477.6 40723.0 40868.8 32118.7 35250.8 35488.5 36003.0 36350.8 27088.0 27286.9 27552.4 27638.4 27711.8 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | [#2] jquery.html Cycles:180 Average:27364.29 Median:20627.76 stddev:73343.37 (355.6%) stddev-sans-first:73521.10 19:32:05 INFO - PROCESS | 12995 | Values: 363.2 390.6 401.8 402.1 415.2 13249.5 15200.5 16030.4 18965.7 19330.8 18553.0 18656.6 19129.5 19278.5 19282.2 5312.1 5537.7 5542.6 5545.0 5546.9 5225.6 5557.9 5565.8 5566.3 5567.7 5486.9 5490.6 5490.8 5491.2 5495.8 5415.3 5693.9 5722.5 5792.5 5803.1 5441.4 5610.2 5618.6 5620.6 5621.4 13925.7 14149.1 14174.4 14206.1 19109.9 2104.7 2291.4 2312.8 2327.9 2389.3 10015.4 10238.4 10287.7 10510.9 10671.1 1704.5 1786.6 1791.5 1871.1 2030.4 78275.6 86424.1 86559.6 88751.6 89536.0 10507.3 11054.0 11112.7 11162.0 11357.0 15887.2 15888.2 15899.8 15914.5 15940.6 7999.8 8101.5 8197.9 8253.3 8281.1 7594.0 7665.7 7693.2 7711.1 7869.1 4684.7 4833.3 4865.8 4935.2 4992.8 1948.2 1968.1 1977.4 1980.7 2016.2 736.8 885.1 887.3 913.0 975.6 17533.3 19438.2 19452.7 19518.9 19601.9 971.3 992.7 995.8 997.9 1017.8 10195.8 10474.0 10684.7 10725.1 10742.8 430812.0 444698.0 444767.0 445315.0 445684.0 82300.0 91252.5 91335.0 91858.5 93337.0 985.8 995.7 997.3 1013.5 1013.9 16316.3 16548.2 16673.5 17208.5 17497.8 40853.4 41313.8 41792.0 42287.4 43393.1 56867.3 56897.4 58122.7 58828.7 60369.7 6618.1 8002.6 8192.9 8667.7 8679.5 11628.9 12446.4 12730.2 13110.1 13301.5 10902.0 11051.0 11192.6 11205.6 11954.2 11180.6 11214.2 11305.8 11692.8 11815.8 11255.6 11428.9 11631.4 11717.0 11822.3 7486.6 8552.3 8553.2 8611.9 8950.9 9668.9 11041.7 11224.7 11707.3 11800.9 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | [#3] mootools.html Cycles:180 Average:5694.87 Median:5136.32 stddev:3165.84 (61.6%) stddev-sans-first:3158.35 19:32:05 INFO - PROCESS | 12995 | Values: 1411.1 1460.7 1560.9 1651.6 1723.6 7422.6 7659.7 8134.8 8717.3 8718.0 8204.5 8480.6 8544.6 8745.7 8746.2 2273.9 2310.2 2311.4 2313.0 2313.0 2012.1 2090.6 2321.3 2329.1 2352.8 2187.8 2286.4 2288.9 2310.8 2311.9 2346.4 2348.3 2368.3 2374.6 2380.7 2337.6 2351.4 2359.6 2360.6 2370.8 8270.8 8401.3 8404.1 8409.9 8415.8 5264.1 5393.6 5414.8 5416.1 5418.4 3374.4 3515.3 3524.2 3578.9 3619.3 1101.3 1168.1 1196.9 1206.4 1224.7 12004.8 12027.1 12030.5 12037.2 12038.1 4783.3 4821.0 4902.9 4922.2 4933.8 10286.0 10288.7 10291.7 10293.8 10305.6 5433.8 5438.6 5438.8 5439.9 5443.3 4898.8 4942.2 4991.0 4995.1 4997.8 3894.6 3963.9 3979.7 4013.9 4070.7 1152.8 1213.2 1281.5 1283.3 1298.2 930.7 935.7 945.8 946.8 954.7 5815.3 6006.5 6138.9 6200.6 6230.7 948.3 969.4 970.7 972.9 994.7 4043.6 4062.5 4069.1 4077.5 4116.4 10542.5 10789.5 10883.9 10891.8 10897.8 10494.1 10532.8 10742.6 10798.4 10951.6 7776.5 7992.2 8067.0 8201.3 8212.1 5825.1 5830.4 5983.3 6030.4 6055.3 9417.2 9623.4 9661.3 9670.3 9850.4 9421.1 9747.1 9753.9 9841.1 9845.6 7236.9 7377.1 7381.2 7394.4 7403.3 8068.9 8120.6 8122.5 8126.6 8127.6 7172.7 7374.2 7413.5 7511.6 7605.9 7157.0 7296.9 7311.4 7376.4 7532.5 6436.4 6580.2 6584.1 6586.7 6605.0 5071.4 5117.3 5155.4 5169.1 5370.9 4955.8 4996.7 5061.4 5100.2 5265.5 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | [#4] prototype.html Cycles:180 Average:4553.50 Median:5258.53 stddev:1319.38 (25.1%) stddev-sans-first:1284.40 19:32:05 INFO - PROCESS | 12995 | Values: 328.4 337.5 342.3 345.1 348.5 5232.9 5289.7 5293.6 5294.0 5298.9 5172.9 5210.4 5248.8 5255.9 5267.2 3867.6 3996.2 4011.7 4034.1 4041.2 4074.6 4079.7 4091.3 4098.7 4109.5 4056.8 4065.3 4070.9 4081.7 4082.0 3867.8 3876.3 4046.7 4051.0 4061.3 3623.5 3633.8 3643.2 3647.8 3649.5 5057.2 5068.2 5076.4 5091.1 5100.2 4521.4 4688.7 4698.3 4708.2 4713.5 5393.8 5425.9 5428.0 5429.2 5437.7 3957.5 3997.2 3997.8 3998.5 4022.0 5491.7 5601.1 5651.3 5675.2 5679.7 4085.3 4088.8 4091.5 4094.3 4096.8 4137.1 4142.9 4148.4 4162.6 4171.9 4408.2 4682.7 4700.4 4714.2 4738.9 5459.4 5473.6 5492.8 5494.5 5527.9 1348.6 1361.0 1369.5 1379.6 1379.8 898.0 944.5 950.2 967.8 968.7 5049.4 5138.7 5176.9 5280.6 5301.4 5590.4 5613.8 5616.7 5616.9 5627.2 5169.6 5270.2 5275.8 5303.6 5308.9 2803.2 2819.3 2838.4 2865.8 2868.9 5583.7 5595.5 5612.4 5645.5 5655.5 5360.6 5498.0 5515.9 5526.9 5559.6 5598.8 5629.7 5648.0 5651.0 5652.5 5269.6 5430.7 5457.6 5462.4 5474.3 5347.6 5365.1 5371.1 5394.2 5402.5 5587.6 5598.0 5598.4 5611.8 5657.4 3792.5 3887.6 3907.9 3909.2 3933.9 5366.8 5376.4 5396.6 5404.4 5437.4 5236.0 5239.9 5261.5 5317.7 5328.1 4968.0 5195.9 5241.2 5279.4 5280.7 5222.6 5241.8 5271.3 5283.1 5294.4 5261.2 5295.3 5320.6 5421.2 5488.9 5361.5 5390.8 5398.3 5399.3 5404.6 19:32:05 INFO - PROCESS | 12995 | 19:32:05 INFO - PROCESS | 12995 | [#5] yui.html Cycles:180 Average:17147.30 Median:609.90 stddev:23064.25 (3781.6%) stddev-sans-first:23095.89 19:32:05 INFO - PROCESS | 12995 | Values: 701.6 719.7 742.6 761.2 781.9 14205.6 15417.4 15455.9 15481.4 15563.6 13867.8 14055.4 14057.2 14057.4 14079.0 647.3 663.3 743.2 757.4 767.2 761.2 763.3 767.2 768.2 768.2 823.7 823.7 824.7 824.8 825.1 691.6 814.2 820.6 820.7 820.9 520.5 574.2 586.5 589.9 592.8 14292.7 14365.6 14374.2 14509.1 14565.8 12791.8 12916.9 13138.4 13178.0 13367.7 11676.9 11731.2 11825.5 12010.6 12065.2 4152.6 4178.4 4247.7 4286.3 4421.8 63058.7 63330.7 63649.0 63869.4 65188.0 10527.1 10660.3 10701.4 10819.5 10840.2 20028.9 20137.8 20169.0 20253.0 20571.5 7560.8 7645.5 7698.8 7729.8 8286.3 3953.9 4033.9 4174.4 4179.5 4257.2 3407.0 3507.6 3528.8 3543.9 3550.5 609.9 609.9 613.9 618.9 623.4 581.4 625.1 638.5 655.1 655.8 8162.9 8937.4 8969.8 9259.7 9307.9 546.4 598.7 619.9 625.8 637.4 3839.0 4148.7 4162.7 4185.4 4194.3 72478.0 84996.6 86337.6 86814.0 87525.6 74050.0 75255.0 75731.6 76209.0 76541.6 45113.5 61417.4 67813.3 68414.7 68557.1 7969.8 8230.7 8231.7 8437.7 8573.4 47383.8 47794.0 48281.3 48294.3 49190.8 55769.7 64142.4 64409.0 65748.3 65789.7 10076.9 10138.8 10281.9 10490.7 10647.5 11070.9 11474.8 11891.8 12145.9 12549.2 11143.3 11376.4 11802.6 11909.7 12192.3 9662.5 9902.6 9914.4 9994.7 10807.5 9970.2 10225.9 10656.8 11069.9 11084.1 9172.8 9511.4 9564.8 9683.6 9823.4 9672.1 9826.0 9849.9 9856.8 9875.4 19:32:05 INFO - PROCESS | 12995 | -------- Summary: end -------- 19:32:05 INFO - PROCESS | 12995 | 19:32:06 INFO - TEST-INFO | 12995: exit 0 19:32:06 INFO - TEST-OK | dromaeo_css | took 1118167ms 19:32:06 INFO - TEST-START | kraken 19:32:06 INFO - operating with platform_type : linux_ 19:32:06 INFO - Initialising browser for kraken test... 19:32:06 INFO - TEST-INFO | started process 13298 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDWYUa0/profile http://localhost:43759/getInfo.html) 19:32:10 INFO - PROCESS | 13298 | __metrics Screen width/height:1600/1200 19:32:10 INFO - PROCESS | 13298 | colorDepth:24 19:32:10 INFO - PROCESS | 13298 | Browser inner width/height: 1024/697 19:32:10 INFO - PROCESS | 13298 | __metrics 19:32:10 INFO - PROCESS | 13298 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:32:10 INFO - PROCESS | 13298 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:32:11 INFO - PROCESS | 13298 | console.error: 19:32:11 INFO - PROCESS | 13298 | Message: Error: Connection closed before committing the transaction. 19:32:11 INFO - PROCESS | 13298 | Stack: 19:32:11 INFO - PROCESS | 13298 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 19:32:11 INFO - PROCESS | 13298 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 19:32:11 INFO - PROCESS | 13298 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 19:32:11 INFO - PROCESS | 13298 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 19:32:11 INFO - PROCESS | 13298 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 19:32:11 INFO - PROCESS | 13298 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 19:32:11 INFO - PROCESS | 13298 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 19:32:11 INFO - PROCESS | 13298 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 19:32:11 INFO - PROCESS | 13298 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 19:32:11 INFO - PROCESS | 13298 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 19:32:11 INFO - PROCESS | 13298 | Barrier/this.client.addBlocker/promise13.1% 19:33:08 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:33:08 INFO - TinderboxPrint: I/O write bytes / time
392,921,088 / 315,228 19:33:08 INFO - TinderboxPrint: CPU idle
8,294.3 (86.8%) 19:33:08 INFO - TinderboxPrint: CPU user
1,205.3 (12.6%) 19:33:08 INFO - TinderboxPrint: Swap in / out
0 / 0 19:33:08 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103702528; Read time: 0; Write time: 83036 19:33:08 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 0; Write bytes: 288751616; Read time: 0; Write time: 232192 19:33:09 INFO - Running post-run listener: _upload_blobber_files 19:33:09 INFO - Blob upload gear active. 19:33:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:33:09 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 19:33:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:33:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:33:09 INFO - (blobuploader) - INFO - Open directory for files ... 19:33:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:33:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:33:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:10 INFO - (blobuploader) - INFO - Done attempting. 19:33:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:33:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:33:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:11 INFO - (blobuploader) - INFO - Done attempting. 19:33:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:33:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:33:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:14 INFO - (blobuploader) - INFO - Done attempting. 19:33:14 INFO - (blobuploader) - INFO - Iteration through files over. 19:33:14 INFO - Return code: 0 19:33:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:33:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:33:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10bf10435adf0d9926b9ed8adf25581a5a662ca4ccd70dc82ec544386f090601eb04ef152edf7966ac91b68e849b5851c62165bbb37da304c080c8c596379c07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ef248b554c213cad7f99bc5b7746beb7eb0c557330c6fdb34ec89a94545a0037001722136506ec03564ce022236c8158387a2ceabeac895d628665671a09340", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3860223e49924f72c920ea8a6a5d1768c35081f22f16019a41a52f59569a56910e4314e735a66b6f0203a78b990c717415f5e307251a8a0d34dd8257a2ad0ea0"} 19:33:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:33:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:33:14 INFO - Contents: 19:33:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10bf10435adf0d9926b9ed8adf25581a5a662ca4ccd70dc82ec544386f090601eb04ef152edf7966ac91b68e849b5851c62165bbb37da304c080c8c596379c07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ef248b554c213cad7f99bc5b7746beb7eb0c557330c6fdb34ec89a94545a0037001722136506ec03564ce022236c8158387a2ceabeac895d628665671a09340", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3860223e49924f72c920ea8a6a5d1768c35081f22f16019a41a52f59569a56910e4314e735a66b6f0203a78b990c717415f5e307251a8a0d34dd8257a2ad0ea0"} 19:33:14 INFO - Running post-run listener: copy_logs_to_upload_dir 19:33:14 INFO - Copying logs to upload dir... 19:33:14 INFO - mkdir: /builds/slave/test/build/upload/logs 19:33:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.051250 ========= master_lag: 1.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-11-23 19:33:15.399025) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 19:33:15.402273) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10bf10435adf0d9926b9ed8adf25581a5a662ca4ccd70dc82ec544386f090601eb04ef152edf7966ac91b68e849b5851c62165bbb37da304c080c8c596379c07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ef248b554c213cad7f99bc5b7746beb7eb0c557330c6fdb34ec89a94545a0037001722136506ec03564ce022236c8158387a2ceabeac895d628665671a09340", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3860223e49924f72c920ea8a6a5d1768c35081f22f16019a41a52f59569a56910e4314e735a66b6f0203a78b990c717415f5e307251a8a0d34dd8257a2ad0ea0"} build_url:https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009708 build_url: 'https://queue.taskcluster.net/v1/task/VpIyVThbQeqrCFK8rydvjQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10bf10435adf0d9926b9ed8adf25581a5a662ca4ccd70dc82ec544386f090601eb04ef152edf7966ac91b68e849b5851c62165bbb37da304c080c8c596379c07", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ef248b554c213cad7f99bc5b7746beb7eb0c557330c6fdb34ec89a94545a0037001722136506ec03564ce022236c8158387a2ceabeac895d628665671a09340", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3860223e49924f72c920ea8a6a5d1768c35081f22f16019a41a52f59569a56910e4314e735a66b6f0203a78b990c717415f5e307251a8a0d34dd8257a2ad0ea0"}' ========= master_lag: 0.22 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-23 19:33:15.629981) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:33:15.630302) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1479957151.562437-1306437281 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004550 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-23 19:33:15.646738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 19:33:15.647068) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-23 19:33:15.647357) ========= ========= Total master_lag: 1.45 =========