builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-063 starttime: 1478192290.99 results: success (0) buildid: 20161103074637 builduid: ed77576b926642969a0949e8bfc1edd5 revision: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:10.991977) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:10.992423) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:10.992710) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:11.007641) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:11.007983) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-03 09:58:11-- 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% 65.9M=0s 2016-11-03 09:58:11 (65.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.105457 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:11.127249) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:11.127648) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016706 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:11.176592) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-03 09:58:11.176974) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 --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 ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 --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=769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-03 09:58:11,436 truncating revision to first 12 chars 2016-11-03 09:58:11,436 Setting DEBUG logging. 2016-11-03 09:58:11,436 attempt 1/10 2016-11-03 09:58:11,436 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/ec5ec8b7f05c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-03 09:58:12,405 unpacking tar archive at: mozilla-inbound-ec5ec8b7f05c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.266566 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-03 09:58:12.457097) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:12.457412) ========= script_repo_revision: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:12.457803) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:12.458067) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-03 09:58:12.467621) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-03 09:58:12.468217) ========= /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=769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:58:12 INFO - MultiFileLogger online at 20161103 09:58:12 in /builds/slave/test 09:58:12 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 09:58:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:12 INFO - {'append_to_log': False, 09:58:12 INFO - 'base_work_dir': '/builds/slave/test', 09:58:12 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 09:58:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:12 INFO - 'branch': 'Mozilla-Inbound', 09:58:12 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:12 INFO - 'config_files': ('talos/linux_config.py',), 09:58:12 INFO - 'default_actions': ('clobber', 09:58:12 INFO - 'read-buildbot-config', 09:58:12 INFO - 'download-and-extract', 09:58:12 INFO - 'populate-webroot', 09:58:12 INFO - 'create-virtualenv', 09:58:12 INFO - 'install', 09:58:12 INFO - 'run-tests'), 09:58:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:12 INFO - 'download_minidump_stackwalk': True, 09:58:12 INFO - 'download_symbols': 'ondemand', 09:58:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:12 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:12 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:12 INFO - 'installer_path': 'installer.exe', 09:58:12 INFO - 'log_level': 'info', 09:58:12 INFO - 'log_name': 'talos', 09:58:12 INFO - 'log_to_console': True, 09:58:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:58:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:58:12 INFO - 'opt_config_files': (), 09:58:12 INFO - 'pip_index': False, 09:58:12 INFO - 'sps_profile': False, 09:58:12 INFO - 'sps_profile_interval': 0, 09:58:12 INFO - 'suite': 'dromaeojs', 09:58:12 INFO - 'system_bits': '32', 09:58:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:12 INFO - 'title': 'talos-linux64-ix-063', 09:58:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:12 INFO - 'use_talos_json': True, 09:58:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:12 INFO - 'work_dir': 'build'} 09:58:12 INFO - [mozharness: 2016-11-03 16:58:12.823591Z] Running clobber step. 09:58:12 INFO - Running pre-action listener: _resource_record_pre_action 09:58:12 INFO - Running main action method: clobber 09:58:12 INFO - rmtree: /builds/slave/test/build 09:58:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:13 INFO - Running post-action listener: _resource_record_post_action 09:58:13 INFO - [mozharness: 2016-11-03 16:58:13.292320Z] Finished clobber step (success) 09:58:13 INFO - [mozharness: 2016-11-03 16:58:13.292422Z] Running read-buildbot-config step. 09:58:13 INFO - Running pre-action listener: _resource_record_pre_action 09:58:13 INFO - Running main action method: read_buildbot_config 09:58:13 INFO - Using buildbot properties: 09:58:13 INFO - { 09:58:13 INFO - "project": "", 09:58:13 INFO - "product": "firefox", 09:58:13 INFO - "script_repo_revision": "production", 09:58:13 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 09:58:13 INFO - "repository": "", 09:58:13 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 09:58:13 INFO - "buildid": "20161103074637", 09:58:13 INFO - "stage_platform": "linux64-pgo", 09:58:13 INFO - "basedir": "/builds/slave/test", 09:58:13 INFO - "buildnumber": 2011, 09:58:13 INFO - "platform": "ubuntu64_hw", 09:58:13 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:58:13 INFO - "slavebuilddir": "test", 09:58:13 INFO - "branch": "mozilla-inbound", 09:58:13 INFO - "revision": "ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3", 09:58:13 INFO - "slavename": "talos-linux64-ix-063", 09:58:13 INFO - "builduid": "ed77576b926642969a0949e8bfc1edd5", 09:58:13 INFO - "repo_path": "integration/mozilla-inbound" 09:58:13 INFO - } 09:58:13 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 09:58:13 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 09:58:13 INFO - Running post-action listener: _resource_record_post_action 09:58:13 INFO - [mozharness: 2016-11-03 16:58:13.294837Z] Finished read-buildbot-config step (success) 09:58:13 INFO - [mozharness: 2016-11-03 16:58:13.294925Z] Running download-and-extract step. 09:58:13 INFO - Running pre-action listener: _resource_record_pre_action 09:58:13 INFO - Running main action method: download_and_extract 09:58:13 INFO - mkdir: /builds/slave/test/build/tests 09:58:13 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')]} 09:58:13 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:58:13 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:58:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:58:13 INFO - Downloaded 1361 bytes. 09:58:13 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 09:58:13 INFO - Using the following test package requirements: 09:58:13 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:58:13 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:58:13 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:58:13 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'jsshell-linux-x86_64.zip'], 09:58:13 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:58:13 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 09:58:13 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:58:13 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:58:13 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:58:13 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 09:58:13 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:58:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:58:13 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/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:58:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:58:13 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:58:13 INFO - Http code: 200 09:58:13 INFO - via: 1.1 9baadab4e4d95afe7af7bcba342b29c6.cloudfront.net (CloudFront) 09:58:13 INFO - x-amz-cf-id: w_fZnX9CyMYE4khdh_Z_zSxb4kPTapI2UYBhpa6Py3h1cJBFwXj3jw== 09:58:13 INFO - x-amz-replication-status: COMPLETED 09:58:13 INFO - x-amz-version-id: 0yoSN_KdLSg5wP1rBdajxLyYM38.JA4O 09:58:13 INFO - Expected file size: 22023921 09:58:13 INFO - Obtained file size: 22023921 09:58:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:58:17 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/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 09:58:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:58:17 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:58:17 INFO - Http code: 200 09:58:17 INFO - via: 1.1 c0740de9f16cf32ffedfd2f9806b8483.cloudfront.net (CloudFront) 09:58:17 INFO - x-amz-cf-id: FVq2pT8U2d0L8XcHZy-IgFySBFTU2GzTKuK2OCF5TypbToexybx86g== 09:58:17 INFO - x-amz-replication-status: COMPLETED 09:58:17 INFO - x-amz-version-id: 0yoSN_KdLSg5wP1rBdajxLyYM38.JA4O 09:58:18 INFO - Expected file size: 22023921 09:58:18 INFO - Obtained file size: 22023921 09:58:22 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/1478093892/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 09:58:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:58:22 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:58:22 INFO - Http code: 200 09:58:22 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 09:58:22 INFO - x-amz-cf-id: 2x5AusSi041HvidiItcFdjCGVAucJVYWsqKtX7JXNyxC2uBhACY1XQ== 09:58:22 INFO - x-amz-replication-status: COMPLETED 09:58:22 INFO - x-amz-version-id: jGQBtSSr5NhE.UrZBVy8KUhD3mreaGIo 09:58:22 INFO - Expected file size: 14409038 09:58:22 INFO - Obtained file size: 14409038 09:58:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:23 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:58:23 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:58:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:58:23 INFO - Downloaded 59615918 bytes. 09:58:23 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:58:23 INFO - mkdir: /builds/slave/test/properties 09:58:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:58:23 INFO - Writing to file /builds/slave/test/properties/build_url 09:58:23 INFO - Contents: 09:58:23 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 09:58:24 INFO - Running post-action listener: _resource_record_post_action 09:58:24 INFO - Running post-action listener: set_extra_try_arguments 09:58:24 INFO - [mozharness: 2016-11-03 16:58:24.197343Z] Finished download-and-extract step (success) 09:58:24 INFO - [mozharness: 2016-11-03 16:58:24.197570Z] Running populate-webroot step. 09:58:24 INFO - Running pre-action listener: _resource_record_pre_action 09:58:24 INFO - Running main action method: populate_webroot 09:58:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:58:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:58:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:58:24 INFO - '--tppagecycles', 09:58:24 INFO - '7'], 09:58:24 INFO - 'tests': ['tsvgm']}}, 09:58:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:58:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:58:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:58:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:58:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:58:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['damp', 'tps']}, 09:58:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'tests': ['damp', 'tps']}, 09:58:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['dromaeo_dom']}, 09:58:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:58:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['basic_compositor_video']}, 09:58:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:58:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['a11yr', 09:58:24 INFO - 'ts_paint', 09:58:24 INFO - 'tpaint', 09:58:24 INFO - 'sessionrestore', 09:58:24 INFO - 'sessionrestore_no_auto_restore', 09:58:24 INFO - 'tabpaint']}, 09:58:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:58:24 INFO - 'ts_paint', 09:58:24 INFO - 'tpaint', 09:58:24 INFO - 'sessionrestore', 09:58:24 INFO - 'sessionrestore_no_auto_restore', 09:58:24 INFO - 'tabpaint']}, 09:58:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:58:24 INFO - 'ts_paint', 09:58:24 INFO - 'tpaint', 09:58:24 INFO - 'sessionrestore', 09:58:24 INFO - 'sessionrestore_no_auto_restore', 09:58:24 INFO - 'tabpaint']}, 09:58:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:58:24 INFO - 'ts_paint', 09:58:24 INFO - 'tpaint', 09:58:24 INFO - 'sessionrestore', 09:58:24 INFO - 'sessionrestore_no_auto_restore', 09:58:24 INFO - 'tabpaint']}, 09:58:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['a11yr', 09:58:24 INFO - 'ts_paint', 09:58:24 INFO - 'tpaint', 09:58:24 INFO - 'sessionrestore', 09:58:24 INFO - 'sessionrestore_no_auto_restore', 09:58:24 INFO - 'tabpaint']}, 09:58:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['a11yr', 09:58:24 INFO - 'ts_paint', 09:58:24 INFO - 'tpaint', 09:58:24 INFO - 'sessionrestore', 09:58:24 INFO - 'sessionrestore_no_auto_restore', 09:58:24 INFO - 'tabpaint']}, 09:58:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['tsvgx', 09:58:24 INFO - 'tsvgr_opacity', 09:58:24 INFO - 'tart', 09:58:24 INFO - 'tscrollx', 09:58:24 INFO - 'cart']}, 09:58:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:58:24 INFO - 'tsvgr_opacity', 09:58:24 INFO - 'tart', 09:58:24 INFO - 'tscrollx', 09:58:24 INFO - 'cart']}, 09:58:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'talos_options': ['--disable-e10s'], 09:58:24 INFO - 'tests': ['tp5o']}, 09:58:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'tests': ['tp5o']}, 09:58:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'talos_options': ['--disable-e10s', 09:58:24 INFO - '--xperf_path', 09:58:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:24 INFO - 'tests': ['tp5n']}, 09:58:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:58:24 INFO - 'talos_options': ['--xperf_path', 09:58:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:58:24 INFO - 'tests': ['tp5n']}}, 09:58:24 INFO - 'talos.zip': {'path': '', 09:58:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:58:24 INFO - Running post-action listener: _resource_record_post_action 09:58:24 INFO - [mozharness: 2016-11-03 16:58:24.217564Z] Finished populate-webroot step (success) 09:58:24 INFO - [mozharness: 2016-11-03 16:58:24.217653Z] Running create-virtualenv step. 09:58:24 INFO - Running pre-action listener: _resource_record_pre_action 09:58:24 INFO - Running main action method: create_virtualenv 09:58:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:58:24 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 09:58:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:58:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:58:24 INFO - Using real prefix '/usr' 09:58:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:58:24 INFO - Installing distribute.............................................................................................................................................................................................done. 09:58:26 INFO - Installing pip.................done. 09:58:26 INFO - Return code: 0 09:58:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:58:26 INFO - Reading from file tmpfile_stdout 09:58:26 INFO - Output received: 09:58:26 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) 09:58:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:27 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:27 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 09:58:27 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 09:58:27 INFO - Using env: {'DISPLAY': ':0', 09:58:27 INFO - 'HOME': '/home/cltbld', 09:58:27 INFO - 'LANG': 'en_US.UTF-8', 09:58:27 INFO - 'LANGUAGE': 'en_US:en', 09:58:27 INFO - 'LOGNAME': 'cltbld', 09:58:27 INFO - 'MAIL': '/var/mail/cltbld', 09:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:27 INFO - 'MOZ_NO_REMOTE': '1', 09:58:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:27 INFO - 'NO_EM_RESTART': '1', 09:58:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:27 INFO - 'PWD': '/builds/slave/test', 09:58:27 INFO - 'SHELL': '/bin/bash', 09:58:27 INFO - 'SHLVL': '1', 09:58:27 INFO - 'TERM': 'linux', 09:58:27 INFO - 'TMOUT': '86400', 09:58:27 INFO - 'USER': 'cltbld', 09:58:27 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:27 INFO - '_': '/tools/buildbot/bin/python'} 09:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:27 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 09:58:27 INFO - Cleaning up... 09:58:27 INFO - Return code: 0 09:58:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:27 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:27 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 09:58:27 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 09:58:27 INFO - Using env: {'DISPLAY': ':0', 09:58:27 INFO - 'HOME': '/home/cltbld', 09:58:27 INFO - 'LANG': 'en_US.UTF-8', 09:58:27 INFO - 'LANGUAGE': 'en_US:en', 09:58:27 INFO - 'LOGNAME': 'cltbld', 09:58:27 INFO - 'MAIL': '/var/mail/cltbld', 09:58:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:27 INFO - 'MOZ_NO_REMOTE': '1', 09:58:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:27 INFO - 'NO_EM_RESTART': '1', 09:58:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:27 INFO - 'PWD': '/builds/slave/test', 09:58:27 INFO - 'SHELL': '/bin/bash', 09:58:27 INFO - 'SHLVL': '1', 09:58:27 INFO - 'TERM': 'linux', 09:58:27 INFO - 'TMOUT': '86400', 09:58:27 INFO - 'USER': 'cltbld', 09:58:27 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:27 INFO - '_': '/tools/buildbot/bin/python'} 09:58:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:27 INFO - Downloading/unpacking psutil>=3.1.1 09:58:27 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 09:58:27 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 09:58:27 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 09:58:27 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 09:58:27 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 09:58:27 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 09:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:58:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:29 INFO - Installing collected packages: psutil 09:58:29 INFO - Running setup.py install for psutil 09:58:29 INFO - building 'psutil._psutil_linux' extension 09:58:29 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 09:58:29 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 09:58:29 INFO - building 'psutil._psutil_posix' extension 09:58:29 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 09:58:29 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 09:58:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:58:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:58:29 INFO - Successfully installed psutil 09:58:29 INFO - Cleaning up... 09:58:30 INFO - Return code: 0 09:58:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:30 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:30 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 09:58:30 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 09:58:30 INFO - Using env: {'DISPLAY': ':0', 09:58:30 INFO - 'HOME': '/home/cltbld', 09:58:30 INFO - 'LANG': 'en_US.UTF-8', 09:58:30 INFO - 'LANGUAGE': 'en_US:en', 09:58:30 INFO - 'LOGNAME': 'cltbld', 09:58:30 INFO - 'MAIL': '/var/mail/cltbld', 09:58:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:30 INFO - 'MOZ_NO_REMOTE': '1', 09:58:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:30 INFO - 'NO_EM_RESTART': '1', 09:58:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:30 INFO - 'PWD': '/builds/slave/test', 09:58:30 INFO - 'SHELL': '/bin/bash', 09:58:30 INFO - 'SHLVL': '1', 09:58:30 INFO - 'TERM': 'linux', 09:58:30 INFO - 'TMOUT': '86400', 09:58:30 INFO - 'USER': 'cltbld', 09:58:30 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:30 INFO - '_': '/tools/buildbot/bin/python'} 09:58:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:58:30 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 09:58:30 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 09:58:30 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 09:58:30 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 09:58:30 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 09:58:30 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 09:58:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:58:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:58:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:58:32 INFO - Installing collected packages: mozsystemmonitor 09:58:32 INFO - Running setup.py install for mozsystemmonitor 09:58:32 INFO - Successfully installed mozsystemmonitor 09:58:32 INFO - Cleaning up... 09:58:32 INFO - Return code: 0 09:58:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:58:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:32 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:32 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 09:58:32 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 09:58:32 INFO - Using env: {'DISPLAY': ':0', 09:58:32 INFO - 'HOME': '/home/cltbld', 09:58:32 INFO - 'LANG': 'en_US.UTF-8', 09:58:32 INFO - 'LANGUAGE': 'en_US:en', 09:58:32 INFO - 'LOGNAME': 'cltbld', 09:58:32 INFO - 'MAIL': '/var/mail/cltbld', 09:58:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:32 INFO - 'MOZ_NO_REMOTE': '1', 09:58:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:32 INFO - 'NO_EM_RESTART': '1', 09:58:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:32 INFO - 'PWD': '/builds/slave/test', 09:58:32 INFO - 'SHELL': '/bin/bash', 09:58:32 INFO - 'SHLVL': '1', 09:58:32 INFO - 'TERM': 'linux', 09:58:32 INFO - 'TMOUT': '86400', 09:58:32 INFO - 'USER': 'cltbld', 09:58:32 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:32 INFO - '_': '/tools/buildbot/bin/python'} 09:58:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:32 INFO - Downloading/unpacking jsonschema==2.5.1 09:58:32 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 09:58:32 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 09:58:32 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 09:58:32 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 09:58:32 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 09:58:32 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 09:58:34 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:58:34 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 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 09:58:34 INFO - Downloading functools32-3.2.3-2.tar.gz 09:58:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:58:34 INFO - warning: no files found matching '*.txt' 09:58:34 INFO - no previously-included directories found matching 'build' 09:58:34 INFO - no previously-included directories found matching 'dist' 09:58:34 INFO - no previously-included directories found matching '.git*' 09:58:34 INFO - Installing collected packages: jsonschema, functools32 09:58:34 INFO - Running setup.py install for functools32 09:58:34 INFO - warning: no files found matching '*.txt' 09:58:34 INFO - no previously-included directories found matching 'build' 09:58:34 INFO - no previously-included directories found matching 'dist' 09:58:34 INFO - no previously-included directories found matching '.git*' 09:58:34 INFO - Successfully installed jsonschema functools32 09:58:34 INFO - Cleaning up... 09:58:34 INFO - Return code: 0 09:58:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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')]} 09:58:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:35 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 09:58:35 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 09:58:35 INFO - Using env: {'DISPLAY': ':0', 09:58:35 INFO - 'HOME': '/home/cltbld', 09:58:35 INFO - 'LANG': 'en_US.UTF-8', 09:58:35 INFO - 'LANGUAGE': 'en_US:en', 09:58:35 INFO - 'LOGNAME': 'cltbld', 09:58:35 INFO - 'MAIL': '/var/mail/cltbld', 09:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:35 INFO - 'MOZ_NO_REMOTE': '1', 09:58:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:35 INFO - 'NO_EM_RESTART': '1', 09:58:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:35 INFO - 'PWD': '/builds/slave/test', 09:58:35 INFO - 'SHELL': '/bin/bash', 09:58:35 INFO - 'SHLVL': '1', 09:58:35 INFO - 'TERM': 'linux', 09:58:35 INFO - 'TMOUT': '86400', 09:58:35 INFO - 'USER': 'cltbld', 09:58:35 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:35 INFO - '_': '/tools/buildbot/bin/python'} 09:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:35 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:58:35 INFO - Cleaning up... 09:58:35 INFO - Return code: 0 09:58:35 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:58:35 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')]} 09:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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')]} 09:58:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:35 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:35 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 09:58:35 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 09:58:35 INFO - Using env: {'DISPLAY': ':0', 09:58:35 INFO - 'HOME': '/home/cltbld', 09:58:35 INFO - 'LANG': 'en_US.UTF-8', 09:58:35 INFO - 'LANGUAGE': 'en_US:en', 09:58:35 INFO - 'LOGNAME': 'cltbld', 09:58:35 INFO - 'MAIL': '/var/mail/cltbld', 09:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:35 INFO - 'MOZ_NO_REMOTE': '1', 09:58:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:35 INFO - 'NO_EM_RESTART': '1', 09:58:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:35 INFO - 'PWD': '/builds/slave/test', 09:58:35 INFO - 'SHELL': '/bin/bash', 09:58:35 INFO - 'SHLVL': '1', 09:58:35 INFO - 'TERM': 'linux', 09:58:35 INFO - 'TMOUT': '86400', 09:58:35 INFO - 'USER': 'cltbld', 09:58:35 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:35 INFO - '_': '/tools/buildbot/bin/python'} 09:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:35 INFO - Downloading/unpacking blobuploader==1.2.4 09:58:35 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 09:58:35 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 09:58:35 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 09:58:35 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 09:58:35 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 09:58:35 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 09:58:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:58:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:58:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 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 09:58:37 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 09:58:38 INFO - Downloading docopt-0.6.1.tar.gz 09:58:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:58:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:58:38 INFO - Running setup.py install for blobuploader 09:58:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:58:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:58:38 INFO - Running setup.py install for requests 09:58:38 INFO - Running setup.py install for docopt 09:58:38 INFO - Successfully installed blobuploader requests docopt 09:58:38 INFO - Cleaning up... 09:58:38 INFO - Return code: 0 09:58:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:38 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:38 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 09:58:38 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 09:58:38 INFO - Using env: {'DISPLAY': ':0', 09:58:38 INFO - 'HOME': '/home/cltbld', 09:58:38 INFO - 'LANG': 'en_US.UTF-8', 09:58:38 INFO - 'LANGUAGE': 'en_US:en', 09:58:38 INFO - 'LOGNAME': 'cltbld', 09:58:38 INFO - 'MAIL': '/var/mail/cltbld', 09:58:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:38 INFO - 'MOZ_NO_REMOTE': '1', 09:58:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:38 INFO - 'NO_EM_RESTART': '1', 09:58:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:38 INFO - 'PWD': '/builds/slave/test', 09:58:38 INFO - 'SHELL': '/bin/bash', 09:58:38 INFO - 'SHLVL': '1', 09:58:38 INFO - 'TERM': 'linux', 09:58:38 INFO - 'TMOUT': '86400', 09:58:38 INFO - 'USER': 'cltbld', 09:58:38 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:38 INFO - '_': '/tools/buildbot/bin/python'} 09:58:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:38 INFO - Running setup.py (path:/tmp/pip-e7qVDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:39 INFO - Running setup.py (path:/tmp/pip-VObGN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:39 INFO - Running setup.py (path:/tmp/pip-tap3EF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:39 INFO - Running setup.py (path:/tmp/pip-0SFEqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:39 INFO - Running setup.py (path:/tmp/pip-tyfNha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:39 INFO - Running setup.py (path:/tmp/pip-DJl6IP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:39 INFO - Running setup.py (path:/tmp/pip-IDcdQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:39 INFO - Running setup.py (path:/tmp/pip-zQmlnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:39 INFO - Running setup.py (path:/tmp/pip-zbFHs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:39 INFO - Running setup.py (path:/tmp/pip-2vzOwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:39 INFO - Running setup.py (path:/tmp/pip-nfx1_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:39 INFO - Running setup.py (path:/tmp/pip-n6gBIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:39 INFO - Running setup.py (path:/tmp/pip-zuA2Ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:39 INFO - Running setup.py (path:/tmp/pip-UtTk5F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:40 INFO - Running setup.py (path:/tmp/pip-DEKgkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:40 INFO - Running setup.py (path:/tmp/pip-wF_zV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:40 INFO - Running setup.py (path:/tmp/pip-tXdexq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:40 INFO - Running setup.py install for manifestparser 09:58:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozcrash 09:58:40 INFO - Running setup.py install for mozdebug 09:58:40 INFO - Running setup.py install for mozdevice 09:58:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozfile 09:58:40 INFO - Running setup.py install for mozhttpd 09:58:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozinfo 09:58:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:40 INFO - Running setup.py install for mozInstall 09:58:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Running setup.py install for mozleak 09:58:41 INFO - Running setup.py install for mozlog 09:58:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Running setup.py install for moznetwork 09:58:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Running setup.py install for mozprocess 09:58:41 INFO - Running setup.py install for mozprofile 09:58:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Running setup.py install for mozrunner 09:58:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:41 INFO - Running setup.py install for mozscreenshot 09:58:41 INFO - Running setup.py install for moztest 09:58:41 INFO - Running setup.py install for mozversion 09:58:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:42 INFO - Cleaning up... 09:58:42 INFO - Return code: 0 09:58:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:42 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')]} 09:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:42 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')]} 09:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:42 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:42 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 09:58:42 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 09:58:42 INFO - Using env: {'DISPLAY': ':0', 09:58:42 INFO - 'HOME': '/home/cltbld', 09:58:42 INFO - 'LANG': 'en_US.UTF-8', 09:58:42 INFO - 'LANGUAGE': 'en_US:en', 09:58:42 INFO - 'LOGNAME': 'cltbld', 09:58:42 INFO - 'MAIL': '/var/mail/cltbld', 09:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:42 INFO - 'MOZ_NO_REMOTE': '1', 09:58:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:42 INFO - 'NO_EM_RESTART': '1', 09:58:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:42 INFO - 'PWD': '/builds/slave/test', 09:58:42 INFO - 'SHELL': '/bin/bash', 09:58:42 INFO - 'SHLVL': '1', 09:58:42 INFO - 'TERM': 'linux', 09:58:42 INFO - 'TMOUT': '86400', 09:58:42 INFO - 'USER': 'cltbld', 09:58:42 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:42 INFO - '_': '/tools/buildbot/bin/python'} 09:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:42 INFO - Running setup.py (path:/tmp/pip-DU8gdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:42 INFO - Running setup.py (path:/tmp/pip-K_EHV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:42 INFO - Running setup.py (path:/tmp/pip-OnUJd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:42 INFO - Running setup.py (path:/tmp/pip-X8yVcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:42 INFO - Running setup.py (path:/tmp/pip-Zv8hkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:42 INFO - Running setup.py (path:/tmp/pip-saW7B2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:42 INFO - Running setup.py (path:/tmp/pip-dxaAWJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:42 INFO - Running setup.py (path:/tmp/pip-c5hbyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:42 INFO - Running setup.py (path:/tmp/pip-ERanfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:42 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)) 09:58:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:42 INFO - Running setup.py (path:/tmp/pip-YzoQS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:43 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)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:43 INFO - Running setup.py (path:/tmp/pip-GQKN7z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:43 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)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:43 INFO - Running setup.py (path:/tmp/pip-GxhOk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:43 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)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:43 INFO - Running setup.py (path:/tmp/pip-UYMWIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:43 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)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:43 INFO - Running setup.py (path:/tmp/pip-btem7E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:43 INFO - Running setup.py (path:/tmp/pip-S4rVPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:43 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)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:43 INFO - Running setup.py (path:/tmp/pip-sH1yyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:43 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)) 09:58:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:43 INFO - Running setup.py (path:/tmp/pip-lAlrBo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:43 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)) 09:58:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:58:43 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)) 09:58:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:58:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:43 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 09:58:43 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 09:58:43 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 09:58:43 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 09:58:43 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 09:58:43 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 09:58:45 INFO - Downloading blessings-1.6.tar.gz 09:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:45 INFO - Installing collected packages: blessings 09:58:45 INFO - Running setup.py install for blessings 09:58:45 INFO - Successfully installed blessings 09:58:45 INFO - Cleaning up... 09:58:45 INFO - Return code: 0 09:58:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:45 INFO - Reading from file tmpfile_stdout 09:58:45 INFO - Current package versions: 09:58:45 INFO - argparse == 1.2.1 09:58:45 INFO - blessings == 1.6 09:58:45 INFO - blobuploader == 1.2.4 09:58:45 INFO - docopt == 0.6.1 09:58:45 INFO - functools32 == 3.2.3-2 09:58:45 INFO - jsonschema == 2.5.1 09:58:45 INFO - manifestparser == 1.1 09:58:45 INFO - mozInstall == 1.12 09:58:45 INFO - mozcrash == 0.17 09:58:45 INFO - mozdebug == 0.1 09:58:45 INFO - mozdevice == 0.48 09:58:45 INFO - mozfile == 1.2 09:58:45 INFO - mozhttpd == 0.7 09:58:45 INFO - mozinfo == 0.9 09:58:45 INFO - mozleak == 0.1 09:58:45 INFO - mozlog == 3.4 09:58:45 INFO - moznetwork == 0.27 09:58:45 INFO - mozprocess == 0.23 09:58:45 INFO - mozprofile == 0.28 09:58:45 INFO - mozrunner == 6.12 09:58:45 INFO - mozscreenshot == 0.1 09:58:45 INFO - mozsystemmonitor == 0.3 09:58:45 INFO - moztest == 0.7 09:58:45 INFO - mozversion == 1.4 09:58:45 INFO - psutil == 3.1.1 09:58:45 INFO - requests == 1.2.3 09:58:45 INFO - wsgiref == 0.1.2 09:58:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:45 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:45 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 09:58:45 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 09:58:45 INFO - Using env: {'DISPLAY': ':0', 09:58:45 INFO - 'HOME': '/home/cltbld', 09:58:45 INFO - 'LANG': 'en_US.UTF-8', 09:58:45 INFO - 'LANGUAGE': 'en_US:en', 09:58:45 INFO - 'LOGNAME': 'cltbld', 09:58:45 INFO - 'MAIL': '/var/mail/cltbld', 09:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:45 INFO - 'MOZ_NO_REMOTE': '1', 09:58:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:45 INFO - 'NO_EM_RESTART': '1', 09:58:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:45 INFO - 'PWD': '/builds/slave/test', 09:58:45 INFO - 'SHELL': '/bin/bash', 09:58:45 INFO - 'SHLVL': '1', 09:58:45 INFO - 'TERM': 'linux', 09:58:45 INFO - 'TMOUT': '86400', 09:58:45 INFO - 'USER': 'cltbld', 09:58:45 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:45 INFO - '_': '/tools/buildbot/bin/python'} 09:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 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)) 09:58:46 INFO - Cleaning up... 09:58:46 INFO - Return code: 0 09:58:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:46 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 0x7f0027b63f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x287a8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2947ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, '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': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', '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 09:58:46 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 09:58:46 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 09:58:46 INFO - Using env: {'DISPLAY': ':0', 09:58:46 INFO - 'HOME': '/home/cltbld', 09:58:46 INFO - 'LANG': 'en_US.UTF-8', 09:58:46 INFO - 'LANGUAGE': 'en_US:en', 09:58:46 INFO - 'LOGNAME': 'cltbld', 09:58:46 INFO - 'MAIL': '/var/mail/cltbld', 09:58:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:46 INFO - 'MOZ_NO_REMOTE': '1', 09:58:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:58:46 INFO - 'NO_EM_RESTART': '1', 09:58:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:58:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:46 INFO - 'PWD': '/builds/slave/test', 09:58:46 INFO - 'SHELL': '/bin/bash', 09:58:46 INFO - 'SHLVL': '1', 09:58:46 INFO - 'TERM': 'linux', 09:58:46 INFO - 'TMOUT': '86400', 09:58:46 INFO - 'USER': 'cltbld', 09:58:46 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:58:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:46 INFO - '_': '/tools/buildbot/bin/python'} 09:58:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:46 INFO - Cleaning up... 09:58:46 INFO - Return code: 0 09:58:46 INFO - Running post-action listener: _resource_record_post_action 09:58:46 INFO - Running post-action listener: _start_resource_monitoring 09:58:46 INFO - Starting resource monitoring. 09:58:46 INFO - [mozharness: 2016-11-03 16:58:46.444078Z] Finished create-virtualenv step (success) 09:58:46 INFO - [mozharness: 2016-11-03 16:58:46.444590Z] Running install step. 09:58:46 INFO - Running pre-action listener: _resource_record_pre_action 09:58:46 INFO - Running main action method: install 09:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:46 INFO - Reading from file tmpfile_stdout 09:58:46 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:46 INFO - Reading from file tmpfile_stdout 09:58:46 INFO - Output received: 09:58:46 INFO - Usage: mozinstall [options] installer 09:58:46 INFO - Options: 09:58:46 INFO - -h, --help show this help message and exit 09:58:46 INFO - -d DEST, --destination=DEST 09:58:46 INFO - Directory to install application into. [default: 09:58:46 INFO - "/builds/slave/test"] 09:58:46 INFO - --app=APP Application being installed. [default: firefox] 09:58:46 INFO - mkdir: /builds/slave/test/build/application 09:58:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:59:03 INFO - Reading from file tmpfile_stdout 09:59:03 INFO - Output received: 09:59:03 INFO - /builds/slave/test/build/application/firefox/firefox 09:59:03 INFO - Running post-action listener: _resource_record_post_action 09:59:03 INFO - [mozharness: 2016-11-03 16:59:03.731564Z] Finished install step (success) 09:59:03 INFO - [mozharness: 2016-11-03 16:59:03.731814Z] Running run-tests step. 09:59:03 INFO - Running pre-action listener: _resource_record_pre_action 09:59:03 INFO - Running main action method: run_tests 09:59:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:59:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:59:03 INFO - Python 2.7.3 09:59:03 INFO - Return code: 0 09:59:03 INFO - grabbing minidump binary from tooltool 09:59:03 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')]} 09:59:03 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 0x2944f60>, '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 0x2945d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x29461d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:59:03 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 09:59:03 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 09:59:03 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 09:59:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:59:03 INFO - Return code: 0 09:59:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:59:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:59:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:59:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:59:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:59:03 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/1478093892/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 09:59:03 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/1478093892/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-063 --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 09:59:03 INFO - Using env: {'DISPLAY': ':0', 09:59:03 INFO - 'HOME': '/home/cltbld', 09:59:03 INFO - 'LANG': 'en_US.UTF-8', 09:59:03 INFO - 'LANGUAGE': 'en_US:en', 09:59:03 INFO - 'LOGNAME': 'cltbld', 09:59:03 INFO - 'MAIL': '/var/mail/cltbld', 09:59:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:59:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:03 INFO - 'MOZ_NO_REMOTE': '1', 09:59:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:59:03 INFO - 'NO_EM_RESTART': '1', 09:59:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:03 INFO - 'PWD': '/builds/slave/test', 09:59:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:59:03 INFO - 'SHELL': '/bin/bash', 09:59:03 INFO - 'SHLVL': '1', 09:59:03 INFO - 'TERM': 'linux', 09:59:03 INFO - 'TMOUT': '86400', 09:59:03 INFO - 'USER': 'cltbld', 09:59:03 INFO - 'XDG_SESSION_COOKIE': '769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270', 09:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:03 INFO - '_': '/tools/buildbot/bin/python'} 09:59:03 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/1478093892/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-063', '--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 09:59:04 INFO - mozversion application_buildid: 20161102063812 09:59:04 INFO - mozversion application_changeset: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 09:59:04 INFO - mozversion application_display_name: Nightly 09:59:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:59:04 INFO - mozversion application_name: Firefox 09:59:04 INFO - mozversion application_remotingname: firefox 09:59:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:59:04 INFO - mozversion application_vendor: Mozilla 09:59:04 INFO - mozversion application_version: 52.0a1 09:59:04 INFO - mozversion platform_buildid: 20161102063812 09:59:04 INFO - mozversion platform_changeset: ec5ec8b7f05cec8b28f38a0073772f4cb16fa8f3 09:59:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:59:04 INFO - mozversion platform_version: 52.0a1 09:59:04 INFO - using testdate: 1478192344 09:59:04 INFO - actual date: 1478192344 09:59:04 INFO - starting webserver on 'localhost:40062' 09:59:04 INFO - SUITE-START | Running 2 tests 09:59:04 INFO - TEST-START | dromaeo_css 09:59:04 INFO - operating with platform_type : linux_ 09:59:04 INFO - Initialising browser for dromaeo_css test... 09:59:04 INFO - TEST-INFO | started process 28434 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5XhPR1/profile http://localhost:40062/getInfo.html) 09:59:08 INFO - PROCESS | 28434 | __metrics Screen width/height:1600/1200 09:59:08 INFO - PROCESS | 28434 | colorDepth:24 09:59:08 INFO - PROCESS | 28434 | Browser inner width/height: 1024/697 09:59:08 INFO - PROCESS | 28434 | __metrics 09:59:08 INFO - PROCESS | 28434 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:59:08 INFO - PROCESS | 28434 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:59:09 INFO - PROCESS | 28434 | console.error: PushService: 09:59:09 INFO - PROCESS | 28434 | stateChangeProcessEnqueue: Error transitioning state 09:59:09 INFO - PROCESS | 28434 | UnknownError 09:59:10 INFO - TEST-INFO | 28434: exit 0 09:59:10 INFO - Browser initialized. 09:59:10 INFO - Running cycle 1/1 for dromaeo_css test... 09:59:10 INFO - TEST-INFO | started process 28519 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5XhPR1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:22 INFO - PROCESS | 28519 | RSS: Main: 172638208 09:59:22 INFO - PROCESS | 28519 | 10:00:13 INFO - PROCESS | 28519 | 1478192413482 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] 10:02:24 INFO - PROCESS | 28519 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40062/tests/dromaeo/cssquery-ext.html) 10:02:25 INFO - PROCESS | 28519 | RSS: Main: 307101696 10:02:25 INFO - PROCESS | 28519 | 10:05:28 INFO - PROCESS | 28519 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-ext.html (next: http://localhost:40062/tests/dromaeo/cssquery-jquery.html) 10:05:28 INFO - PROCESS | 28519 | RSS: Main: 276434944 10:05:28 INFO - PROCESS | 28519 | 10:08:30 INFO - PROCESS | 28519 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40062/tests/dromaeo/cssquery-mootools.html) 10:08:31 INFO - PROCESS | 28519 | RSS: Main: 218042368 10:08:31 INFO - PROCESS | 28519 | 10:11:33 INFO - PROCESS | 28519 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40062/tests/dromaeo/cssquery-prototype.html) 10:11:34 INFO - PROCESS | 28519 | RSS: Main: 275849216 10:11:34 INFO - PROCESS | 28519 | 10:14:36 INFO - PROCESS | 28519 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40062/tests/dromaeo/cssquery-yui.html) 10:14:37 INFO - PROCESS | 28519 | RSS: Main: 219049984 10:14:37 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | Cycle 1(1): loaded http://localhost:40062/tests/dromaeo/cssquery-yui.html (next: http://localhost:40062/tests/dromaeo/cssquery-dojo.html) 10:17:40 INFO - PROCESS | 28519 | RSS: Main: 308822016 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | __start_tp_report 10:17:40 INFO - PROCESS | 28519 | _x_x_mozilla_page_load 10:17:40 INFO - PROCESS | 28519 | _x_x_mozilla_page_load_details 10:17:40 INFO - PROCESS | 28519 | |i|pagename|runs| 10:17:40 INFO - PROCESS | 28519 | |0;dojo.html;2970.5841182234485;2996.056242283682;3021.4108248891466;3040.7871448998567;3048.7103725146117;5075.213341932006;5140.691558506493;5234.319538459999;5303.416624171343;5360.999999999995;5164.612654050942;5197.116490196666;5210.583153347732;5253.684778913268;5256.290400795891;4678.602318802906;4698.130845793539;4769.284607308897;4789.6168306535565;4790.760461976887;4872.853814385574;4893.314935908976;4944.456109827934;4962.156433406311;5012.674176178537;4715.1512727709;4775.928361074587;4780.043991201739;4806;4896.46138924718;4746.050789842011;4750.311204875274;4798.808047678123;4813.133635945528;4816.51834816519;4741.193997020515;4833.540813622701;4835.492273311287;4839.806407743686;4853.902921941577;5234.790608375661;5237.8428647140645;5238.68567885928;5242.55438287746;5246.31797866275;5540.695261760601;5587.916181257285;5588.497035266805;5597.608167428281;5641.182028605829;5473.28847249859;5528.612997090206;5601.131824567158;5643.661380317153;5668.489835914787;5285.894282114336;5286.471352864722;5286.735663216824;5297.364316282033;5347.251384806092;5702.515286200636;5725.51333136688;5777.569553125516;5784.595078344475;5787.739551720183;5339;5445.237666726662;5445.428230035789;5522.171674248814;5601.411851755507;5572.888542229214;5582.134769110794;5583.972080139573;5584.022796010682;5587.357453892854;5485.917711234335;5563;5571.972140139354;5573.637713548606;5578.665280083208;5492.835214943558;5494.835154945358;5542.861428464321;5601.047821870292;5603.8879222416135;4311.288637374861;4311.762853043113;4319.97840010798;4331.177076355482;4360.280553708666;3833.1375440526217;3856.5179352580926;3898.90252743678;3908.2378936107752;3914.2758589661003;5273.31446911899;5345.064613692588;5347.51872331492;5352.839414817562;5354.437784032621;5634.04221282383;5634.830955071355;5642.069058605366;5642.915356269659;5643.294588176478;5344.545713614386;5345.251664766935;5353.625246232804;5353.839384818461;5355.785768569213;4783.832565860178;4796.832110876103;4921.3356196912955;4927.90144197114;4929.728864912393;5794.913076303858;5802.100674395476;5805.82582522525;5809.535237181015;5813.88372232553;5500.50495455411;5502.669839809624;5508;5509.531689806411;5514.944850551443;5713.05734553794;5721.056025755704;5727.312722473163;5731.111677689798;5746.827595172152;5386.676799392049;5395.56835453155;5408.296921400193;5417.322834645669;5433.755481003285;5534.972325138348;5540.36285827135;5550.139728342114;5554.694491802989;5592.7762889483965;5432.320959880015;5437.564994800327;5444.455554444682;5445.128779395278;5496.697681627549;5444.047291724011;5444.918326225031;5502.257195278587;5503.944960550343;5510.090835012178;5448.373437054631;5474.343078830566;5478.972605137108;5484.753186106555;5520.392756796829;5549.417311182267;5564.675632911329;5567.3040869891265;5569.749361278671;5579.77680892776;5280.339957505312;5283.5773138149625;5294.708791016531;5341.092014357491;5508.063629182969 10:17:40 INFO - PROCESS | 28519 | |1;ext.html;557.9302587176603;594.3165359836188;602.1058727789235;604.8004158627654;620.1906512001838;32128.67871321286;35043.82478087609;35054.47418288728;41829.58170418295;42649.360259596055;44729.7763511182;45378.54621453785;45391.31913021307;45396.31905521412;45513.08973820521;8883.022867484588;9033.051529589397;9035.819283614324;9038.86441703375;9042.457452552868;8155;8371.874421883676;8378.874316885252;8567.95716021419;8838.29293656509;10242.48787560619;10507.527161277761;10510.789784204311;10858.294210876307;10898.727531811728;10684.252102352839;10732.892671073267;10736.624218152327;10758.408287544182;10761.30051546647;10628.627998020032;10731.78536429275;10735;10741.785164296749;10742.624008159677;42587.148257035;42652.3602145968;42746.78626606847;42795.14409711788;42795.28818847243;3322.352141332441;3510.5611798525183;3523.453864650983;3582.229820588586;3650.0874781304674;32333.51499727506;32348.339040668023;33295.66704332974;33372.165695857555;33983.15042123942;4133.772642504661;4218.472690913636;4219.135077309175;4230.238557059728;4289.871303860889;309001;312494.0631827346;313214;314780;314951;34396.48405273923;59030.40969590335;59156.704216479506;59254.11118833221;59284.11073833896;35353.116172095906;37287;37523.81238093792;37692.62307376891;37763.62236377656;13025.804612930813;13170;13188.208707477583;13193.53822616223;13200.20798752078;10403.115735162444;10457.895421045692;10460.52927618259;10461.110805581458;10472.371657700562;7992.5204487730925;8250.38122140842;8347.373946954003;8709.172628600272;8721.43310684816;3344.5317655528247;3353.865845366158;3367.5285460035616;3378.5101160331615;3447.8794391822757;948.9620415183315;991.8115558043949;1007;1093.9945300273448;1126.9943650281696;31900;32267.482552442678;32308;32676.67323326784;33228.50157247643;1061.7345663584103;1149.2587281203578;1152.5101831721486;1164.5807509296646;1235.3884827010688;16989.111850985362;17002.744958825628;17094.487165385057;17150.828491715172;17162.485125446256;456468;458014;460210;462007;462908;36276.27447451089;36611.63388366136;36784.63215367812;36810;36847.26305473875;1279.2196759976407;1311.4164196932275;1356.165957935882;1357.9592612221452;1363.6250031241289;30181.547276790425;30874.382512349177;31540;31823;32153.196170095936;51983.74008129935;52639.21041184309;52667;52677.47322526879;52783.47216527785;391410;393979;394550;394668;394737;25388.873055634605;26279.4744105113;29167.416651666423;29522.261943451587;29523.704762952955;50217.24674129818;50577;50813;51030.48969510257;52069.739651301505;40230.59769402268;40924.590754092074;41000.589994099675;41062.1787564253;42741.35887961745;40500.73123131306;41358.586414135476;42200.78899605482;42252;42380;40313;40546.39180412355;40575.1884962305;40700.38949415702;41559.58440415557;33605.83197083999;36353.636463635026;37470.43794343142;37733.24533509369;37802;27466.725332747217;27922.720772792825;27949.441011180068;27952.30119247035;28050.859745701142 10:17:40 INFO - PROCESS | 28519 | |2;jquery.html;362.84216365880826;371.9103028127585;380.774837129513;382.96361845624665;398.10227936004304;14213.147211167325;17872.731909021375;18014.748156480422;18759.249630014816;19950.501237469034;18227.635447291046;18304.908475457636;18444.53888652781;18909.7163542547;18931.81068189321;5437.782488700447;5740.425957404268;5743.62666426681;5745.712714364265;5745.798897038604;4172.979135104335;4278.25130602145;4318.157959197958;5593.244911936898;5719.542436605062;6105.725242364104;6134;6222.751089956397;6229.626222426647;6231.6572588507615;5980.6411615303;6115.266168059861;6176.104464852571;6232.407921247497;6240.625562466266;5423.674579525241;5427.240186373911;5427.62006659534;5429.131338985783;5432.538234250093;21683.67474487884;22425;22561.43596410103;22585.209517666804;22765;2322.268485427085;2396.700412448444;2466.5560199164142;2467.580511313081;3088.81467111974;13358.273553085859;13559.661008474768;13577.321133943264;14604.196769177692;14691.853081469155;1879.736836842843;1963.8527110466773;1975.8814471131634;1977.0707767349322;1977.7330060441943;53548.732256338466;74058.25941740621;78074.60962695206;82810;85055.14944850534;9505.714828555154;9738.565358660335;9764.218862490983;9975.800483990279;10215.336003159771;13661.590152295446;13664.385102670403;13664.795028074586;13685.657858553615;13697.794533082011;7167.211606723256;7241.710331586678;7308.926910730931;7344.559326440431;7376.446766492534;6741.898871516931;6812.14848143982;6826.726930922807;7041;7056.647167641598;6079.449740316191;6214.223222097238;6218.844528886814;6334.3665633436285;6379.74481020754;1709.5042437693178;1738.97391539127;1801.4415531185375;1818.399928023749;1845.6401001804788;893.3076865429344;971.7279161834698;1024.3188279794003;1045.1482042135635;1070.641335152717;18639.440816775517;18647.906760466383;19619.21523139087;20179.495512612302;20311.28910488126;941.9905800941902;966.5650457294246;986.713852982627;1010.4139599032543;1017.2319898476751;10229.641962531276;10733.946330268454;10877.619283325046;10896.291741036966;10974.615888443866;455655;465553;471396;471972;473279;90739;90778.54610726904;90793.54603226941;91364.99999999868;92566;973.4597298499259;998.610541888664;1011.8886922438526;1022.9641962531275;1023.8515415264745;16533.669326613643;17063.744043839102;17259.309627614755;17391.130463473386;17468.825311747227;40686.59313406828;41793.373099402925;42432.36351454669;43598;44109.33835992527;51370;52062.73968630133;53121;60727.69636151791;62948;7722.53664780115;7907.881381779162;7977.800554986172;8190.262876341158;8288.005439347316;8082;10191.72603424572;12350.876491234972;13053;13157.736845262849;10685.946570267099;11199.944000279946;11278.887211128113;11548.249363791327;11983;11352.375619341015;11556.364399957747;11701.531938722355;11859.644210673694;12071;11686.88313116892;11887.108466865193;12004.699882503008;12044.277343359427;12332.951699105317;7819.687212511436;8095.676172953016;8172.959135204286;8302.460340077876;8473.745787626382;10596.576136954436;11639.825402618799;11756.470958807045;11808.760080191456;12393.938030309791 10:17:40 INFO - PROCESS | 28519 | |3;mootools.html;1339.2968691436988;1477.4385733421298;1490.8136482939633;1573.5908663747423;1700.8299170082994;7431.702731890725;7828.804279892998;8018.959905200466;8045.195480451959;8207.91792082079;8085.029796424437;8143.714969976052;8147.226013528705;8157.836843263146;8160.836783264331;2207.47020715028;2241.8454495934607;2246.2475070851283;2246.4833088389682;2247.1573160064977;2238.563480121377;2267.183813827021;2269.307861102363;2280.6579013147993;2289.496310811619;2166.837487188467;2184.2246002669062;2195.7584665686763;2202.3723238876923;2204.316661834828;2154.6983422320886;2162.8269738421045;2195.4401627585025;2201.1415547936317;2201.8348623853194;2179.4769255378756;2197.7582465928735;2198.406430263836;2200.9559808803892;2201.669749537566;8057.476264042877;8108.554029528374;8170.67317307307;8354.582270886493;8441;4755.334253204554;4843.4914333994775;4855.198892182786;4876.707397556158;4882.389701287339;3115.3728046279816;3236.417444859924;3237.6600456951915;3299.0432774495366;3312.867485300585;1023.161007973462;1031.5306535526317;1038.4911393417246;1040.4589613401065;1086.7337502311905;11535.942320288344;11584.131190160688;11610.535578576846;11621.186516943817;11738.823917641243;4037.2934736421007;4270.466191726035;4323.329883868006;4334.371516130206;4335.523092459827;9338.719838404859;9346.719598412059;9366.063393660716;9380.437173769596;9398.812023759487;4939.382577177853;5036.471170527115;5042.067217564762;5043.949560504348;5048.091343558195;3907.6092390760696;4076.7553946763287;4332.415123958288;4664.976675116603;4672.159011377985;3558.964410355915;3675.283319752676;3717.275131349415;3769.981150094232;3770.490983717163;1128.8137457319615;1143.1312202725821;1143.422571601336;1145.6734830573246;1160.1646814293695;893.6246776353947;940.9341346105707;957.167264479907;974.3228456222857;1012.7822518158631;5208.088584497697;5261.210818377198;5300.681959082467;5335.359756829128;5807.825765226966;393.3077783101704;397.57260944484796;403.7173978215261;407.43366720258865;413.20870532929706;3871.845126194921;3882.0683036071705;3891.4357418174204;3935.58676338986;4045.6561192298395;9844.50777461124;10114.797704045877;10324;10422.947885260677;10434.113100386552;9893.653722119836;10314.896851031393;10323.831995281074;10326.845097323547;10345.844812327821;7554.809105359384;8017.839643207103;8019.799505012421;8021.077576078709;8028.157043510464;5759.366469688247;5777.971110144423;5835.270591176103;5885.588008839341;6003.159557661844;9619.855702164614;9636.46999415039;9639.518024098907;9718.514074296116;9736.75658108553;8769.91230087691;9583;9883.85174222373;9969.651062212788;9998.850017249893;6899.551529150589;7193.928060719326;7296.562206267641;7346.045014148127;7556.773296801105;7793.454458187873;7970.2826745593175;7998.600069996593;8063.959680201561;8071.677132914852;7008;7161.641917903979;7162.068931038932;7205.639718014183;7234.16807067194;6858.794236172923;7022.367986881205;7099;7146.071010768567;7299.197088320387;6104.725287361991;6135.877282454237;6152.661603611666;6192.133101365903;6249.406306400884;4680.695754775928;4698.2952557116705;4720.527947205252;4805.543473370024;4808.591269742111;4642.628589712747;4663.463701674351;4700.412448443944;4890.437599676083;4893.975530122469 10:17:40 INFO - PROCESS | 28519 | |4;prototype.html;301.51606671292564;311.5031524718075;314.8819192802699;320.3289109315984;325.6352884769059;4780.880477988052;4968.22992436173;5149.794008239671;5184.444666600024;5188.714620695865;5000.4749501302385;5088.949110508894;5089.720065396411;5119;5133.691978481284;4077.734947228428;4087.816048277835;4092.5907409259135;4097.139600683845;4104.97947510262;3860.4981352424147;4071.9592804071876;4081.244969680604;4084.5711200323835;4091.2635725569385;3799.810009499528;4011.237864805692;4019.376996565537;4038.7778672173167;4050.25070361984;4015.4579131817122;4021.416894550303;4037.4751282333405;4039;4049.453323801293;3564.5207238995786;3567.7680950738118;3571.8035508047046;3575.1777091268896;3598.5861625913094;4816.494268101834;4915.778789954425;4972.279019542182;4995.175795993657;5010.69935803853;4561.863144105682;4562;4569.702969306984;4569.862904112882;4595.333676616905;5171;5276.683398996034;5305.753148010214;5316.086208964808;5319.468053194688;3822.923541529182;3833.8083095845095;3835.4822099016556;3842.2507611015862;3850.942235866464;5314.362276526842;5451.291332126798;5525.198846167284;5560.7219639017885;5573.972130139364;3793.962060379416;3896.7077469189926;3898.1229223424502;3900.902477438087;3902.551206611276;3912.217556488714;3924.646781789653;3924.7645141291614;3931.8230679619487;3939.4681717967705;4505.211587972091;4515.75163366018;4516.096780643818;4524.117797029613;4536.432945881765;5192;5371.516563509303;5392.865178370494;5399.730013499309;5403.729813509309;1359.9116057456233;1367.4256812138926;1371.8422381426064;1377.800218968244;1380.2408675228585;837.0959363886988;864.8918885139358;876.7851876290222;883.0242581946984;884.8893888263967;4638.281066434708;4651.697639653412;4651.976740116278;4654.953450465519;4662.743549104764;4724.669273150916;4789.976050119727;4807.687500312469;4858.732769697701;4905.828296009622;4416.895776055986;4563.1480324797;4581.465209154904;4590.609798167126;4604.562566556172;2562.525932702456;2569.4090359217485;2575.3819083419844;2583.328334633009;2583.7416258374014;4953.479884612136;4981.626378021663;4982.975085124551;4994.228203347177;5036.521530454601;4594.540545945379;4813.4945830687975;4872.78072486739;4889.926651100236;4900.215965445512;4922.606191504671;4946.477296578771;4952.380952380952;4999.850004499871;5002.799888004439;4794.5924596409695;4800.231962885921;4803.975980120077;4805.7597120143155;4806.44725856517;4339.609435150852;4653.975520648529;4752.762361881962;4780.426348838162;4819.7349145795915;4888.755562221946;4952.900941981212;5376.6020834582505;5499.725013749377;5546.889062218653;3890.8249128788707;3927.4108883667677;3930.194310166352;3945.171513982096;3951.5653278139957;5514.172874068761;5522.199281104137;5543.944560554504;5546.805861794818;5550.139728342114;5231.3722353316325;5344.9732751336;5370;5391.622586418913;5400.62195646301;5306.708131052675;5322.627416080837;5338.225957236256;5367.752509731712;5391.4338994405025;5280.894382112259;5302.920456193083;5303.231031500488;5307.203919412119;5310.256564081109;5158.716270605003;5365.168398898177;5400.567954563547;5405.270288511158;5407.756650950795;5258.605604579749;5259.368875734936;5263.131583288715;5268.288781014514;5280.445553216857 10:17:40 INFO - PROCESS | 28519 | |5;yui.html;762.2034973452742;792.1405275276325;794.0907660728466;807.6930766308802;839.5508403004392;12670.936645316771;13536.1201521901;13547.390367433465;13576.389062492186;13590.116642418236;11992.64022079339;12096.818547721768;12099.455524501396;12137.514499420013;12345.197562158475;654.4306453385561;674.0899785289869;776.3052068398765;791.3510921044746;796.8446153000167;768.3968085053234;792.1959211400433;795.4272923495104;798.0144521515923;800.8478389106051;848.5926755157498;851.3359579527978;852.0925214646413;852.624845068168;852.8678054901469;693.7329128285646;846.0524212881551;846.7290467050547;849.307814131484;849.3587341557115;518.0545504454386;610.2402508876477;623.2676604989119;625.7966160997648;626.7273735924887;12226.388680566026;12239.75520489594;12248.142630015902;12281.263124212575;12296.385180740926;11709.765804683944;11817.822732659017;12375.257484550955;12495.500179992789;13002.414891329818;11092.500837462334;11273.830892536618;11364;11378.37418941958;11456.140789440742;4201.285781417167;4212.241796476633;4268.274393353137;4270.252705776507;4399.658818161973;58611.413885861446;58743.41256587422;60246.39753602452;60634.393656063316;61081;9977.551010204557;10068.144207742278;10114.949425252827;10216.131628811634;10218.489075546193;17253.741193882102;17387.478375648498;17399.304027838996;17401;17423.91288043577;7787.338076263468;7930.841383172305;7987.600619968978;7995.720149794845;8455.704050358208;4266.765327906933;4343.978280108579;4383.715058521186;4475.619572336323;4510.548945105464;3104.2549788050706;3143.575617291682;3197.7441804655573;3203.167176534118;3207.989483312749;546.972651367438;565.2679779685317;574.2420005592578;588.3792598808213;617.305531277313;690.4821383962028;723.8335182907855;725.3870479444861;738.9261073892675;751.4927423988895;9421.481818499911;9682.467464289392;9876.80246395068;9989.30074894765;10032.247581431422;644.5133923887529;665.2017578905333;759.3811043999123;775.9728409505753;789.7986013566618;4338.913221735547;4446.9555304447185;4746.121967435966;4757.8096876125255;4826.662133650681;79289.60355198187;79452.60273698595;79582.6020869892;82784;83159.16840831636;46667.766661166475;70198.34446355028;70530.29469705238;70616.6469167651;72840.63579682067;62437.68781156156;64503;64840.351596484375;64946.675266623366;65212;8737.470942585149;9199.402038867453;9221.538923053955;9228.169464748205;9288.699582058653;31309;35891;37188.07029824276;37417.625823741415;37502.62497374992;38970;39168.804155979036;39595;52256;54844.45155548393;11989.700257493632;12415.082455623102;12571.685707857378;12793.56922184782;13310.534131305358;13733;13920.142393341606;13950.302484875918;14048.648783780078;14600.196989165273;12539.686507837378;12813.743725125632;13091.672708182372;13107.213567186;13577.963305504254;12576.937115314731;12847;13639.79540306916;13828.792568111687;14102.435902563782;12193.32936688455;12338.876611233773;12397.380130993595;12756;12885.162464439782;10212.591496340361;10548.947255263674;10801.945990269998;10809.189310801565;10992;8613.526256055977;9237.67668131639;9263.583138758906;9994.400335979864;10071 10:17:40 INFO - PROCESS | 28519 | __end_tp_report 10:17:40 INFO - PROCESS | 28519 | __start_cc_report 10:17:40 INFO - PROCESS | 28519 | _x_x_mozilla_cycle_collect,1254 10:17:40 INFO - PROCESS | 28519 | __end_cc_report 10:17:40 INFO - PROCESS | 28519 | __startTimestamp1478193460719__endTimestamp 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | ------- Summary: start ------- 10:17:40 INFO - PROCESS | 28519 | Number of tests: 6 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | [#0] dojo.html Cycles:165 Average:5208.88 Median:5408.30 stddev:573.73 (10.6%) stddev-sans-first:547.96 10:17:40 INFO - PROCESS | 28519 | Values: 2970.6 2996.1 3021.4 3040.8 3048.7 5075.2 5140.7 5234.3 5303.4 5361.0 5164.6 5197.1 5210.6 5253.7 5256.3 4678.6 4698.1 4769.3 4789.6 4790.8 4872.9 4893.3 4944.5 4962.2 5012.7 4715.2 4775.9 4780.0 4806.0 4896.5 4746.1 4750.3 4798.8 4813.1 4816.5 4741.2 4833.5 4835.5 4839.8 4853.9 5234.8 5237.8 5238.7 5242.6 5246.3 5540.7 5587.9 5588.5 5597.6 5641.2 5473.3 5528.6 5601.1 5643.7 5668.5 5285.9 5286.5 5286.7 5297.4 5347.3 5702.5 5725.5 5777.6 5784.6 5787.7 5339.0 5445.2 5445.4 5522.2 5601.4 5572.9 5582.1 5584.0 5584.0 5587.4 5485.9 5563.0 5572.0 5573.6 5578.7 5492.8 5494.8 5542.9 5601.0 5603.9 4311.3 4311.8 4320.0 4331.2 4360.3 3833.1 3856.5 3898.9 3908.2 3914.3 5273.3 5345.1 5347.5 5352.8 5354.4 5634.0 5634.8 5642.1 5642.9 5643.3 5344.5 5345.3 5353.6 5353.8 5355.8 4783.8 4796.8 4921.3 4927.9 4929.7 5794.9 5802.1 5805.8 5809.5 5813.9 5500.5 5502.7 5508.0 5509.5 5514.9 5713.1 5721.1 5727.3 5731.1 5746.8 5386.7 5395.6 5408.3 5417.3 5433.8 5535.0 5540.4 5550.1 5554.7 5592.8 5432.3 5437.6 5444.5 5445.1 5496.7 5444.0 5444.9 5502.3 5503.9 5510.1 5448.4 5474.3 5479.0 5484.8 5520.4 5549.4 5564.7 5567.3 5569.7 5579.8 5280.3 5283.6 5294.7 5341.1 5508.1 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | [#1] ext.html Cycles:180 Average:54175.86 Median:20130.86 stddev:104056.83 (516.9%) stddev-sans-first:104270.87 10:17:40 INFO - PROCESS | 28519 | Values: 557.9 594.3 602.1 604.8 620.2 32128.7 35043.8 35054.5 41829.6 42649.4 44729.8 45378.5 45391.3 45396.3 45513.1 8883.0 9033.1 9035.8 9038.9 9042.5 8155.0 8371.9 8378.9 8568.0 8838.3 10242.5 10507.5 10510.8 10858.3 10898.7 10684.3 10732.9 10736.6 10758.4 10761.3 10628.6 10731.8 10735.0 10741.8 10742.6 42587.1 42652.4 42746.8 42795.1 42795.3 3322.4 3510.6 3523.5 3582.2 3650.1 32333.5 32348.3 33295.7 33372.2 33983.2 4133.8 4218.5 4219.1 4230.2 4289.9 309001.0 312494.1 313214.0 314780.0 314951.0 34396.5 59030.4 59156.7 59254.1 59284.1 35353.1 37287.0 37523.8 37692.6 37763.6 13025.8 13170.0 13188.2 13193.5 13200.2 10403.1 10457.9 10460.5 10461.1 10472.4 7992.5 8250.4 8347.4 8709.2 8721.4 3344.5 3353.9 3367.5 3378.5 3447.9 949.0 991.8 1007.0 1094.0 1127.0 31900.0 32267.5 32308.0 32676.7 33228.5 1061.7 1149.3 1152.5 1164.6 1235.4 16989.1 17002.7 17094.5 17150.8 17162.5 456468.0 458014.0 460210.0 462007.0 462908.0 36276.3 36611.6 36784.6 36810.0 36847.3 1279.2 1311.4 1356.2 1358.0 1363.6 30181.5 30874.4 31540.0 31823.0 32153.2 51983.7 52639.2 52667.0 52677.5 52783.5 391410.0 393979.0 394550.0 394668.0 394737.0 25388.9 26279.5 29167.4 29522.3 29523.7 50217.2 50577.0 50813.0 51030.5 52069.7 40230.6 40924.6 41000.6 41062.2 42741.4 40500.7 41358.6 42200.8 42252.0 42380.0 40313.0 40546.4 40575.2 40700.4 41559.6 33605.8 36353.6 37470.4 37733.2 37802.0 27466.7 27922.7 27949.4 27952.3 28050.9 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | [#2] jquery.html Cycles:180 Average:28006.90 Median:381.87 stddev:77134.10 (20199.1%) stddev-sans-first:77322.56 10:17:40 INFO - PROCESS | 28519 | Values: 362.8 371.9 380.8 383.0 398.1 14213.1 17872.7 18014.7 18759.2 19950.5 18227.6 18304.9 18444.5 18909.7 18931.8 5437.8 5740.4 5743.6 5745.7 5745.8 4173.0 4278.3 4318.2 5593.2 5719.5 6105.7 6134.0 6222.8 6229.6 6231.7 5980.6 6115.3 6176.1 6232.4 6240.6 5423.7 5427.2 5427.6 5429.1 5432.5 21683.7 22425.0 22561.4 22585.2 22765.0 2322.3 2396.7 2466.6 2467.6 3088.8 13358.3 13559.7 13577.3 14604.2 14691.9 1879.7 1963.9 1975.9 1977.1 1977.7 53548.7 74058.3 78074.6 82810.0 85055.1 9505.7 9738.6 9764.2 9975.8 10215.3 13661.6 13664.4 13664.8 13685.7 13697.8 7167.2 7241.7 7308.9 7344.6 7376.4 6741.9 6812.1 6826.7 7041.0 7056.6 6079.4 6214.2 6218.8 6334.4 6379.7 1709.5 1739.0 1801.4 1818.4 1845.6 893.3 971.7 1024.3 1045.1 1070.6 18639.4 18647.9 19619.2 20179.5 20311.3 942.0 966.6 986.7 1010.4 1017.2 10229.6 10733.9 10877.6 10896.3 10974.6 455655.0 465553.0 471396.0 471972.0 473279.0 90739.0 90778.5 90793.5 91365.0 92566.0 973.5 998.6 1011.9 1023.0 1023.9 16533.7 17063.7 17259.3 17391.1 17468.8 40686.6 41793.4 42432.4 43598.0 44109.3 51370.0 52062.7 53121.0 60727.7 62948.0 7722.5 7907.9 7977.8 8190.3 8288.0 8082.0 10191.7 12350.9 13053.0 13157.7 10685.9 11199.9 11278.9 11548.2 11983.0 11352.4 11556.4 11701.5 11859.6 12071.0 11686.9 11887.1 12004.7 12044.3 12333.0 7819.7 8095.7 8173.0 8302.5 8473.7 10596.6 11639.8 11756.5 11808.8 12393.9 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | [#3] mootools.html Cycles:180 Average:5411.24 Median:4780.44 stddev:3100.50 (64.9%) stddev-sans-first:3094.10 10:17:40 INFO - PROCESS | 28519 | Values: 1339.3 1477.4 1490.8 1573.6 1700.8 7431.7 7828.8 8019.0 8045.2 8207.9 8085.0 8143.7 8147.2 8157.8 8160.8 2207.5 2241.8 2246.2 2246.5 2247.2 2238.6 2267.2 2269.3 2280.7 2289.5 2166.8 2184.2 2195.8 2202.4 2204.3 2154.7 2162.8 2195.4 2201.1 2201.8 2179.5 2197.8 2198.4 2201.0 2201.7 8057.5 8108.6 8170.7 8354.6 8441.0 4755.3 4843.5 4855.2 4876.7 4882.4 3115.4 3236.4 3237.7 3299.0 3312.9 1023.2 1031.5 1038.5 1040.5 1086.7 11535.9 11584.1 11610.5 11621.2 11738.8 4037.3 4270.5 4323.3 4334.4 4335.5 9338.7 9346.7 9366.1 9380.4 9398.8 4939.4 5036.5 5042.1 5043.9 5048.1 3907.6 4076.8 4332.4 4665.0 4672.2 3559.0 3675.3 3717.3 3770.0 3770.5 1128.8 1143.1 1143.4 1145.7 1160.2 893.6 940.9 957.2 974.3 1012.8 5208.1 5261.2 5300.7 5335.4 5807.8 393.3 397.6 403.7 407.4 413.2 3871.8 3882.1 3891.4 3935.6 4045.7 9844.5 10114.8 10324.0 10422.9 10434.1 9893.7 10314.9 10323.8 10326.8 10345.8 7554.8 8017.8 8019.8 8021.1 8028.2 5759.4 5778.0 5835.3 5885.6 6003.2 9619.9 9636.5 9639.5 9718.5 9736.8 8769.9 9583.0 9883.9 9969.7 9998.9 6899.6 7193.9 7296.6 7346.0 7556.8 7793.5 7970.3 7998.6 8064.0 8071.7 7008.0 7161.6 7162.1 7205.6 7234.2 6858.8 7022.4 7099.0 7146.1 7299.2 6104.7 6135.9 6152.7 6192.1 6249.4 4680.7 4698.3 4720.5 4805.5 4808.6 4642.6 4663.5 4700.4 4890.4 4894.0 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | [#4] prototype.html Cycles:180 Average:4343.59 Median:4804.87 stddev:1247.35 (26.0%) stddev-sans-first:1213.39 10:17:40 INFO - PROCESS | 28519 | Values: 301.5 311.5 314.9 320.3 325.6 4780.9 4968.2 5149.8 5184.4 5188.7 5000.5 5088.9 5089.7 5119.0 5133.7 4077.7 4087.8 4092.6 4097.1 4105.0 3860.5 4072.0 4081.2 4084.6 4091.3 3799.8 4011.2 4019.4 4038.8 4050.3 4015.5 4021.4 4037.5 4039.0 4049.5 3564.5 3567.8 3571.8 3575.2 3598.6 4816.5 4915.8 4972.3 4995.2 5010.7 4561.9 4562.0 4569.7 4569.9 4595.3 5171.0 5276.7 5305.8 5316.1 5319.5 3822.9 3833.8 3835.5 3842.3 3850.9 5314.4 5451.3 5525.2 5560.7 5574.0 3794.0 3896.7 3898.1 3900.9 3902.6 3912.2 3924.6 3924.8 3931.8 3939.5 4505.2 4515.8 4516.1 4524.1 4536.4 5192.0 5371.5 5392.9 5399.7 5403.7 1359.9 1367.4 1371.8 1377.8 1380.2 837.1 864.9 876.8 883.0 884.9 4638.3 4651.7 4652.0 4655.0 4662.7 4724.7 4790.0 4807.7 4858.7 4905.8 4416.9 4563.1 4581.5 4590.6 4604.6 2562.5 2569.4 2575.4 2583.3 2583.7 4953.5 4981.6 4983.0 4994.2 5036.5 4594.5 4813.5 4872.8 4889.9 4900.2 4922.6 4946.5 4952.4 4999.9 5002.8 4794.6 4800.2 4804.0 4805.8 4806.4 4339.6 4654.0 4752.8 4780.4 4819.7 4888.8 4952.9 5376.6 5499.7 5546.9 3890.8 3927.4 3930.2 3945.2 3951.6 5514.2 5522.2 5543.9 5546.8 5550.1 5231.4 5345.0 5370.0 5391.6 5400.6 5306.7 5322.6 5338.2 5367.8 5391.4 5280.9 5302.9 5303.2 5307.2 5310.3 5158.7 5365.2 5400.6 5405.3 5407.8 5258.6 5259.4 5263.1 5268.3 5280.4 10:17:40 INFO - PROCESS | 28519 | 10:17:40 INFO - PROCESS | 28519 | [#5] yui.html Cycles:180 Average:16020.52 Median:25589.16 stddev:20750.44 (81.1%) stddev-sans-first:20777.01 10:17:40 INFO - PROCESS | 28519 | Values: 762.2 792.1 794.1 807.7 839.6 12670.9 13536.1 13547.4 13576.4 13590.1 11992.6 12096.8 12099.5 12137.5 12345.2 654.4 674.1 776.3 791.4 796.8 768.4 792.2 795.4 798.0 800.8 848.6 851.3 852.1 852.6 852.9 693.7 846.1 846.7 849.3 849.4 518.1 610.2 623.3 625.8 626.7 12226.4 12239.8 12248.1 12281.3 12296.4 11709.8 11817.8 12375.3 12495.5 13002.4 11092.5 11273.8 11364.0 11378.4 11456.1 4201.3 4212.2 4268.3 4270.3 4399.7 58611.4 58743.4 60246.4 60634.4 61081.0 9977.6 10068.1 10114.9 10216.1 10218.5 17253.7 17387.5 17399.3 17401.0 17423.9 7787.3 7930.8 7987.6 7995.7 8455.7 4266.8 4344.0 4383.7 4475.6 4510.5 3104.3 3143.6 3197.7 3203.2 3208.0 547.0 565.3 574.2 588.4 617.3 690.5 723.8 725.4 738.9 751.5 9421.5 9682.5 9876.8 9989.3 10032.2 644.5 665.2 759.4 776.0 789.8 4338.9 4447.0 4746.1 4757.8 4826.7 79289.6 79452.6 79582.6 82784.0 83159.2 46667.8 70198.3 70530.3 70616.6 72840.6 62437.7 64503.0 64840.4 64946.7 65212.0 8737.5 9199.4 9221.5 9228.2 9288.7 31309.0 35891.0 37188.1 37417.6 37502.6 38970.0 39168.8 39595.0 52256.0 54844.5 11989.7 12415.1 12571.7 12793.6 13310.5 13733.0 13920.1 13950.3 14048.6 14600.2 12539.7 12813.7 13091.7 13107.2 13578.0 12576.9 12847.0 13639.8 13828.8 14102.4 12193.3 12338.9 12397.4 12756.0 12885.2 10212.6 10548.9 10801.9 10809.2 10992.0 8613.5 9237.7 9263.6 9994.4 10071.0 10:17:40 INFO - PROCESS | 28519 | -------- Summary: end -------- 10:17:40 INFO - PROCESS | 28519 | 10:17:41 INFO - TEST-INFO | 28519: exit 0 10:17:41 INFO - TEST-OK | dromaeo_css | took 1117630ms 10:17:41 INFO - TEST-START | kraken 10:17:41 INFO - operating with platform_type : linux_ 10:17:41 INFO - Initialising browser for kraken test... 10:17:41 INFO - TEST-INFO | started process 28833 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpv4cxCP/profile http://localhost:40062/getInfo.html) 10:17:45 INFO - PROCESS | 28833 | __metrics Screen width/height:1600/1200 10:17:45 INFO - PROCESS | 28833 | colorDepth:24 10:17:45 INFO - PROCESS | 28833 | Browser inner width/height: 1024/697 10:17:45 INFO - PROCESS | 28833 | __metrics 10:17:46 INFO - PROCESS | 28833 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:17:46 INFO - PROCESS | 28833 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:46 INFO - PROCESS | 28833 | console.error: 10:17:46 INFO - PROCESS | 28833 | Message: Error: Connection closed before committing the transaction. 10:17:46 INFO - PROCESS | 28833 | Stack: 10:17:46 INFO - PROCESS | 28833 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 10:17:46 INFO - PROCESS | 28833 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 10:17:46 INFO - PROCESS | 28833 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 10:17:46 INFO - PROCESS | 28833 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 10:17:46 INFO - PROCESS | 28833 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 10:17:46 INFO - PROCESS | 28833 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 10:17:46 INFO - PROCESS | 28833 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 10:17:46 INFO - PROCESS | 28833 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 10:17:46 INFO - PROCESS | 28833 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 10:17:46 INFO - PROCESS | 28833 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 10:17:46 INFO - PROCESS | 28833 | Barrier/this.client.addBlocker/promise13.1% 10:18:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 10:18:43 INFO - TinderboxPrint: I/O write bytes / time
400,969,728 / 256,888 10:18:43 INFO - TinderboxPrint: CPU idle
8,294.9 (86.8%) 10:18:43 INFO - TinderboxPrint: CPU user
1,203.4 (12.6%) 10:18:43 INFO - TinderboxPrint: Swap in / out
0 / 0 10:18:43 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 110317568; Read time: 0; Write time: 82876 10:18:43 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 0; Write bytes: 290652160; Read time: 0; Write time: 174012 10:18:44 INFO - Running post-run listener: _upload_blobber_files 10:18:44 INFO - Blob upload gear active. 10:18:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:44 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 10:18:44 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'] 10:18:44 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 10:18:44 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:45 INFO - (blobuploader) - INFO - Done attempting. 10:18:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:46 INFO - (blobuploader) - INFO - Done attempting. 10:18:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:49 INFO - (blobuploader) - INFO - Done attempting. 10:18:49 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:49 INFO - Return code: 0 10:18:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5e36b10321a03d8520dd276a7f963d161e4acd44e449a11b2a77cf5c6abca679690d220bc020d2d5d8286d9aae487b9238a49ef18710df188f7a01b7126fec5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fca763d023d54c6fd8c633e2ddb5a99e8d88bfaa004518a88d27e933984f1a8cf8637e5f9ac349911825f6f95c46b54059a42e0ec8344db9ce500f373a308def", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/705b010781629505907c35b888c67a34030df2147e0be112827f55d91e461faee6639746547b5f26ba6777abdffd8a47d1768cf70e945b9383d087ecb11eb5e5"} 10:18:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:49 INFO - Contents: 10:18:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5e36b10321a03d8520dd276a7f963d161e4acd44e449a11b2a77cf5c6abca679690d220bc020d2d5d8286d9aae487b9238a49ef18710df188f7a01b7126fec5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fca763d023d54c6fd8c633e2ddb5a99e8d88bfaa004518a88d27e933984f1a8cf8637e5f9ac349911825f6f95c46b54059a42e0ec8344db9ce500f373a308def", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/705b010781629505907c35b888c67a34030df2147e0be112827f55d91e461faee6639746547b5f26ba6777abdffd8a47d1768cf70e945b9383d087ecb11eb5e5"} 10:18:49 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:49 INFO - Copying logs to upload dir... 10:18:49 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.124304 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-11-03 10:18:49.605171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 10:18:49.609293) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270 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/d5e36b10321a03d8520dd276a7f963d161e4acd44e449a11b2a77cf5c6abca679690d220bc020d2d5d8286d9aae487b9238a49ef18710df188f7a01b7126fec5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fca763d023d54c6fd8c633e2ddb5a99e8d88bfaa004518a88d27e933984f1a8cf8637e5f9ac349911825f6f95c46b54059a42e0ec8344db9ce500f373a308def", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/705b010781629505907c35b888c67a34030df2147e0be112827f55d91e461faee6639746547b5f26ba6777abdffd8a47d1768cf70e945b9383d087ecb11eb5e5"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011439 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/mozilla-inbound-linux64-pgo/1478093892/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d5e36b10321a03d8520dd276a7f963d161e4acd44e449a11b2a77cf5c6abca679690d220bc020d2d5d8286d9aae487b9238a49ef18710df188f7a01b7126fec5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fca763d023d54c6fd8c633e2ddb5a99e8d88bfaa004518a88d27e933984f1a8cf8637e5f9ac349911825f6f95c46b54059a42e0ec8344db9ce500f373a308def", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/705b010781629505907c35b888c67a34030df2147e0be112827f55d91e461faee6639746547b5f26ba6777abdffd8a47d1768cf70e945b9383d087ecb11eb5e5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-03 10:18:49.636036) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 10:18:49.636544) ========= 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=769a0cfe1e83e11e6f11f5a3000001a8-1478192290.480037-719576270 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-03 10:18:49.719019) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 10:18:49.719335) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-03 10:18:49.719688) ========= ========= Total master_lag: 0.17 =========