builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-048 starttime: 1479842317.58 results: success (0) buildid: 20161122111759 builduid: b93ab96820a54d61831cc02ffe2ed20f revision: d4792f52f4761029eb416632312c61d92cf85497 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.580814) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.581397) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.581719) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.604355) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.604773) ========= 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=fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-22 11:18:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2016-11-22 11:18:38 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099047 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.719290) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.719674) ========= 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=fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016673 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.769979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:37.770437) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d4792f52f4761029eb416632312c61d92cf85497 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d4792f52f4761029eb416632312c61d92cf85497 --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=fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-22 11:18:38,441 truncating revision to first 12 chars 2016-11-22 11:18:38,441 Setting DEBUG logging. 2016-11-22 11:18:38,441 attempt 1/10 2016-11-22 11:18:38,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d4792f52f476?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 11:18:38,808 unpacking tar archive at: mozilla-inbound-d4792f52f476/testing/mozharness/ program finished with exit code 0 elapsedTime=0.685755 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:38.471305) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:38.471622) ========= script_repo_revision: d4792f52f4761029eb416632312c61d92cf85497 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:38.472051) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:38.472380) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 11:18:38.481918) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-11-22 11:18:38.482246) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:18:39 INFO - MultiFileLogger online at 20161122 11:18:39 in /builds/slave/test 11:18:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 11:18:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:18:39 INFO - {'append_to_log': False, 11:18:39 INFO - 'base_work_dir': '/builds/slave/test', 11:18:39 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 11:18:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:18:39 INFO - 'branch': 'Mozilla-Inbound', 11:18:39 INFO - 'buildbot_json_path': 'buildprops.json', 11:18:39 INFO - 'config_files': ('talos/linux_config.py',), 11:18:39 INFO - 'default_actions': ('clobber', 11:18:39 INFO - 'read-buildbot-config', 11:18:39 INFO - 'download-and-extract', 11:18:39 INFO - 'populate-webroot', 11:18:39 INFO - 'create-virtualenv', 11:18:39 INFO - 'install', 11:18:39 INFO - 'run-tests'), 11:18:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:18:39 INFO - 'download_minidump_stackwalk': True, 11:18:39 INFO - 'download_symbols': 'ondemand', 11:18:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:18:39 INFO - 'tooltool.py': '/tools/tooltool.py', 11:18:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:18:39 INFO - '/tools/misc-python/virtualenv.py')}, 11:18:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:18:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:18:39 INFO - 'installer_path': 'installer.exe', 11:18:39 INFO - 'log_level': 'info', 11:18:39 INFO - 'log_name': 'talos', 11:18:39 INFO - 'log_to_console': True, 11:18:39 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:18:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:18:39 INFO - 'opt_config_files': (), 11:18:39 INFO - 'pip_index': False, 11:18:39 INFO - 'sps_profile': False, 11:18:39 INFO - 'sps_profile_interval': 0, 11:18:39 INFO - 'suite': 'dromaeojs', 11:18:39 INFO - 'system_bits': '32', 11:18:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:18:39 INFO - 'title': 'talos-linux64-ix-048', 11:18:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:18:39 INFO - 'use_talos_json': True, 11:18:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:18:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:18:39 INFO - 'work_dir': 'build'} 11:18:39 INFO - [mozharness: 2016-11-22 19:18:39.251121Z] Running clobber step. 11:18:39 INFO - Running pre-action listener: _resource_record_pre_action 11:18:39 INFO - Running main action method: clobber 11:18:39 INFO - rmtree: /builds/slave/test/build 11:18:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:18:40 INFO - Running post-action listener: _resource_record_post_action 11:18:40 INFO - [mozharness: 2016-11-22 19:18:40.225116Z] Finished clobber step (success) 11:18:40 INFO - [mozharness: 2016-11-22 19:18:40.225215Z] Running read-buildbot-config step. 11:18:40 INFO - Running pre-action listener: _resource_record_pre_action 11:18:40 INFO - Running main action method: read_buildbot_config 11:18:40 INFO - Using buildbot properties: 11:18:40 INFO - { 11:18:40 INFO - "project": "", 11:18:40 INFO - "product": "firefox", 11:18:40 INFO - "script_repo_revision": "production", 11:18:40 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 11:18:40 INFO - "repository": "", 11:18:40 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 11:18:40 INFO - "buildid": "20161122111759", 11:18:40 INFO - "stage_platform": "linux64-pgo", 11:18:40 INFO - "basedir": "/builds/slave/test", 11:18:40 INFO - "buildnumber": 2130, 11:18:40 INFO - "platform": "ubuntu64_hw", 11:18:40 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:18:40 INFO - "slavebuilddir": "test", 11:18:40 INFO - "branch": "mozilla-inbound", 11:18:40 INFO - "revision": "d4792f52f4761029eb416632312c61d92cf85497", 11:18:40 INFO - "slavename": "talos-linux64-ix-048", 11:18:40 INFO - "builduid": "b93ab96820a54d61831cc02ffe2ed20f", 11:18:40 INFO - "repo_path": "integration/mozilla-inbound" 11:18:40 INFO - } 11:18:40 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 11:18:40 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.test_packages.json. 11:18:40 INFO - Running post-action listener: _resource_record_post_action 11:18:40 INFO - [mozharness: 2016-11-22 19:18:40.227554Z] Finished read-buildbot-config step (success) 11:18:40 INFO - [mozharness: 2016-11-22 19:18:40.227643Z] Running download-and-extract step. 11:18:40 INFO - Running pre-action listener: _resource_record_pre_action 11:18:40 INFO - Running main action method: download_and_extract 11:18:40 INFO - mkdir: /builds/slave/test/build/tests 11:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:40 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 11:18:40 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/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 11:18:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/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 11:18:40 INFO - Downloaded 1361 bytes. 11:18:40 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 11:18:40 INFO - Using the following test package requirements: 11:18:40 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 11:18:40 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 11:18:40 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 11:18:40 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'jsshell-linux-x86_64.zip'], 11:18:40 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 11:18:40 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 11:18:40 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 11:18:40 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 11:18:40 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 11:18:40 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 11:18:40 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 11:18:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:18:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 11:18:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:18:40 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:18:40 INFO - Http code: 200 11:18:40 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 11:18:40 INFO - x-amz-cf-id: ZSgRjSWg2Hs7xQY8OBziYJJ_VGIjk25Vbd5I3TP_ojz4gwji2Ndplg== 11:18:40 INFO - x-amz-replication-status: COMPLETED 11:18:40 INFO - x-amz-version-id: HQRATt2G_J9FIfCi9mi84DDW1R_2RYNU 11:18:40 INFO - Expected file size: 21745503 11:18:40 INFO - Obtained file size: 21745503 11:18:44 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 11:18:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 11:18:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:18:44 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 11:18:44 INFO - Http code: 200 11:18:44 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 11:18:44 INFO - x-amz-cf-id: xdYaZKrmBl2XLmBCqw3uhSJVpZLCVsZ88N5e1A574ZeQ_dcdPbWexw== 11:18:44 INFO - x-amz-replication-status: COMPLETED 11:18:44 INFO - x-amz-version-id: HQRATt2G_J9FIfCi9mi84DDW1R_2RYNU 11:18:44 INFO - Expected file size: 21745503 11:18:44 INFO - Obtained file size: 21745503 11:18:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 11:18:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:18:49 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 11:18:49 INFO - Http code: 200 11:18:49 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 11:18:49 INFO - x-amz-cf-id: 3OcQbg-wiqf8VrHIbQCFHEbIAJ79cqFqyc3QU1Bkwn133gthsyAZpw== 11:18:49 INFO - x-amz-replication-status: COMPLETED 11:18:49 INFO - x-amz-version-id: 77Tp6hO_UCe_b6sX0Zp8O3agAWQvGfYD 11:18:49 INFO - Expected file size: 14409392 11:18:49 INFO - Obtained file size: 14409392 11:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:49 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:18:49 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/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 11:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/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 11:18:50 INFO - Downloaded 59729461 bytes. 11:18:50 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:18:50 INFO - mkdir: /builds/slave/test/properties 11:18:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:18:50 INFO - Writing to file /builds/slave/test/properties/build_url 11:18:50 INFO - Contents: 11:18:50 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 11:18:50 INFO - Running post-action listener: _resource_record_post_action 11:18:50 INFO - Running post-action listener: set_extra_try_arguments 11:18:50 INFO - [mozharness: 2016-11-22 19:18:50.704455Z] Finished download-and-extract step (success) 11:18:50 INFO - [mozharness: 2016-11-22 19:18:50.704681Z] Running populate-webroot step. 11:18:50 INFO - Running pre-action listener: _resource_record_pre_action 11:18:50 INFO - Running main action method: populate_webroot 11:18:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:18:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:18:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:18:50 INFO - '--tppagecycles', 11:18:50 INFO - '7'], 11:18:50 INFO - 'tests': ['tsvgm']}}, 11:18:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:18:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:18:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:18:50 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:18:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:18:50 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['damp', 'tps']}, 11:18:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'tests': ['damp', 'tps']}, 11:18:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['dromaeo_dom']}, 11:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:18:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['basic_compositor_video']}, 11:18:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:18:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['a11yr', 11:18:50 INFO - 'ts_paint', 11:18:50 INFO - 'tpaint', 11:18:50 INFO - 'sessionrestore', 11:18:50 INFO - 'sessionrestore_no_auto_restore', 11:18:50 INFO - 'tabpaint']}, 11:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 11:18:50 INFO - 'ts_paint', 11:18:50 INFO - 'tpaint', 11:18:50 INFO - 'sessionrestore', 11:18:50 INFO - 'sessionrestore_no_auto_restore', 11:18:50 INFO - 'tabpaint']}, 11:18:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:18:50 INFO - 'ts_paint', 11:18:50 INFO - 'tpaint', 11:18:50 INFO - 'sessionrestore', 11:18:50 INFO - 'sessionrestore_no_auto_restore', 11:18:50 INFO - 'tabpaint']}, 11:18:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:18:50 INFO - 'ts_paint', 11:18:50 INFO - 'tpaint', 11:18:50 INFO - 'sessionrestore', 11:18:50 INFO - 'sessionrestore_no_auto_restore', 11:18:50 INFO - 'tabpaint']}, 11:18:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['a11yr', 11:18:50 INFO - 'ts_paint', 11:18:50 INFO - 'tpaint', 11:18:50 INFO - 'sessionrestore', 11:18:50 INFO - 'sessionrestore_no_auto_restore', 11:18:50 INFO - 'tabpaint']}, 11:18:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['a11yr', 11:18:50 INFO - 'ts_paint', 11:18:50 INFO - 'tpaint', 11:18:50 INFO - 'sessionrestore', 11:18:50 INFO - 'sessionrestore_no_auto_restore', 11:18:50 INFO - 'tabpaint']}, 11:18:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['tsvgx', 11:18:50 INFO - 'tsvgr_opacity', 11:18:50 INFO - 'tart', 11:18:50 INFO - 'tscrollx', 11:18:50 INFO - 'cart']}, 11:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:18:50 INFO - 'tsvgr_opacity', 11:18:50 INFO - 'tart', 11:18:50 INFO - 'tscrollx', 11:18:50 INFO - 'cart']}, 11:18:50 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'talos_options': ['--disable-e10s'], 11:18:50 INFO - 'tests': ['tp5o']}, 11:18:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:18:50 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'talos_options': ['--disable-e10s', 11:18:50 INFO - '--xperf_path', 11:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:18:50 INFO - 'tests': ['tp5n']}, 11:18:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:18:50 INFO - 'talos_options': ['--xperf_path', 11:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:18:50 INFO - 'tests': ['tp5n']}}, 11:18:50 INFO - 'talos.zip': {'path': '', 11:18:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:18:50 INFO - Running post-action listener: _resource_record_post_action 11:18:50 INFO - [mozharness: 2016-11-22 19:18:50.719298Z] Finished populate-webroot step (success) 11:18:50 INFO - [mozharness: 2016-11-22 19:18:50.719386Z] Running create-virtualenv step. 11:18:50 INFO - Running pre-action listener: _resource_record_pre_action 11:18:50 INFO - Running main action method: create_virtualenv 11:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 11:18:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:18:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:18:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:18:50 INFO - Using real prefix '/usr' 11:18:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:18:51 INFO - Installing distribute.............................................................................................................................................................................................done. 11:18:53 INFO - Installing pip.................done. 11:18:53 INFO - Return code: 0 11:18:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:18:53 INFO - Reading from file tmpfile_stdout 11:18:53 INFO - Output received: 11:18:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:18:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:18:53 INFO - Using env: {'DISPLAY': ':0', 11:18:53 INFO - 'HOME': '/home/cltbld', 11:18:53 INFO - 'LANG': 'en_US.UTF-8', 11:18:53 INFO - 'LANGUAGE': 'en_US:en', 11:18:53 INFO - 'LOGNAME': 'cltbld', 11:18:53 INFO - 'MAIL': '/var/mail/cltbld', 11:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:53 INFO - 'MOZ_NO_REMOTE': '1', 11:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:18:53 INFO - 'NO_EM_RESTART': '1', 11:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:53 INFO - 'PWD': '/builds/slave/test', 11:18:53 INFO - 'SHELL': '/bin/bash', 11:18:53 INFO - 'SHLVL': '1', 11:18:53 INFO - 'TERM': 'linux', 11:18:53 INFO - 'TMOUT': '86400', 11:18:53 INFO - 'USER': 'cltbld', 11:18:53 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:53 INFO - '_': '/tools/buildbot/bin/python'} 11:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:18:53 INFO - Cleaning up... 11:18:53 INFO - Return code: 0 11:18:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:18:53 INFO - Using env: {'DISPLAY': ':0', 11:18:53 INFO - 'HOME': '/home/cltbld', 11:18:53 INFO - 'LANG': 'en_US.UTF-8', 11:18:53 INFO - 'LANGUAGE': 'en_US:en', 11:18:53 INFO - 'LOGNAME': 'cltbld', 11:18:53 INFO - 'MAIL': '/var/mail/cltbld', 11:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:53 INFO - 'MOZ_NO_REMOTE': '1', 11:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:18:53 INFO - 'NO_EM_RESTART': '1', 11:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:53 INFO - 'PWD': '/builds/slave/test', 11:18:53 INFO - 'SHELL': '/bin/bash', 11:18:53 INFO - 'SHLVL': '1', 11:18:53 INFO - 'TERM': 'linux', 11:18:53 INFO - 'TMOUT': '86400', 11:18:53 INFO - 'USER': 'cltbld', 11:18:53 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:53 INFO - '_': '/tools/buildbot/bin/python'} 11:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:53 INFO - Downloading/unpacking psutil>=3.1.1 11:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:18:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:18:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:18:55 INFO - Installing collected packages: psutil 11:18:55 INFO - Running setup.py install for psutil 11:18:56 INFO - building 'psutil._psutil_linux' extension 11:18:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:18:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:18:56 INFO - building 'psutil._psutil_posix' extension 11:18:56 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:18:56 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:18:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:18:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:18:56 INFO - Successfully installed psutil 11:18:56 INFO - Cleaning up... 11:18:56 INFO - Return code: 0 11:18:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:18: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')]} 11:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18: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')]} 11:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:18: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 mozsystemmonitor==0.3 11:18:56 INFO - Using env: {'DISPLAY': ':0', 11:18:56 INFO - 'HOME': '/home/cltbld', 11:18:56 INFO - 'LANG': 'en_US.UTF-8', 11:18:56 INFO - 'LANGUAGE': 'en_US:en', 11:18:56 INFO - 'LOGNAME': 'cltbld', 11:18:56 INFO - 'MAIL': '/var/mail/cltbld', 11:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:56 INFO - 'MOZ_NO_REMOTE': '1', 11:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:18:56 INFO - 'NO_EM_RESTART': '1', 11:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:56 INFO - 'PWD': '/builds/slave/test', 11:18:56 INFO - 'SHELL': '/bin/bash', 11:18:56 INFO - 'SHLVL': '1', 11:18:56 INFO - 'TERM': 'linux', 11:18:56 INFO - 'TMOUT': '86400', 11:18:56 INFO - 'USER': 'cltbld', 11:18:56 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:56 INFO - '_': '/tools/buildbot/bin/python'} 11:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:18: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 11:18: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 11:18: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 11:18: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 11:18: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 11:18: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 11:18:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:18:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:18:58 INFO - Installing collected packages: mozsystemmonitor 11:18:58 INFO - Running setup.py install for mozsystemmonitor 11:18:58 INFO - Successfully installed mozsystemmonitor 11:18:58 INFO - Cleaning up... 11:18:58 INFO - Return code: 0 11:18:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:58 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:18:58 INFO - Using env: {'DISPLAY': ':0', 11:18:58 INFO - 'HOME': '/home/cltbld', 11:18:58 INFO - 'LANG': 'en_US.UTF-8', 11:18:58 INFO - 'LANGUAGE': 'en_US:en', 11:18:58 INFO - 'LOGNAME': 'cltbld', 11:18:58 INFO - 'MAIL': '/var/mail/cltbld', 11:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:58 INFO - 'MOZ_NO_REMOTE': '1', 11:18:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:18:58 INFO - 'NO_EM_RESTART': '1', 11:18:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:58 INFO - 'PWD': '/builds/slave/test', 11:18:58 INFO - 'SHELL': '/bin/bash', 11:18:58 INFO - 'SHLVL': '1', 11:18:58 INFO - 'TERM': 'linux', 11:18:58 INFO - 'TMOUT': '86400', 11:18:58 INFO - 'USER': 'cltbld', 11:18:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:58 INFO - '_': '/tools/buildbot/bin/python'} 11:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:59 INFO - Downloading/unpacking jsonschema==2.5.1 11:18: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 11:18: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 11:18: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 11:18: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 11:18: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 11:18: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 11:19:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:19:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:19: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 11:19: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 11:19: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 11:19: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 11:19: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 11:19: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 11:19:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:19:01 INFO - warning: no files found matching '*.txt' 11:19:01 INFO - no previously-included directories found matching 'build' 11:19:01 INFO - no previously-included directories found matching 'dist' 11:19:01 INFO - no previously-included directories found matching '.git*' 11:19:01 INFO - Installing collected packages: jsonschema, functools32 11:19:01 INFO - Running setup.py install for functools32 11:19:01 INFO - warning: no files found matching '*.txt' 11:19:01 INFO - no previously-included directories found matching 'build' 11:19:01 INFO - no previously-included directories found matching 'dist' 11:19:01 INFO - no previously-included directories found matching '.git*' 11:19:01 INFO - Successfully installed jsonschema functools32 11:19:01 INFO - Cleaning up... 11:19:01 INFO - Return code: 0 11:19:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:01 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:19:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:19:01 INFO - Using env: {'DISPLAY': ':0', 11:19:01 INFO - 'HOME': '/home/cltbld', 11:19:01 INFO - 'LANG': 'en_US.UTF-8', 11:19:01 INFO - 'LANGUAGE': 'en_US:en', 11:19:01 INFO - 'LOGNAME': 'cltbld', 11:19:01 INFO - 'MAIL': '/var/mail/cltbld', 11:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:01 INFO - 'MOZ_NO_REMOTE': '1', 11:19:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:01 INFO - 'NO_EM_RESTART': '1', 11:19:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:01 INFO - 'PWD': '/builds/slave/test', 11:19:01 INFO - 'SHELL': '/bin/bash', 11:19:01 INFO - 'SHLVL': '1', 11:19:01 INFO - 'TERM': 'linux', 11:19:01 INFO - 'TMOUT': '86400', 11:19:01 INFO - 'USER': 'cltbld', 11:19:01 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:01 INFO - '_': '/tools/buildbot/bin/python'} 11:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:19:01 INFO - Cleaning up... 11:19:01 INFO - Return code: 0 11:19:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:01 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:19:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:19:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:19:01 INFO - Using env: {'DISPLAY': ':0', 11:19:01 INFO - 'HOME': '/home/cltbld', 11:19:01 INFO - 'LANG': 'en_US.UTF-8', 11:19:01 INFO - 'LANGUAGE': 'en_US:en', 11:19:01 INFO - 'LOGNAME': 'cltbld', 11:19:01 INFO - 'MAIL': '/var/mail/cltbld', 11:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:01 INFO - 'MOZ_NO_REMOTE': '1', 11:19:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:01 INFO - 'NO_EM_RESTART': '1', 11:19:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:01 INFO - 'PWD': '/builds/slave/test', 11:19:01 INFO - 'SHELL': '/bin/bash', 11:19:01 INFO - 'SHLVL': '1', 11:19:01 INFO - 'TERM': 'linux', 11:19:01 INFO - 'TMOUT': '86400', 11:19:01 INFO - 'USER': 'cltbld', 11:19:01 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:01 INFO - '_': '/tools/buildbot/bin/python'} 11:19:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:01 INFO - Downloading/unpacking blobuploader==1.2.4 11:19: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 11:19: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 11:19: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 11:19: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 11:19: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 11:19: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 11:19:03 INFO - Downloading blobuploader-1.2.4.tar.gz 11:19:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:19:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:19:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:04 INFO - Downloading docopt-0.6.1.tar.gz 11:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:19:04 INFO - Installing collected packages: blobuploader, requests, docopt 11:19:04 INFO - Running setup.py install for blobuploader 11:19:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:19:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:19:04 INFO - Running setup.py install for requests 11:19:05 INFO - Running setup.py install for docopt 11:19:05 INFO - Successfully installed blobuploader requests docopt 11:19:05 INFO - Cleaning up... 11:19:05 INFO - Return code: 0 11:19:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:05 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:05 INFO - Using env: {'DISPLAY': ':0', 11:19:05 INFO - 'HOME': '/home/cltbld', 11:19:05 INFO - 'LANG': 'en_US.UTF-8', 11:19:05 INFO - 'LANGUAGE': 'en_US:en', 11:19:05 INFO - 'LOGNAME': 'cltbld', 11:19:05 INFO - 'MAIL': '/var/mail/cltbld', 11:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:05 INFO - 'MOZ_NO_REMOTE': '1', 11:19:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:05 INFO - 'NO_EM_RESTART': '1', 11:19:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:05 INFO - 'PWD': '/builds/slave/test', 11:19:05 INFO - 'SHELL': '/bin/bash', 11:19:05 INFO - 'SHLVL': '1', 11:19:05 INFO - 'TERM': 'linux', 11:19:05 INFO - 'TMOUT': '86400', 11:19:05 INFO - 'USER': 'cltbld', 11:19:05 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:05 INFO - '_': '/tools/buildbot/bin/python'} 11:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:05 INFO - Running setup.py (path:/tmp/pip-4ZIGH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:05 INFO - Running setup.py (path:/tmp/pip-zu12Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:05 INFO - Running setup.py (path:/tmp/pip-D4enzn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:05 INFO - Running setup.py (path:/tmp/pip-0TvfE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:05 INFO - Running setup.py (path:/tmp/pip-HKMcpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:05 INFO - Running setup.py (path:/tmp/pip-wLp41C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:05 INFO - Running setup.py (path:/tmp/pip-ebrWmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:06 INFO - Running setup.py (path:/tmp/pip-sIVYpm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:06 INFO - Running setup.py (path:/tmp/pip-HxYy9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:06 INFO - Running setup.py (path:/tmp/pip-oy3Lps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:06 INFO - Running setup.py (path:/tmp/pip-kkl5dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:06 INFO - Running setup.py (path:/tmp/pip-IFCDOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:06 INFO - Running setup.py (path:/tmp/pip-prhniN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:06 INFO - Running setup.py (path:/tmp/pip-HcVLjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:06 INFO - Running setup.py (path:/tmp/pip-WTz5Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:06 INFO - Running setup.py (path:/tmp/pip-8D15nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:06 INFO - Running setup.py (path:/tmp/pip-bzyqzU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:19:06 INFO - Running setup.py install for manifestparser 11:19:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:19:06 INFO - Running setup.py install for mozcrash 11:19:06 INFO - Running setup.py install for mozdebug 11:19:07 INFO - Running setup.py install for mozdevice 11:19:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Running setup.py install for mozfile 11:19:07 INFO - Running setup.py install for mozhttpd 11:19:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Running setup.py install for mozinfo 11:19:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Running setup.py install for mozInstall 11:19:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Running setup.py install for mozleak 11:19:07 INFO - Running setup.py install for mozlog 11:19:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Running setup.py install for moznetwork 11:19:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:19:07 INFO - Running setup.py install for mozprocess 11:19:07 INFO - Running setup.py install for mozprofile 11:19:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:19:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:19:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:19:08 INFO - Running setup.py install for mozrunner 11:19:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:19:08 INFO - Running setup.py install for mozscreenshot 11:19:08 INFO - Running setup.py install for moztest 11:19:08 INFO - Running setup.py install for mozversion 11:19:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:19:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:19:08 INFO - Cleaning up... 11:19:08 INFO - Return code: 0 11:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:08 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:08 INFO - Using env: {'DISPLAY': ':0', 11:19:08 INFO - 'HOME': '/home/cltbld', 11:19:08 INFO - 'LANG': 'en_US.UTF-8', 11:19:08 INFO - 'LANGUAGE': 'en_US:en', 11:19:08 INFO - 'LOGNAME': 'cltbld', 11:19:08 INFO - 'MAIL': '/var/mail/cltbld', 11:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:08 INFO - 'MOZ_NO_REMOTE': '1', 11:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:08 INFO - 'NO_EM_RESTART': '1', 11:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:08 INFO - 'PWD': '/builds/slave/test', 11:19:08 INFO - 'SHELL': '/bin/bash', 11:19:08 INFO - 'SHLVL': '1', 11:19:08 INFO - 'TERM': 'linux', 11:19:08 INFO - 'TMOUT': '86400', 11:19:08 INFO - 'USER': 'cltbld', 11:19:08 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:08 INFO - '_': '/tools/buildbot/bin/python'} 11:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:19:08 INFO - Running setup.py (path:/tmp/pip-E7KlR6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:19:08 INFO - Running setup.py (path:/tmp/pip-fG88_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:19:08 INFO - Running setup.py (path:/tmp/pip-dhx0f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:19:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:19:08 INFO - Running setup.py (path:/tmp/pip-EnLM6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:19:09 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)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:19:09 INFO - Running setup.py (path:/tmp/pip-Yhp7hG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:19:09 INFO - Running setup.py (path:/tmp/pip-TztxIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:19:09 INFO - Running setup.py (path:/tmp/pip-yZAHBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:19:09 INFO - Running setup.py (path:/tmp/pip-fDG_yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:19:09 INFO - Running setup.py (path:/tmp/pip-KW16CE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:19:09 INFO - Running setup.py (path:/tmp/pip-IqfaNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:19:09 INFO - Running setup.py (path:/tmp/pip-15CgHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:19:09 INFO - Running setup.py (path:/tmp/pip-A0Uy2U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:19:09 INFO - Running setup.py (path:/tmp/pip-jwC69f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:19:09 INFO - Running setup.py (path:/tmp/pip-TcM4Ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:09 INFO - Running setup.py (path:/tmp/pip-dXliAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:19:09 INFO - Running setup.py (path:/tmp/pip-gFO2uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:19:09 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)) 11:19:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:19:09 INFO - Running setup.py (path:/tmp/pip-pCGdeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:19:10 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)) 11:19:10 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)) 11:19:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:19:11 INFO - Downloading blessings-1.6.tar.gz 11:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:19:12 INFO - Installing collected packages: blessings 11:19:12 INFO - Running setup.py install for blessings 11:19:12 INFO - Successfully installed blessings 11:19:12 INFO - Cleaning up... 11:19:12 INFO - Return code: 0 11:19:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:12 INFO - Reading from file tmpfile_stdout 11:19:12 INFO - Current package versions: 11:19:12 INFO - argparse == 1.2.1 11:19:12 INFO - blessings == 1.6 11:19:12 INFO - blobuploader == 1.2.4 11:19:12 INFO - docopt == 0.6.1 11:19:12 INFO - functools32 == 3.2.3-2 11:19:12 INFO - jsonschema == 2.5.1 11:19:12 INFO - manifestparser == 1.1 11:19:12 INFO - mozInstall == 1.12 11:19:12 INFO - mozcrash == 1.0 11:19:12 INFO - mozdebug == 0.1 11:19:12 INFO - mozdevice == 0.49 11:19:12 INFO - mozfile == 1.2 11:19:12 INFO - mozhttpd == 0.7 11:19:12 INFO - mozinfo == 0.9 11:19:12 INFO - mozleak == 0.1 11:19:12 INFO - mozlog == 3.4 11:19:12 INFO - moznetwork == 0.27 11:19:12 INFO - mozprocess == 0.23 11:19:12 INFO - mozprofile == 0.28 11:19:12 INFO - mozrunner == 6.13 11:19:12 INFO - mozscreenshot == 0.1 11:19:12 INFO - mozsystemmonitor == 0.3 11:19:12 INFO - moztest == 0.7 11:19:12 INFO - mozversion == 1.4 11:19:12 INFO - psutil == 3.1.1 11:19:12 INFO - requests == 1.2.3 11:19:12 INFO - wsgiref == 0.1.2 11:19:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:12 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:19:12 INFO - Using env: {'DISPLAY': ':0', 11:19:12 INFO - 'HOME': '/home/cltbld', 11:19:12 INFO - 'LANG': 'en_US.UTF-8', 11:19:12 INFO - 'LANGUAGE': 'en_US:en', 11:19:12 INFO - 'LOGNAME': 'cltbld', 11:19:12 INFO - 'MAIL': '/var/mail/cltbld', 11:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:12 INFO - 'MOZ_NO_REMOTE': '1', 11:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:12 INFO - 'NO_EM_RESTART': '1', 11:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:12 INFO - 'PWD': '/builds/slave/test', 11:19:12 INFO - 'SHELL': '/bin/bash', 11:19:12 INFO - 'SHLVL': '1', 11:19:12 INFO - 'TERM': 'linux', 11:19:12 INFO - 'TMOUT': '86400', 11:19:12 INFO - 'USER': 'cltbld', 11:19:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:12 INFO - '_': '/tools/buildbot/bin/python'} 11:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:19:12 INFO - Cleaning up... 11:19:12 INFO - Return code: 0 11:19:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:19:12 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 0x7f4470ce0e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1456b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15f2130>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, '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': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:19:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:19:12 INFO - Using env: {'DISPLAY': ':0', 11:19:12 INFO - 'HOME': '/home/cltbld', 11:19:12 INFO - 'LANG': 'en_US.UTF-8', 11:19:12 INFO - 'LANGUAGE': 'en_US:en', 11:19:12 INFO - 'LOGNAME': 'cltbld', 11:19:12 INFO - 'MAIL': '/var/mail/cltbld', 11:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:12 INFO - 'MOZ_NO_REMOTE': '1', 11:19:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:12 INFO - 'NO_EM_RESTART': '1', 11:19:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:12 INFO - 'PWD': '/builds/slave/test', 11:19:12 INFO - 'SHELL': '/bin/bash', 11:19:12 INFO - 'SHLVL': '1', 11:19:12 INFO - 'TERM': 'linux', 11:19:12 INFO - 'TMOUT': '86400', 11:19:12 INFO - 'USER': 'cltbld', 11:19:12 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:12 INFO - '_': '/tools/buildbot/bin/python'} 11:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:19:12 INFO - Cleaning up... 11:19:12 INFO - Return code: 0 11:19:12 INFO - Running post-action listener: _resource_record_post_action 11:19:12 INFO - Running post-action listener: _start_resource_monitoring 11:19:12 INFO - Starting resource monitoring. 11:19:12 INFO - [mozharness: 2016-11-22 19:19:12.935990Z] Finished create-virtualenv step (success) 11:19:12 INFO - [mozharness: 2016-11-22 19:19:12.936469Z] Running install step. 11:19:12 INFO - Running pre-action listener: _resource_record_pre_action 11:19:12 INFO - Running main action method: install 11:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:19:13 INFO - Reading from file tmpfile_stdout 11:19:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:19:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:19:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:19:13 INFO - Reading from file tmpfile_stdout 11:19:13 INFO - Output received: 11:19:13 INFO - Usage: mozinstall [options] installer 11:19:13 INFO - Options: 11:19:13 INFO - -h, --help show this help message and exit 11:19:13 INFO - -d DEST, --destination=DEST 11:19:13 INFO - Directory to install application into. [default: 11:19:13 INFO - "/builds/slave/test"] 11:19:13 INFO - --app=APP Application being installed. [default: firefox] 11:19:13 INFO - mkdir: /builds/slave/test/build/application 11:19:13 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'] 11:19:13 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 11:19:30 INFO - Reading from file tmpfile_stdout 11:19:30 INFO - Output received: 11:19:30 INFO - /builds/slave/test/build/application/firefox/firefox 11:19:30 INFO - Running post-action listener: _resource_record_post_action 11:19:30 INFO - [mozharness: 2016-11-22 19:19:30.253055Z] Finished install step (success) 11:19:30 INFO - [mozharness: 2016-11-22 19:19:30.253323Z] Running run-tests step. 11:19:30 INFO - Running pre-action listener: _resource_record_pre_action 11:19:30 INFO - Running main action method: run_tests 11:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:19:30 INFO - Python 2.7.3 11:19:30 INFO - Return code: 0 11:19:30 INFO - grabbing minidump binary from tooltool 11:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:19:30 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 0x1680fc0>, '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 0x1681ec0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1682350>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:19:30 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:19:30 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:19:30 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:19:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:19:30 INFO - Return code: 0 11:19:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:19:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:19:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:19:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:19:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 11:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-048 --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 11:19:30 INFO - Using env: {'DISPLAY': ':0', 11:19:30 INFO - 'HOME': '/home/cltbld', 11:19:30 INFO - 'LANG': 'en_US.UTF-8', 11:19:30 INFO - 'LANGUAGE': 'en_US:en', 11:19:30 INFO - 'LOGNAME': 'cltbld', 11:19:30 INFO - 'MAIL': '/var/mail/cltbld', 11:19:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:19:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:19:30 INFO - 'MOZ_NO_REMOTE': '1', 11:19:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:19:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:19:30 INFO - 'NO_EM_RESTART': '1', 11:19:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:19:30 INFO - 'PWD': '/builds/slave/test', 11:19:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:19:30 INFO - 'SHELL': '/bin/bash', 11:19:30 INFO - 'SHLVL': '1', 11:19:30 INFO - 'TERM': 'linux', 11:19:30 INFO - 'TMOUT': '86400', 11:19:30 INFO - 'USER': 'cltbld', 11:19:30 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219', 11:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:19:30 INFO - '_': '/tools/buildbot/bin/python'} 11:19:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--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 11:19:30 INFO - mozversion application_buildid: 20161122093201 11:19:30 INFO - mozversion application_changeset: d4792f52f4761029eb416632312c61d92cf85497 11:19:30 INFO - mozversion application_display_name: Nightly 11:19:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:19:30 INFO - mozversion application_name: Firefox 11:19:30 INFO - mozversion application_remotingname: firefox 11:19:30 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:19:30 INFO - mozversion application_vendor: Mozilla 11:19:30 INFO - mozversion application_version: 53.0a1 11:19:30 INFO - mozversion platform_buildid: 20161122093201 11:19:30 INFO - mozversion platform_changeset: d4792f52f4761029eb416632312c61d92cf85497 11:19:30 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 11:19:30 INFO - mozversion platform_version: 53.0a1 11:19:30 INFO - using testdate: 1479842370 11:19:30 INFO - actual date: 1479842370 11:19:30 INFO - starting webserver on 'localhost:56911' 11:19:30 INFO - SUITE-START | Running 2 tests 11:19:30 INFO - TEST-START | dromaeo_css 11:19:30 INFO - operating with platform_type : linux_ 11:19:30 INFO - Initialising browser for dromaeo_css test... 11:19:30 INFO - TEST-INFO | started process 4474 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplnu3WD/profile http://localhost:56911/getInfo.html) 11:19:34 INFO - PROCESS | 4474 | __metrics Screen width/height:1600/1200 11:19:34 INFO - PROCESS | 4474 | colorDepth:24 11:19:34 INFO - PROCESS | 4474 | Browser inner width/height: 1024/697 11:19:34 INFO - PROCESS | 4474 | __metrics 11:19:35 INFO - PROCESS | 4474 | 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" 11:19:35 INFO - PROCESS | 4474 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:19:36 INFO - PROCESS | 4474 | console.error: PushService: 11:19:36 INFO - PROCESS | 4474 | stateChangeProcessEnqueue: Error transitioning state 11:19:36 INFO - PROCESS | 4474 | UnknownError 11:19:36 INFO - TEST-INFO | 4474: exit 0 11:19:36 INFO - Browser initialized. 11:19:36 INFO - Running cycle 1/1 for dromaeo_css test... 11:19:36 INFO - TEST-INFO | started process 4558 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplnu3WD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:19:48 INFO - PROCESS | 4558 | RSS: Main: 173322240 11:19:48 INFO - PROCESS | 4558 | 11:20:40 INFO - PROCESS | 4558 | 1479842440596 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] 11:22:50 INFO - PROCESS | 4558 | Cycle 1(1): loaded http://localhost:56911/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56911/tests/dromaeo/cssquery-ext.html) 11:22:53 INFO - PROCESS | 4558 | RSS: Main: 396566528 11:22:53 INFO - PROCESS | 4558 | 11:25:56 INFO - PROCESS | 4558 | Cycle 1(1): loaded http://localhost:56911/tests/dromaeo/cssquery-ext.html (next: http://localhost:56911/tests/dromaeo/cssquery-jquery.html) 11:25:56 INFO - PROCESS | 4558 | RSS: Main: 285446144 11:25:56 INFO - PROCESS | 4558 | 11:28:58 INFO - PROCESS | 4558 | Cycle 1(1): loaded http://localhost:56911/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56911/tests/dromaeo/cssquery-mootools.html) 11:28:59 INFO - PROCESS | 4558 | RSS: Main: 289083392 11:28:59 INFO - PROCESS | 4558 | 11:32:01 INFO - PROCESS | 4558 | Cycle 1(1): loaded http://localhost:56911/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56911/tests/dromaeo/cssquery-prototype.html) 11:32:02 INFO - PROCESS | 4558 | RSS: Main: 224919552 11:32:02 INFO - PROCESS | 4558 | 11:35:04 INFO - PROCESS | 4558 | Cycle 1(1): loaded http://localhost:56911/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56911/tests/dromaeo/cssquery-yui.html) 11:35:05 INFO - PROCESS | 4558 | RSS: Main: 222449664 11:35:05 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | Cycle 1(1): loaded http://localhost:56911/tests/dromaeo/cssquery-yui.html (next: http://localhost:56911/tests/dromaeo/cssquery-dojo.html) 11:38:08 INFO - PROCESS | 4558 | RSS: Main: 288858112 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | __start_tp_report 11:38:08 INFO - PROCESS | 4558 | _x_x_mozilla_page_load 11:38:08 INFO - PROCESS | 4558 | _x_x_mozilla_page_load_details 11:38:08 INFO - PROCESS | 4558 | |i|pagename|runs| 11:38:08 INFO - PROCESS | 4558 | |0;dojo.html;3036.893708720195;3067.125869127298;3068.999999999999;3093.690630936905;3119.111053349796;5286.154215325549;5332.280142180804;5335.839924802252;5363.544098751606;5403.351597808269;5272.367315922094;5348.331458567679;5375.973120134394;5379.865503362418;5435.701036442994;4769.833055843029;4816.566509014188;4858.052679727454;4892.804287828482;4910.312556242129;4928.286852589641;4932.161532539477;4977.825776097838;4980.726060066695;5022.522860328263;4759.785809638574;4914.70511769295;4917.041176970492;4921.261810728383;5021.297018417425;4794.664373493857;4803.2554953981835;4850.830220942285;4862.805487780484;4888.657793954425;4872.829450969199;4879.756012199376;4880;4882.43851957026;4921.114199444099;5371.033214021474;5385.7307134643115;5394.217838413448;5395.811146609889;5401.459854014606;5596.608237423382;5628.212050312959;5636.520895723868;5711.457411545896;5768.480836724673;5721.113227449752;5733.455321744427;5743.54051675865;5745.396733342981;5747.281589801275;5280.419153893069;5333.57331413486;5334.5732341412595;5337.5996800239745;5339.385970613429;5783.450572195635;5801.931981478515;5854.4730974212525;5862.618929769551;5955.642661440241;5617.606767526234;5637.49262566371;5638.661680299195;5638.971805140948;5641.153826925994;5724.885502289931;5727.39862314451;5731.369549349568;5733.942660573341;5738.971305143531;5486.698231597221;5530.668159910419;5556.444355564411;5618.213450116987;5641.379448260688;5662.433756624305;5663.32040155184;5664.291963504562;5671.489565939003;5712.285964254468;4353.085851971058;4359.455068116486;4359.825606975685;4370.147821174841;4394.978025109854;3878.4182372644327;3881.514810648669;3903.355946268834;3906.7265291429326;3927.253821773854;5394.433584473573;5459.617826752089;5459.945400546023;5492.835214943558;5519.420460851553;5780.566457515703;5784.334801497797;5784.421557844266;5791.160281759121;5795.420457954255;5314.946850531445;5375.946240537544;5453.618246722692;5456.072467680504;5459.181122831607;4888.169011268093;4910.90178196434;4926.211806111005;4937.827176048893;4995.875103122451;5893.469587737124;5928.792492262923;5945.494632956161;5948.078047902582;5993;5706.629069110661;5718.399568045295;5737.426257374229;5740;5766.59633825645;5777.653340799566;5860.706964651836;5865.530757539472;5947.851303717442;5956.731947062479;5623.100303951348;5629.043062679436;5629.746661400165;5629.859253518531;5631.802886898939;5714.771409143587;5731.140328950691;5731.541476681939;5736.4550367715;5738.741756620879;5552.888942221214;5583.274174357308;5596.384397716167;5600.57995650336;5603.7758489659955;5586.888262234651;5596.384397716167;5596.580256480699;5597.832065038056;5598.608097433141;5599.80400685974;5621.325440947113;5624.043912534797;5629.634073785191;5652.717364131695;5474.6715197086705;5535.612507124462;5539.418361072029;5620.437956204347;5674.574406919415;5483.588730845123;5483.890322193454;5491.121420572689;5492.203630473638;5504.146857236975 11:38:08 INFO - PROCESS | 4558 | |1;ext.html;574.3710636852646;590.681032242589;593.6972144206457;605.8697380063288;620.5780069552708;27288.590671139948;29633.851830740816;29782.553261701065;29782.55326170109;41271.58728412715;41375;42212.155756884844;42303.78848105763;42347;42361;8474.279686226662;8624.094470080645;8625;8634;8677.913220867806;7783.338416234598;7978.481398709066;8509.361797865186;8597.5701214939;8601;7983.281504664579;8059.07320658123;10004.649837255698;10260.999105174495;10267.691969240936;10176.287659863812;10237.334573252714;10259.589616415335;10262.435566043865;10266.794664106676;10114.44370559619;10167.898321016843;10174;10176.389416635026;10186.847197292047;39387.40918886219;39616.20767584632;39678.20643587141;39710.801445992875;39728;3450.7929524228375;3478.147853775841;3517.3668739626855;3531.4173161428334;3607.9098022549383;27835.443291134266;30637.23406914845;30661.846690766626;30714.621391646902;30776.53835192474;4065.1014033566944;4070.4097905803787;4173.165366926627;4318.848840290575;4334.807927819843;342273;342781.14437711105;344380;344968.2751586251;346407;55484;55582.72208639012;55876.441235587125;55893;55958;60064;60444.69777651172;60562.39437605567;60564.69717651472;60656.69671651613;14944;15224.923875380553;15238.3904643813;15242.932994690265;15253.466128685443;11498.712532186595;11537.365444900443;11571.190016698918;11575.42122893852;11581.189316747746;16221;16402.917985409997;17109;18278.63442731138;18592.907035465007;6524.249711283168;6989.37095661393;6990.545614535141;7052.224255331909;7125.18060423058;963.677168148664;1019.2559431614962;1031.9742006449749;1047.1308813684623;1103.4041617526607;30647.34971794953;30892.845535772176;31185;31510.36979260402;31854.681453185636;1098.6209757633603;1139.3961200563715;1150.8446359741329;1182.0779791762438;1192.8687844337117;16234.675306493804;16290.185490725653;16361.427350042692;16405.507834764976;16500.6699866002;498091;502430;502550;502902;504841;47234.76382618065;48397.516024840006;52540;54028.72985635047;54465.45534544683;1305.3016636099642;1328.641266857943;1343.0598580993149;1355.241065003601;1374.5189183785994;29676.703232967393;31888;32143.35713285768;32306.1655005261;32640.673593263764;49618.25572616486;50065.74967125287;50136.49863501464;50145;50190;424704;754803;772553;776030;779558;40458.39312410375;40994.1801163981;41366.65650106695;41464.3780343289;41925.27485713419;46113;46289.305660414444;46544.53455465537;46612.30081548711;46946;39164.6083539161;39856.40215396713;40182;40578.391324129574;41521.37717934173;38717.6128238714;39620.801895990335;40062.599374007055;40172.598274016884;41190;38961.805190975;39071;39191.608083919935;39284.80357598194;39289;32406;35594;36092;36339;36463.63536364709;26698.332541686614;27062.594061089494;27336;27416.177514674593;27626.861865690542 11:38:08 INFO - PROCESS | 4558 | |2;jquery.html;359.68347853888577;373.3242830476837;391.6142599539453;396.20758483033933;414.61647975622543;14269.643258918532;15479.458218962338;18095.819041809576;18716;18721.06394680264;14136.434542618308;14163.504277350268;14224.07543509671;14231.288435578232;14279.857201427983;4059;5310.893782124355;5543.6119471637;5548.4174161713045;5552.333719953591;4161.250974824546;5825.912611310834;6076.665783381913;6119.724612392453;6226.533010024244;5972.641641501524;6133.264008319008;6168.228971378578;6185;6187.412195841411;6103.511719062487;6124.112003759475;6131.938680613226;6137.815865524041;6138.938610613882;5258.92111618326;5403.648762830443;5436.3476382834315;5438.809641662562;5440.455954404528;21110;21407.785922140734;21497.46256343588;21672.458188545254;21683.891580541997;2367.443650742074;2428.5992811186134;2431.3070774829266;2444.2667199839975;2801.9019334323407;9630.518474076338;10160.33957792741;13230.984365005048;13476.730465390736;14044.719105617933;1797.4877160009335;1820.8998505082216;1857.8049304823069;1862.3016368861677;1896.9525761855925;85243.5737821307;86778.5661071697;88354.55822720846;89631;90212;10591.940805919501;10717.303375280582;10801.675949721364;10912.945435272773;11139.220254582102;14150.716985660434;14155.504557340444;14155.716885662228;14189.716205676035;14195.14829110236;7726.304632583095;7910.16943220965;7910.9604451977375;7925.0886148093705;8169.5098294102545;7437.293457121565;7452.701891924262;7523.0972283326355;7717.807054823562;7782.6886924523515;4551.95448045522;4568.680192386501;4602.309653551994;4667.463241727177;4752.596029337476;1874.6719324118496;1951.5706544560173;1971.526833559964;2010.145688082559;2042.1320938601036;934.1545900959643;946.7112530678072;956.4548207521785;985.5417230987625;1064.11678307005;17641.294348226184;18702.81297187038;19500.70748938767;19739.40781776549;19992.90003550002;949.1742184299703;971.2521358554015;984.8768903887014;1003.4731765822855;1035.358077991635;9915.603375865028;9922.553485093189;10597.31117477377;10631.46842657864;11048.281861678965;419946;421110;422252;423782;436974;92124;96904;99908;100235;101093.49453252833;954.5561313989028;994.7811481474064;1021.310615334661;1023.9641612543526;1026.7484466305652;16544.83455165433;16623.3350665972;17088.487345379657;17375.304987800348;17555.210015549586;43357.34963975606;44338.77830610827;44743.89219880905;44873.326900095875;46172.45930488363;55755;55993.720031401215;60399.38831522666;61850.38149618446;61976;8412.663493460193;8445.239928406472;8498.490090594583;8571.314294856273;8704.086070962458;12200.633980980585;12373.13388062827;12386.690332741753;12392.087859353225;12793.680157996125;10386.740331491774;10428.061474467335;11149.163812713916;11182.552697891993;11454.656360309204;10034.939343500011;10151.187904967732;10952.56189752401;11043.558257669925;11165.944170279097;10802.135829133807;10868.61959831402;11285.266457680225;11511.654650360502;11611.709707257387;8398.496090234361;8498.447600905922;8501.247266181093;8639.524826134622;8742.60658270392;11228.326300422;11383.715407114889;11424.828627570427;11476.770464590829;11482.598109066143 11:38:08 INFO - PROCESS | 4558 | |3;mootools.html;1333.3333333333333;1400.6428360768002;1439.3954539093584;1527.244014212964;1713.3917459301947;7115.324044215804;7762.107357653871;8149.736790797436;8153.673853045871;8399.49603023819;8201.958990205041;8270.669173233078;8276.255137037666;8276.710315138971;8278.875816862737;2059.3615979046467;2124.1715730864976;2264.6603009548617;2291.4385975436;2327.8952447139836;2280.9201677941273;2294;2302.827287953402;2307.90768369265;2310.0066971202377;2261.4685548896077;2262.0386335807298;2275.590393729128;2291.232437133562;2293.2546922250253;2233.396982814631;2245.7305123385217;2254.357508110181;2259.9435014124615;2263.411513006622;2270.0692715986365;2286.588414085464;2287.8284128690248;2288.7253529576556;2293.117149897285;7998.560079195642;8076.192380761936;8079.798005049862;8092.676292948275;8145.307648849855;4954.653174277802;5088.516590923857;5091.363579552556;5092.51621095999;5094.490550944913;2968.3172870239964;3060.6786287439945;3185.2196211927994;3194.4250034993693;3204.5193221016802;1037.0821822686976;1050.732063323855;1053.467998660679;1055.3298655353906;1098.417838545572;10872.402017889013;10902.61840835575;10989.505472253768;11002.284851484708;11093.778124437466;4383.430154080013;4424.778761061934;4539.228331183706;4540.659450541222;4615.16926953145;9485.486346118285;9504.572294246775;9527.761805954768;9531.285153613506;9536.189423899046;5078.035173317058;5118;5118.129917913889;5130.615203859726;5135.075686376414;4266.807993640294;4405.31717583775;4414.933775993362;4676.906461870743;4738;3712.3874560697723;3815.6756675682323;3839.5968423315708;3891.377379619247;3922.627350401707;1278.26499762635;1279.575204986564;1292.6768307923019;1298.0718786068007;1314.8093526438613;925.5742358515158;928.9396189247677;942.731321573362;947.4031360243141;981.3375971219541;5440.646357986719;5528.364238112588;5578.9163162552595;5615.831525054255;5829.09649004405;441.5099239843771;446.941897553316;457.487613872465;466.23537398665536;472.7210945542147;3789.810509474515;3848.6536211741077;3889.3776995680555;3906.277338692351;3948.3287841067086;9645.662401815904;10014.098731114089;10080.495975201211;10098.848517272247;10100.898991009995;9788.510574471391;9836.311458197857;9924.652637157666;9995.650152244636;10053.296269261082;7580.166181720117;7647.235276472308;7737.8452430951065;7747.147813740484;7749.302562769265;5449.809256675997;5526.198701188225;5551.361593416647;5602.887942241051;5698;9098.090190980849;9173.40372875761;9269.72190834276;9324.207442367475;9338.206252468344;9106.772330691785;9295.86056209171;9392.201662858733;9486.667966621135;9492.620295188115;6554.508411869034;6810.6594670265295;6936.167659880846;7279;7399.186089530255;7571.318581327707;7952.36381089523;7957.1644977276555;7960.243776841191;7967.322777563972;6848.623325717133;7029.121359830021;7164.426845852424;7255.854882902418;7260.67326970277;6664.8000559983275;6734.696938637675;6971.616561088986;7033.683484243119;7060.117485314336;6126.6017708848785;6298.0867774171575;6340;6355.872882542416;6359.841003974938;4773.09311230865;4825.95174048255;4827.951720482891;4882.145624515767;4954.677945933503;4568.269076947672;4648.767561621838;4697.459792123949;4720.881977950441;4860.781264843161 11:38:08 INFO - PROCESS | 4558 | |4;prototype.html;319.37721443185785;324.5008062750093;328.81914946779267;333.2119537294298;337.1604704286328;5030.396352437712;5185.9222111668305;5187.792488300468;5207.140821764409;5257.5531079858165;4965.875853103674;5128.512791284822;5154.948450515493;5169.508896654812;5181.222816577506;4086.816093275795;4094.5086589609286;4102.589741025903;4105.322621767405;4119;3914.5693973662874;4034.69739769517;4097.242010228102;4111.568285330042;4121;4007.4790277263915;4045.292073887058;4049.2104039712267;4053;4057.431959525669;4023.75857448554;4027.818748156311;4041.252368311857;4041.5554289028332;4075.1442197138635;3591.7844929304324;3602;3606.3328284267495;3607.7474576779637;3628.9455658165148;4980.576650984671;5034;5038.521340472649;5044.798208071673;5069.746512674352;4592.632589392841;4608.746518941457;4618.930716039262;4623.75747762335;4629.7916593753025;5404.972975135139;5456.836294911159;5463.535599474049;5466.808661696861;5467.644603100785;3843.961560384388;3872.1481274119647;3873.86441474547;3882.4176373544196;3885.417187421881;5550.666959982414;5579.023670857624;5606.411326810667;5632.239647647556;5634.211210430502;3980.124372638015;3983.7609743415487;3988.481497405319;3995.4606128172327;4036.4348991141264;3949.5458022327225;4029.3351596986754;4089.2026054919006;4089.8364065437645;4091.6931230157857;4586.954130458719;4594.816207351669;4594.88512787183;4595.195840727859;4602.401687780634;5423.701696406735;5424.593155513352;5443.673379597237;5465.754041068161;5468.125099983983;1296.7665820152274;1305.4712841299288;1329.2755448280711;1351.7161396106928;1359.6396954806985;922.2437601166979;935.7754139006726;944.367273926471;944.6976967370375;950.254050570299;4993.554447431261;4993.70037797726;4996.075725990703;5004.3994720633755;5029.597632189416;5285.788568457296;5343.786248550092;5351;5361.544268737201;5367.731613419313;4723.622110231173;4898.755062246944;4955.40535135786;4973.42805577356;4977.601791856643;2770.3074231442138;2819.5629677400034;2821.5187026810677;2822.7036161203187;2830.731080547345;5436.401995780461;5462.289902312674;5465.699386533699;5477.972610136924;5534.501894829405;5349.893002139936;5433.565314774809;5450.91823622646;5689.374168841424;5693.889691510199;5804.171685918922;5804.912926306109;5806.303243610793;5816.41835816415;5827.358990511125;5478.424765399575;5602.607817452739;5622;5632.070708333079;5690.231818704588;5510.531604813636;5517.613767036269;5548.55611551083;5549.472800083986;5555.611107222456;5776.884462310814;5778.16216648592;5811.535077193899;5829.650220986754;5846.298444186725;3878.786666733357;3994.520657521;4022.6178513041214;4041.1109555897656;4061.553229144851;5390.245365648891;5595.916061259003;5639.971800140973;5643.391633384465;5661.188419705654;5323.680579165262;5409.945900541102;5482.095454250004;5490.725463726878;5493.7802487900035;5472.823342981278;5484.396716361117;5499.807506737245;5503.394626591152;5512.6968016759465;5289.761960711854;5438.483344082306;5459.836204913859;5466.426025267289;5475.288212532345;5405.189221616632;5438.265834112344;5456.508914197741;5505.642133261325;5509.917351239655;5352.759125839269;5395.3525576931015;5404.324659355455;5416.643759964428;5421.268128802719 11:38:08 INFO - PROCESS | 4558 | |5;yui.html;737.6053811211001;745.6160077560056;746.1419367727113;762.8245503534187;810.5825499867573;15087.321070551825;15881.205939702997;15916.204189790493;15924.920375398107;15976.600584985381;14162.575122746308;14331.426742930296;14337.283135843218;14356.210408427532;14363.497277595287;649.1755470552396;671.8857794174977;744.9739259125931;767.7619937819265;783.9764807055769;781.0978320040363;790.8971833661616;791.0941971442695;791.1890312429748;791.3589992106383;829.4608504472081;832.4006715165071;833.0295206084904;833.1002517281322;833.1501868094571;674.9190097188368;703.4372501998432;800.8758642410378;821.4578378270343;828.2793969246734;599.6252342286072;602.9276486821566;603.5081408651979;604.1391017799618;604.2024527623539;14083.788743168861;14135.78796318056;14142.646433839134;14143.080699754482;14150.858491415058;12950.744513325539;13245;13285.86714132856;13356.73286534264;13588.32058397086;11779.587714429954;11883.762324753543;12048.518059277618;12127.696807579792;12212.206206596631;4120.299549076664;4184.267753143188;4216.746995180299;4220.78896055196;4246.617804397588;64333.67833160851;64672.67663661698;64894.675526622064;65894.3410565893;66078.66960665214;9220.907790922005;9793.384325022378;9800.509974501247;10026.248031397674;10137.087662110445;17996.55008624795;18027;18034.909825450788;18037.27850885976;18055.09724513769;7789.143194248628;7857.567833769198;7912.881306780403;8031.317338026216;8175.3459723222;4408.559144085566;4438.800253988578;4466.508684044752;4501.954980450219;4528.6150677192145;3141.167590588496;3186.7769256151846;3200.0399880035898;3207.4066297734994;3226.9838650806596;584.5382148102951;592.4963780786297;616.6331033035336;621.9844503887349;625.1310678157365;680.9591424514545;698.4970821008866;715.8747219236612;743.7917383132732;744.3338212299961;8514.318854491627;8608.48349099056;9048.559646334794;9063.954680226556;9934.006599340153;705.3264132753209;706.4772068669148;709.5281637710967;714.8427345983876;721.469719755979;4064.878053658395;4381.605655491021;4400.867973960786;4469.69242760355;4496.685232033727;84541;85694.14305856987;86436.2207401337;89210;92536;78100;79073.20926790658;79110.60444697854;79411.60294198492;84374;70532.58934821274;70709.64645176742;70753;71735.64132179305;73280.34393121397;8349;8558.614862331335;8584.828303433771;8590.91409085901;8634.511173809904;47838;47889;48818.26772598343;49455.75272123616;49480.75259623679;57992.71003644955;61822.69088654528;62029.68985155045;63820.68089659522;65028.34971650412;11246.493907774005;11260.83108753353;11384.829227561428;11421.657350279505;11733.823992639946;12148.514059437524;12329.938350308192;12339.321337326533;12664.113512054437;13238.073334866467;11063.290261550957;11144.33134011962;11512.712182195177;11980.58067967617;12364.4436000382;11582.6525204244;11859.52561897549;12354.973031607315;12682.365881705693;12722.427490762753;11627.418629068681;11671.649850504498;11751.294922304689;12149.088818338483;12234.632961011184;10092.192624590161;10096.34373765703;10141.797164056825;10334.173266138541;10446;10475.633352832614;10544.367337959748;10546.26176167661;10587.788244234918;10637.574497020341 11:38:08 INFO - PROCESS | 4558 | __end_tp_report 11:38:08 INFO - PROCESS | 4558 | __start_cc_report 11:38:08 INFO - PROCESS | 4558 | _x_x_mozilla_cycle_collect,2408 11:38:08 INFO - PROCESS | 4558 | __end_cc_report 11:38:08 INFO - PROCESS | 4558 | __startTimestamp1479843488734__endTimestamp 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | ------- Summary: start ------- 11:38:08 INFO - PROCESS | 4558 | Number of tests: 6 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | [#0] dojo.html Cycles:165 Average:5317.09 Median:5530.67 stddev:600.94 (10.9%) stddev-sans-first:575.55 11:38:08 INFO - PROCESS | 4558 | Values: 3036.9 3067.1 3069.0 3093.7 3119.1 5286.2 5332.3 5335.8 5363.5 5403.4 5272.4 5348.3 5376.0 5379.9 5435.7 4769.8 4816.6 4858.1 4892.8 4910.3 4928.3 4932.2 4977.8 4980.7 5022.5 4759.8 4914.7 4917.0 4921.3 5021.3 4794.7 4803.3 4850.8 4862.8 4888.7 4872.8 4879.8 4880.0 4882.4 4921.1 5371.0 5385.7 5394.2 5395.8 5401.5 5596.6 5628.2 5636.5 5711.5 5768.5 5721.1 5733.5 5743.5 5745.4 5747.3 5280.4 5333.6 5334.6 5337.6 5339.4 5783.5 5801.9 5854.5 5862.6 5955.6 5617.6 5637.5 5638.7 5639.0 5641.2 5724.9 5727.4 5731.4 5733.9 5739.0 5486.7 5530.7 5556.4 5618.2 5641.4 5662.4 5663.3 5664.3 5671.5 5712.3 4353.1 4359.5 4359.8 4370.1 4395.0 3878.4 3881.5 3903.4 3906.7 3927.3 5394.4 5459.6 5459.9 5492.8 5519.4 5780.6 5784.3 5784.4 5791.2 5795.4 5314.9 5375.9 5453.6 5456.1 5459.2 4888.2 4910.9 4926.2 4937.8 4995.9 5893.5 5928.8 5945.5 5948.1 5993.0 5706.6 5718.4 5737.4 5740.0 5766.6 5777.7 5860.7 5865.5 5947.9 5956.7 5623.1 5629.0 5629.7 5629.9 5631.8 5714.8 5731.1 5731.5 5736.5 5738.7 5552.9 5583.3 5596.4 5600.6 5603.8 5586.9 5596.4 5596.6 5597.8 5598.6 5599.8 5621.3 5624.0 5629.6 5652.7 5474.7 5535.6 5539.4 5620.4 5674.6 5483.6 5483.9 5491.1 5492.2 5504.1 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | [#1] ext.html Cycles:180 Average:65759.23 Median:39238.21 stddev:145467.36 (370.7%) stddev-sans-first:145793.11 11:38:08 INFO - PROCESS | 4558 | Values: 574.4 590.7 593.7 605.9 620.6 27288.6 29633.9 29782.6 29782.6 41271.6 41375.0 42212.2 42303.8 42347.0 42361.0 8474.3 8624.1 8625.0 8634.0 8677.9 7783.3 7978.5 8509.4 8597.6 8601.0 7983.3 8059.1 10004.6 10261.0 10267.7 10176.3 10237.3 10259.6 10262.4 10266.8 10114.4 10167.9 10174.0 10176.4 10186.8 39387.4 39616.2 39678.2 39710.8 39728.0 3450.8 3478.1 3517.4 3531.4 3607.9 27835.4 30637.2 30661.8 30714.6 30776.5 4065.1 4070.4 4173.2 4318.8 4334.8 342273.0 342781.1 344380.0 344968.3 346407.0 55484.0 55582.7 55876.4 55893.0 55958.0 60064.0 60444.7 60562.4 60564.7 60656.7 14944.0 15224.9 15238.4 15242.9 15253.5 11498.7 11537.4 11571.2 11575.4 11581.2 16221.0 16402.9 17109.0 18278.6 18592.9 6524.2 6989.4 6990.5 7052.2 7125.2 963.7 1019.3 1032.0 1047.1 1103.4 30647.3 30892.8 31185.0 31510.4 31854.7 1098.6 1139.4 1150.8 1182.1 1192.9 16234.7 16290.2 16361.4 16405.5 16500.7 498091.0 502430.0 502550.0 502902.0 504841.0 47234.8 48397.5 52540.0 54028.7 54465.5 1305.3 1328.6 1343.1 1355.2 1374.5 29676.7 31888.0 32143.4 32306.2 32640.7 49618.3 50065.7 50136.5 50145.0 50190.0 424704.0 754803.0 772553.0 776030.0 779558.0 40458.4 40994.2 41366.7 41464.4 41925.3 46113.0 46289.3 46544.5 46612.3 46946.0 39164.6 39856.4 40182.0 40578.4 41521.4 38717.6 39620.8 40062.6 40172.6 41190.0 38961.8 39071.0 39191.6 39284.8 39289.0 32406.0 35594.0 36092.0 36339.0 36463.6 26698.3 27062.6 27336.0 27416.2 27626.9 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | [#2] jquery.html Cycles:180 Average:27313.58 Median:366.50 stddev:70876.72 (19338.6%) stddev-sans-first:71046.65 11:38:08 INFO - PROCESS | 4558 | Values: 359.7 373.3 391.6 396.2 414.6 14269.6 15479.5 18095.8 18716.0 18721.1 14136.4 14163.5 14224.1 14231.3 14279.9 4059.0 5310.9 5543.6 5548.4 5552.3 4161.3 5825.9 6076.7 6119.7 6226.5 5972.6 6133.3 6168.2 6185.0 6187.4 6103.5 6124.1 6131.9 6137.8 6138.9 5258.9 5403.6 5436.3 5438.8 5440.5 21110.0 21407.8 21497.5 21672.5 21683.9 2367.4 2428.6 2431.3 2444.3 2801.9 9630.5 10160.3 13231.0 13476.7 14044.7 1797.5 1820.9 1857.8 1862.3 1897.0 85243.6 86778.6 88354.6 89631.0 90212.0 10591.9 10717.3 10801.7 10912.9 11139.2 14150.7 14155.5 14155.7 14189.7 14195.1 7726.3 7910.2 7911.0 7925.1 8169.5 7437.3 7452.7 7523.1 7717.8 7782.7 4552.0 4568.7 4602.3 4667.5 4752.6 1874.7 1951.6 1971.5 2010.1 2042.1 934.2 946.7 956.5 985.5 1064.1 17641.3 18702.8 19500.7 19739.4 19992.9 949.2 971.3 984.9 1003.5 1035.4 9915.6 9922.6 10597.3 10631.5 11048.3 419946.0 421110.0 422252.0 423782.0 436974.0 92124.0 96904.0 99908.0 100235.0 101093.5 954.6 994.8 1021.3 1024.0 1026.7 16544.8 16623.3 17088.5 17375.3 17555.2 43357.3 44338.8 44743.9 44873.3 46172.5 55755.0 55993.7 60399.4 61850.4 61976.0 8412.7 8445.2 8498.5 8571.3 8704.1 12200.6 12373.1 12386.7 12392.1 12793.7 10386.7 10428.1 11149.2 11182.6 11454.7 10034.9 10151.2 10952.6 11043.6 11165.9 10802.1 10868.6 11285.3 11511.7 11611.7 8398.5 8498.4 8501.2 8639.5 8742.6 11228.3 11383.7 11424.8 11476.8 11482.6 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | [#3] mootools.html Cycles:180 Average:5372.62 Median:5016.36 stddev:2985.72 (59.5%) stddev-sans-first:2978.66 11:38:08 INFO - PROCESS | 4558 | Values: 1333.3 1400.6 1439.4 1527.2 1713.4 7115.3 7762.1 8149.7 8153.7 8399.5 8202.0 8270.7 8276.3 8276.7 8278.9 2059.4 2124.2 2264.7 2291.4 2327.9 2280.9 2294.0 2302.8 2307.9 2310.0 2261.5 2262.0 2275.6 2291.2 2293.3 2233.4 2245.7 2254.4 2259.9 2263.4 2270.1 2286.6 2287.8 2288.7 2293.1 7998.6 8076.2 8079.8 8092.7 8145.3 4954.7 5088.5 5091.4 5092.5 5094.5 2968.3 3060.7 3185.2 3194.4 3204.5 1037.1 1050.7 1053.5 1055.3 1098.4 10872.4 10902.6 10989.5 11002.3 11093.8 4383.4 4424.8 4539.2 4540.7 4615.2 9485.5 9504.6 9527.8 9531.3 9536.2 5078.0 5118.0 5118.1 5130.6 5135.1 4266.8 4405.3 4414.9 4676.9 4738.0 3712.4 3815.7 3839.6 3891.4 3922.6 1278.3 1279.6 1292.7 1298.1 1314.8 925.6 928.9 942.7 947.4 981.3 5440.6 5528.4 5578.9 5615.8 5829.1 441.5 446.9 457.5 466.2 472.7 3789.8 3848.7 3889.4 3906.3 3948.3 9645.7 10014.1 10080.5 10098.8 10100.9 9788.5 9836.3 9924.7 9995.7 10053.3 7580.2 7647.2 7737.8 7747.1 7749.3 5449.8 5526.2 5551.4 5602.9 5698.0 9098.1 9173.4 9269.7 9324.2 9338.2 9106.8 9295.9 9392.2 9486.7 9492.6 6554.5 6810.7 6936.2 7279.0 7399.2 7571.3 7952.4 7957.2 7960.2 7967.3 6848.6 7029.1 7164.4 7255.9 7260.7 6664.8 6734.7 6971.6 7033.7 7060.1 6126.6 6298.1 6340.0 6355.9 6359.8 4773.1 4826.0 4828.0 4882.1 4954.7 4568.3 4648.8 4697.5 4720.9 4860.8 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | [#4] prototype.html Cycles:180 Average:4556.42 Median:5183.57 stddev:1340.02 (25.9%) stddev-sans-first:1305.50 11:38:08 INFO - PROCESS | 4558 | Values: 319.4 324.5 328.8 333.2 337.2 5030.4 5185.9 5187.8 5207.1 5257.6 4965.9 5128.5 5154.9 5169.5 5181.2 4086.8 4094.5 4102.6 4105.3 4119.0 3914.6 4034.7 4097.2 4111.6 4121.0 4007.5 4045.3 4049.2 4053.0 4057.4 4023.8 4027.8 4041.3 4041.6 4075.1 3591.8 3602.0 3606.3 3607.7 3628.9 4980.6 5034.0 5038.5 5044.8 5069.7 4592.6 4608.7 4618.9 4623.8 4629.8 5405.0 5456.8 5463.5 5466.8 5467.6 3844.0 3872.1 3873.9 3882.4 3885.4 5550.7 5579.0 5606.4 5632.2 5634.2 3980.1 3983.8 3988.5 3995.5 4036.4 3949.5 4029.3 4089.2 4089.8 4091.7 4587.0 4594.8 4594.9 4595.2 4602.4 5423.7 5424.6 5443.7 5465.8 5468.1 1296.8 1305.5 1329.3 1351.7 1359.6 922.2 935.8 944.4 944.7 950.3 4993.6 4993.7 4996.1 5004.4 5029.6 5285.8 5343.8 5351.0 5361.5 5367.7 4723.6 4898.8 4955.4 4973.4 4977.6 2770.3 2819.6 2821.5 2822.7 2830.7 5436.4 5462.3 5465.7 5478.0 5534.5 5349.9 5433.6 5450.9 5689.4 5693.9 5804.2 5804.9 5806.3 5816.4 5827.4 5478.4 5602.6 5622.0 5632.1 5690.2 5510.5 5517.6 5548.6 5549.5 5555.6 5776.9 5778.2 5811.5 5829.7 5846.3 3878.8 3994.5 4022.6 4041.1 4061.6 5390.2 5595.9 5640.0 5643.4 5661.2 5323.7 5409.9 5482.1 5490.7 5493.8 5472.8 5484.4 5499.8 5503.4 5512.7 5289.8 5438.5 5459.8 5466.4 5475.3 5405.2 5438.3 5456.5 5505.6 5509.9 5352.8 5395.4 5404.3 5416.6 5421.3 11:38:08 INFO - PROCESS | 4558 | 11:38:08 INFO - PROCESS | 4558 | [#5] yui.html Cycles:180 Average:17810.20 Median:49468.25 stddev:24153.63 (48.8%) stddev-sans-first:24187.37 11:38:08 INFO - PROCESS | 4558 | Values: 737.6 745.6 746.1 762.8 810.6 15087.3 15881.2 15916.2 15924.9 15976.6 14162.6 14331.4 14337.3 14356.2 14363.5 649.2 671.9 745.0 767.8 784.0 781.1 790.9 791.1 791.2 791.4 829.5 832.4 833.0 833.1 833.2 674.9 703.4 800.9 821.5 828.3 599.6 602.9 603.5 604.1 604.2 14083.8 14135.8 14142.6 14143.1 14150.9 12950.7 13245.0 13285.9 13356.7 13588.3 11779.6 11883.8 12048.5 12127.7 12212.2 4120.3 4184.3 4216.7 4220.8 4246.6 64333.7 64672.7 64894.7 65894.3 66078.7 9220.9 9793.4 9800.5 10026.2 10137.1 17996.6 18027.0 18034.9 18037.3 18055.1 7789.1 7857.6 7912.9 8031.3 8175.3 4408.6 4438.8 4466.5 4502.0 4528.6 3141.2 3186.8 3200.0 3207.4 3227.0 584.5 592.5 616.6 622.0 625.1 681.0 698.5 715.9 743.8 744.3 8514.3 8608.5 9048.6 9064.0 9934.0 705.3 706.5 709.5 714.8 721.5 4064.9 4381.6 4400.9 4469.7 4496.7 84541.0 85694.1 86436.2 89210.0 92536.0 78100.0 79073.2 79110.6 79411.6 84374.0 70532.6 70709.6 70753.0 71735.6 73280.3 8349.0 8558.6 8584.8 8590.9 8634.5 47838.0 47889.0 48818.3 49455.8 49480.8 57992.7 61822.7 62029.7 63820.7 65028.3 11246.5 11260.8 11384.8 11421.7 11733.8 12148.5 12329.9 12339.3 12664.1 13238.1 11063.3 11144.3 11512.7 11980.6 12364.4 11582.7 11859.5 12355.0 12682.4 12722.4 11627.4 11671.6 11751.3 12149.1 12234.6 10092.2 10096.3 10141.8 10334.2 10446.0 10475.6 10544.4 10546.3 10587.8 10637.6 11:38:08 INFO - PROCESS | 4558 | -------- Summary: end -------- 11:38:08 INFO - PROCESS | 4558 | 11:38:09 INFO - TEST-INFO | 4558: exit 0 11:38:09 INFO - TEST-OK | dromaeo_css | took 1119134ms 11:38:09 INFO - TEST-START | kraken 11:38:09 INFO - operating with platform_type : linux_ 11:38:09 INFO - Initialising browser for kraken test... 11:38:09 INFO - TEST-INFO | started process 4859 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbDc8qR/profile http://localhost:56911/getInfo.html) 11:38:13 INFO - PROCESS | 4859 | __metrics Screen width/height:1600/1200 11:38:13 INFO - PROCESS | 4859 | colorDepth:24 11:38:13 INFO - PROCESS | 4859 | Browser inner width/height: 1024/697 11:38:13 INFO - PROCESS | 4859 | __metrics 11:38:14 INFO - PROCESS | 4859 | 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" 11:38:14 INFO - PROCESS | 4859 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:38:14 INFO - PROCESS | 4859 | console.error: 11:38:14 INFO - PROCESS | 4859 | Message: Error: Connection closed before committing the transaction. 11:38:14 INFO - PROCESS | 4859 | Stack: 11:38:14 INFO - PROCESS | 4859 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:932:23 11:38:14 INFO - PROCESS | 4859 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:813:7 11:38:14 INFO - PROCESS | 4859 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:744:11 11:38:14 INFO - PROCESS | 4859 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:776:7 11:38:14 INFO - PROCESS | 4859 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:711:7 11:38:14 INFO - PROCESS | 4859 | TaskImpl_run@resource://gre/modules/Task.jsm:324:15 11:38:14 INFO - PROCESS | 4859 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:550:9 11:38:14 INFO - PROCESS | 4859 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 11:38:14 INFO - PROCESS | 4859 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:386:5 11:38:14 INFO - PROCESS | 4859 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 11:38:14 INFO - PROCESS | 4859 | Barrier/this.client.addBlocker/promise13.1% 11:39:12 INFO - TinderboxPrint: I/O read bytes / time
16,384 / 404 11:39:12 INFO - TinderboxPrint: I/O write bytes / time
411,967,488 / 401,624 11:39:12 INFO - TinderboxPrint: CPU idle
8,312.8 (86.9%) 11:39:12 INFO - TinderboxPrint: CPU user
1,202.6 (12.6%) 11:39:12 INFO - TinderboxPrint: Swap in / out
0 / 0 11:39:12 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 104112128; Read time: 0; Write time: 146368 11:39:12 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 12288; Write bytes: 287850496; Read time: 388; Write time: 244648 11:39:12 INFO - Running post-run listener: _upload_blobber_files 11:39:12 INFO - Blob upload gear active. 11:39:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:39:12 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:39:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:39:13 INFO - (blobuploader) - INFO - Open directory for files ... 11:39:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:39:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:39:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:19 INFO - (blobuploader) - INFO - Done attempting. 11:39:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:39:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:39:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:20 INFO - (blobuploader) - INFO - Done attempting. 11:39:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:39:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:39:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:39:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:39:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:39:21 INFO - (blobuploader) - INFO - Done attempting. 11:39:21 INFO - (blobuploader) - INFO - Iteration through files over. 11:39:21 INFO - Return code: 0 11:39:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:39:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:39:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c39e20e11cee0cf7a38f8bba82dc14432fd7d55166b5ff4edaa3a93f892cd40290611ca9c5f4d3b19318160755251a4a40c77be3a6c432c1071b0d8067d88ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/78b5a8f872145f835b2201ffaba6508d86d9b294aa56058cf160720d85ab5910d5f7a1f4b3bdee4aafb0a62c5455f2cadde5166bb4049d7d6970a454b7e7c4ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c666cd1211a29ac03f8ec59e8222e005e8b51da3699eca3464b4619eecafc2a754038740c104004116c2fd85c407364f277884cc017ad10f1f51ced5aa2304be"} 11:39:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:39:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:39:21 INFO - Contents: 11:39:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c39e20e11cee0cf7a38f8bba82dc14432fd7d55166b5ff4edaa3a93f892cd40290611ca9c5f4d3b19318160755251a4a40c77be3a6c432c1071b0d8067d88ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/78b5a8f872145f835b2201ffaba6508d86d9b294aa56058cf160720d85ab5910d5f7a1f4b3bdee4aafb0a62c5455f2cadde5166bb4049d7d6970a454b7e7c4ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c666cd1211a29ac03f8ec59e8222e005e8b51da3699eca3464b4619eecafc2a754038740c104004116c2fd85c407364f277884cc017ad10f1f51ced5aa2304be"} 11:39:21 INFO - Running post-run listener: copy_logs_to_upload_dir 11:39:21 INFO - Copying logs to upload dir... 11:39:21 INFO - mkdir: /builds/slave/test/build/upload/logs 11:39:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.240019 ========= master_lag: 0.35 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-11-22 11:39:21.068057) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 11:39:21.076828) ========= 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=fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c39e20e11cee0cf7a38f8bba82dc14432fd7d55166b5ff4edaa3a93f892cd40290611ca9c5f4d3b19318160755251a4a40c77be3a6c432c1071b0d8067d88ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/78b5a8f872145f835b2201ffaba6508d86d9b294aa56058cf160720d85ab5910d5f7a1f4b3bdee4aafb0a62c5455f2cadde5166bb4049d7d6970a454b7e7c4ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c666cd1211a29ac03f8ec59e8222e005e8b51da3699eca3464b4619eecafc2a754038740c104004116c2fd85c407364f277884cc017ad10f1f51ced5aa2304be"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011687 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1479835921/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/0c39e20e11cee0cf7a38f8bba82dc14432fd7d55166b5ff4edaa3a93f892cd40290611ca9c5f4d3b19318160755251a4a40c77be3a6c432c1071b0d8067d88ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/78b5a8f872145f835b2201ffaba6508d86d9b294aa56058cf160720d85ab5910d5f7a1f4b3bdee4aafb0a62c5455f2cadde5166bb4049d7d6970a454b7e7c4ee", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/c666cd1211a29ac03f8ec59e8222e005e8b51da3699eca3464b4619eecafc2a754038740c104004116c2fd85c407364f277884cc017ad10f1f51ced5aa2304be"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 11:39:21.195138) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:39:21.195525) ========= 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=fa44defc139c4ba8574fe040000001db-1479842156.924475-688507219 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 11:39:21.255758) ========= ========= Started reboot skipped (results: 3, elapsed: 1 secs) (at 2016-11-22 11:39:21.256041) ========= ========= Finished reboot skipped (results: 3, elapsed: 1 secs) (at 2016-11-22 11:39:22.877150) ========= ========= Total master_lag: 0.57 =========