builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-020 starttime: 1476798017.78 results: success (0) buildid: 20161018062554 builduid: e8603878bd7f470cbf61e785c53c379a revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.784205) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.784678) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.784975) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.799620) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.799943) ========= 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=af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-18 06:40:18-- 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% 225M=0s 2016-10-18 06:40:18 (225 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111756 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.925395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.925704) ========= 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=af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017655 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:17.975161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 06:40:17.975516) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dc89484d4b45abf442162e5ea2dd46f9de40197d --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=af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-18 06:40:18,498 truncating revision to first 12 chars 2016-10-18 06:40:18,498 Setting DEBUG logging. 2016-10-18 06:40:18,498 attempt 1/10 2016-10-18 06:40:18,498 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dc89484d4b45?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 06:40:19,621 unpacking tar archive at: fx-team-dc89484d4b45/testing/mozharness/ program finished with exit code 0 elapsedTime=1.430648 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 06:40:19.419727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:19.420057) ========= script_repo_revision: dc89484d4b45abf442162e5ea2dd46f9de40197d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:19.420462) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:19.420727) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 06:40:19.431324) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-18 06:40:19.431632) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 06:40:20 INFO - MultiFileLogger online at 20161018 06:40:20 in /builds/slave/test 06:40:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:40:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:40:20 INFO - {'append_to_log': False, 06:40:20 INFO - 'base_work_dir': '/builds/slave/test', 06:40:20 INFO - 'blob_upload_branch': 'Fx-Team', 06:40:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:40:20 INFO - 'branch': 'Fx-Team', 06:40:20 INFO - 'buildbot_json_path': 'buildprops.json', 06:40:20 INFO - 'config_files': ('talos/linux_config.py',), 06:40:20 INFO - 'default_actions': ('clobber', 06:40:20 INFO - 'read-buildbot-config', 06:40:20 INFO - 'download-and-extract', 06:40:20 INFO - 'populate-webroot', 06:40:20 INFO - 'create-virtualenv', 06:40:20 INFO - 'install', 06:40:20 INFO - 'run-tests'), 06:40:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:40:20 INFO - 'download_minidump_stackwalk': True, 06:40:20 INFO - 'download_symbols': 'ondemand', 06:40:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:40:20 INFO - 'tooltool.py': '/tools/tooltool.py', 06:40:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:40:20 INFO - '/tools/misc-python/virtualenv.py')}, 06:40:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:40:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:40:20 INFO - 'installer_path': 'installer.exe', 06:40:20 INFO - 'log_level': 'info', 06:40:20 INFO - 'log_name': 'talos', 06:40:20 INFO - 'log_to_console': True, 06:40:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 06:40:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 06:40:20 INFO - 'opt_config_files': (), 06:40:20 INFO - 'pip_index': False, 06:40:20 INFO - 'sps_profile': False, 06:40:20 INFO - 'sps_profile_interval': 0, 06:40:20 INFO - 'suite': 'dromaeojs', 06:40:20 INFO - 'system_bits': '32', 06:40:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:40:20 INFO - 'title': 'talos-linux64-ix-020', 06:40:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:40:20 INFO - 'use_talos_json': True, 06:40:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:40:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:40:20 INFO - 'work_dir': 'build'} 06:40:20 INFO - [mozharness: 2016-10-18 13:40:20.052203Z] Running clobber step. 06:40:20 INFO - Running pre-action listener: _resource_record_pre_action 06:40:20 INFO - Running main action method: clobber 06:40:20 INFO - rmtree: /builds/slave/test/build 06:40:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:40:20 INFO - Running post-action listener: _resource_record_post_action 06:40:20 INFO - [mozharness: 2016-10-18 13:40:20.521737Z] Finished clobber step (success) 06:40:20 INFO - [mozharness: 2016-10-18 13:40:20.521836Z] Running read-buildbot-config step. 06:40:20 INFO - Running pre-action listener: _resource_record_pre_action 06:40:20 INFO - Running main action method: read_buildbot_config 06:40:20 INFO - Using buildbot properties: 06:40:20 INFO - { 06:40:20 INFO - "project": "", 06:40:20 INFO - "product": "firefox", 06:40:20 INFO - "script_repo_revision": "production", 06:40:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 06:40:20 INFO - "repository": "", 06:40:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 06:40:20 INFO - "buildid": "20161018062554", 06:40:20 INFO - "stage_platform": "linux64-pgo", 06:40:20 INFO - "basedir": "/builds/slave/test", 06:40:20 INFO - "buildnumber": 538, 06:40:20 INFO - "platform": "ubuntu64_hw", 06:40:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 06:40:20 INFO - "slavebuilddir": "test", 06:40:20 INFO - "branch": "fx-team", 06:40:20 INFO - "revision": "dc89484d4b45abf442162e5ea2dd46f9de40197d", 06:40:20 INFO - "slavename": "talos-linux64-ix-020", 06:40:20 INFO - "builduid": "e8603878bd7f470cbf61e785c53c379a", 06:40:20 INFO - "repo_path": "integration/fx-team" 06:40:20 INFO - } 06:40:20 INFO - Found installer url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.tar.bz2. 06:40:20 INFO - Found a test packages url https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.test_packages.json. 06:40:20 INFO - Running post-action listener: _resource_record_post_action 06:40:20 INFO - [mozharness: 2016-10-18 13:40:20.524175Z] Finished read-buildbot-config step (success) 06:40:20 INFO - [mozharness: 2016-10-18 13:40:20.524261Z] Running download-and-extract step. 06:40:20 INFO - Running pre-action listener: _resource_record_pre_action 06:40:20 INFO - Running main action method: download_and_extract 06:40:20 INFO - mkdir: /builds/slave/test/build/tests 06:40:20 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')]} 06:40:20 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 06:40:20 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/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 06:40:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/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 06:40:20 INFO - Downloaded 1361 bytes. 06:40:20 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.linux-x86_64.test_packages.json 06:40:20 INFO - Using the following test package requirements: 06:40:20 INFO - {u'common': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:20 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 06:40:20 INFO - u'gtest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.gtest.tests.zip'], 06:40:20 INFO - u'jittest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'jsshell-linux-x86_64.zip'], 06:40:20 INFO - u'mochitest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 06:40:20 INFO - u'mozbase': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'], 06:40:20 INFO - u'reftest': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.reftest.tests.zip'], 06:40:20 INFO - u'talos': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'], 06:40:20 INFO - u'web-platform': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 06:40:20 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip', 06:40:20 INFO - u'firefox-52.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 06:40:20 INFO - Downloading packages: [u'firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 06:40:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 06:40:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:40:20 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 06:40:20 INFO - Http code: 200 06:40:20 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 06:40:20 INFO - x-amz-cf-id: k2KGijoThQO6nSBPbkkaWhJ866ed5WBL7amoWRivzMKtsg8qDwF4gw== 06:40:20 INFO - x-amz-replication-status: COMPLETED 06:40:20 INFO - x-amz-version-id: qvnGSvvlURsDbCc9nUOY_3tO5113X.h9 06:40:20 INFO - Expected file size: 22090426 06:40:20 INFO - Obtained file size: 22090426 06:40:24 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 06:40:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip 06:40:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 06:40:24 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.common.tests.zip into memory 06:40:24 INFO - Http code: 200 06:40:24 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 06:40:24 INFO - x-amz-cf-id: _JUiImZGra1_rtt2EyebE6wZX9mE46OY2AWlLFChJltzqQgYLlQ1pg== 06:40:24 INFO - x-amz-replication-status: COMPLETED 06:40:24 INFO - x-amz-version-id: qvnGSvvlURsDbCc9nUOY_3tO5113X.h9 06:40:25 INFO - Expected file size: 22090426 06:40:25 INFO - Obtained file size: 22090426 06:40:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip 06:40:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 06:40:29 INFO - Fetch https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.talos.tests.zip into memory 06:40:29 INFO - Http code: 200 06:40:29 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 06:40:29 INFO - x-amz-cf-id: s3zIy24nkwZShd-J1rAMaz7lMGx5TQKDH8TMZ4gJNRXvYDk4YL3p_g== 06:40:29 INFO - x-amz-replication-status: COMPLETED 06:40:29 INFO - x-amz-version-id: cJpeD_KFrXWBJYRyuLhjwwo6uCOh9UD4 06:40:30 INFO - Expected file size: 14409028 06:40:30 INFO - Obtained file size: 14409028 06:40: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')]} 06:40:30 INFO - trying https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:40:30 INFO - Downloading https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/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 06:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/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 06:40:30 INFO - Downloaded 59486961 bytes. 06:40:30 INFO - Setting buildbot property build_url to https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:40:30 INFO - mkdir: /builds/slave/test/properties 06:40:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:40:30 INFO - Writing to file /builds/slave/test/properties/build_url 06:40:30 INFO - Contents: 06:40:30 INFO - build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 06:40:31 INFO - Running post-action listener: _resource_record_post_action 06:40:31 INFO - Running post-action listener: set_extra_try_arguments 06:40:31 INFO - [mozharness: 2016-10-18 13:40:31.283258Z] Finished download-and-extract step (success) 06:40:31 INFO - [mozharness: 2016-10-18 13:40:31.283483Z] Running populate-webroot step. 06:40:31 INFO - Running pre-action listener: _resource_record_pre_action 06:40:31 INFO - Running main action method: populate_webroot 06:40:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:40:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:40:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:40:31 INFO - '--tppagecycles', 06:40:31 INFO - '7'], 06:40:31 INFO - 'tests': ['tsvgm']}}, 06:40:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:40:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:40:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:40:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:40:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:40:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:40:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['damp', 'tps']}, 06:40:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'tests': ['damp', 'tps']}, 06:40:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['dromaeo_dom']}, 06:40:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:40:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['basic_compositor_video']}, 06:40:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:40:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['a11yr', 06:40:31 INFO - 'ts_paint', 06:40:31 INFO - 'tpaint', 06:40:31 INFO - 'sessionrestore', 06:40:31 INFO - 'sessionrestore_no_auto_restore', 06:40:31 INFO - 'tabpaint']}, 06:40:31 INFO - 'other-e10s': {'tests': ['a11yr', 06:40:31 INFO - 'ts_paint', 06:40:31 INFO - 'tpaint', 06:40:31 INFO - 'sessionrestore', 06:40:31 INFO - 'sessionrestore_no_auto_restore', 06:40:31 INFO - 'tabpaint']}, 06:40:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:40:31 INFO - 'ts_paint', 06:40:31 INFO - 'tpaint', 06:40:31 INFO - 'sessionrestore', 06:40:31 INFO - 'sessionrestore_no_auto_restore', 06:40:31 INFO - 'tabpaint']}, 06:40:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:40:31 INFO - 'ts_paint', 06:40:31 INFO - 'tpaint', 06:40:31 INFO - 'sessionrestore', 06:40:31 INFO - 'sessionrestore_no_auto_restore', 06:40:31 INFO - 'tabpaint']}, 06:40:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['a11yr', 06:40:31 INFO - 'ts_paint', 06:40:31 INFO - 'tpaint', 06:40:31 INFO - 'sessionrestore', 06:40:31 INFO - 'sessionrestore_no_auto_restore', 06:40:31 INFO - 'tabpaint']}, 06:40:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['a11yr', 06:40:31 INFO - 'ts_paint', 06:40:31 INFO - 'tpaint', 06:40:31 INFO - 'sessionrestore', 06:40:31 INFO - 'sessionrestore_no_auto_restore', 06:40:31 INFO - 'tabpaint']}, 06:40:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['tsvgx', 06:40:31 INFO - 'tsvgr_opacity', 06:40:31 INFO - 'tart', 06:40:31 INFO - 'tscrollx', 06:40:31 INFO - 'cart']}, 06:40:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:40:31 INFO - 'tsvgr_opacity', 06:40:31 INFO - 'tart', 06:40:31 INFO - 'tscrollx', 06:40:31 INFO - 'cart']}, 06:40:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'talos_options': ['--disable-e10s'], 06:40:31 INFO - 'tests': ['tp5o']}, 06:40:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'tests': ['tp5o']}, 06:40:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'talos_options': ['--disable-e10s', 06:40:31 INFO - '--xperf_path', 06:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:40:31 INFO - 'tests': ['tp5n']}, 06:40:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:40:31 INFO - 'talos_options': ['--xperf_path', 06:40:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:40:31 INFO - 'tests': ['tp5n']}}, 06:40:31 INFO - 'talos.zip': {'path': '', 06:40:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:40:31 INFO - Running post-action listener: _resource_record_post_action 06:40:31 INFO - [mozharness: 2016-10-18 13:40:31.300565Z] Finished populate-webroot step (success) 06:40:31 INFO - [mozharness: 2016-10-18 13:40:31.300652Z] Running create-virtualenv step. 06:40:31 INFO - Running pre-action listener: _resource_record_pre_action 06:40:31 INFO - Running main action method: create_virtualenv 06:40:31 INFO - Creating virtualenv /builds/slave/test/build/venv 06:40:31 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 06:40:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:40:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:40:31 INFO - Using real prefix '/usr' 06:40:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:40:31 INFO - Installing distribute.............................................................................................................................................................................................done. 06:40:33 INFO - Installing pip.................done. 06:40:33 INFO - Return code: 0 06:40:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:40:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:40:34 INFO - Reading from file tmpfile_stdout 06:40:34 INFO - Output received: 06:40:34 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) 06:40:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:34 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:34 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 06:40:34 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 06:40:34 INFO - Using env: {'DISPLAY': ':0', 06:40:34 INFO - 'HOME': '/home/cltbld', 06:40:34 INFO - 'LANG': 'en_US.UTF-8', 06:40:34 INFO - 'LANGUAGE': 'en_US:en', 06:40:34 INFO - 'LOGNAME': 'cltbld', 06:40:34 INFO - 'MAIL': '/var/mail/cltbld', 06:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:34 INFO - 'MOZ_NO_REMOTE': '1', 06:40:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:34 INFO - 'NO_EM_RESTART': '1', 06:40:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:34 INFO - 'PWD': '/builds/slave/test', 06:40:34 INFO - 'SHELL': '/bin/bash', 06:40:34 INFO - 'SHLVL': '1', 06:40:34 INFO - 'TERM': 'linux', 06:40:34 INFO - 'TMOUT': '86400', 06:40:34 INFO - 'USER': 'cltbld', 06:40:34 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:34 INFO - '_': '/tools/buildbot/bin/python'} 06:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:34 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 06:40:34 INFO - Cleaning up... 06:40:34 INFO - Return code: 0 06:40:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:34 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:34 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 06:40:34 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 06:40:34 INFO - Using env: {'DISPLAY': ':0', 06:40:34 INFO - 'HOME': '/home/cltbld', 06:40:34 INFO - 'LANG': 'en_US.UTF-8', 06:40:34 INFO - 'LANGUAGE': 'en_US:en', 06:40:34 INFO - 'LOGNAME': 'cltbld', 06:40:34 INFO - 'MAIL': '/var/mail/cltbld', 06:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:34 INFO - 'MOZ_NO_REMOTE': '1', 06:40:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:34 INFO - 'NO_EM_RESTART': '1', 06:40:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:34 INFO - 'PWD': '/builds/slave/test', 06:40:34 INFO - 'SHELL': '/bin/bash', 06:40:34 INFO - 'SHLVL': '1', 06:40:34 INFO - 'TERM': 'linux', 06:40:34 INFO - 'TMOUT': '86400', 06:40:34 INFO - 'USER': 'cltbld', 06:40:34 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:34 INFO - '_': '/tools/buildbot/bin/python'} 06:40:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:34 INFO - Downloading/unpacking psutil>=3.1.1 06:40: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 06:40: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 06:40: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 06:40: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 06:40: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 06:40: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 06:40:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:40:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:40:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:40:36 INFO - Installing collected packages: psutil 06:40:36 INFO - Running setup.py install for psutil 06:40:36 INFO - building 'psutil._psutil_linux' extension 06:40:36 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 06:40:36 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 06:40:36 INFO - building 'psutil._psutil_posix' extension 06:40:36 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 06:40:36 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 06:40:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:40:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:40:37 INFO - Successfully installed psutil 06:40:37 INFO - Cleaning up... 06:40:37 INFO - Return code: 0 06:40:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:40:37 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')]} 06:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:37 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')]} 06:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:37 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:37 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 06:40:37 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 06:40:37 INFO - Using env: {'DISPLAY': ':0', 06:40:37 INFO - 'HOME': '/home/cltbld', 06:40:37 INFO - 'LANG': 'en_US.UTF-8', 06:40:37 INFO - 'LANGUAGE': 'en_US:en', 06:40:37 INFO - 'LOGNAME': 'cltbld', 06:40:37 INFO - 'MAIL': '/var/mail/cltbld', 06:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:37 INFO - 'MOZ_NO_REMOTE': '1', 06:40:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:37 INFO - 'NO_EM_RESTART': '1', 06:40:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:37 INFO - 'PWD': '/builds/slave/test', 06:40:37 INFO - 'SHELL': '/bin/bash', 06:40:37 INFO - 'SHLVL': '1', 06:40:37 INFO - 'TERM': 'linux', 06:40:37 INFO - 'TMOUT': '86400', 06:40:37 INFO - 'USER': 'cltbld', 06:40:37 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:37 INFO - '_': '/tools/buildbot/bin/python'} 06:40:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:40: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 06:40: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 06:40: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 06:40: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 06:40: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 06:40: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 06:40:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:40:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:40:39 INFO - Installing collected packages: mozsystemmonitor 06:40:39 INFO - Running setup.py install for mozsystemmonitor 06:40:39 INFO - Successfully installed mozsystemmonitor 06:40:39 INFO - Cleaning up... 06:40:39 INFO - Return code: 0 06:40:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:40:39 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')]} 06:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:39 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')]} 06:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:39 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:39 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 06:40:39 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 06:40:39 INFO - Using env: {'DISPLAY': ':0', 06:40:39 INFO - 'HOME': '/home/cltbld', 06:40:39 INFO - 'LANG': 'en_US.UTF-8', 06:40:39 INFO - 'LANGUAGE': 'en_US:en', 06:40:39 INFO - 'LOGNAME': 'cltbld', 06:40:39 INFO - 'MAIL': '/var/mail/cltbld', 06:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:39 INFO - 'MOZ_NO_REMOTE': '1', 06:40:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:39 INFO - 'NO_EM_RESTART': '1', 06:40:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:39 INFO - 'PWD': '/builds/slave/test', 06:40:39 INFO - 'SHELL': '/bin/bash', 06:40:39 INFO - 'SHLVL': '1', 06:40:39 INFO - 'TERM': 'linux', 06:40:39 INFO - 'TMOUT': '86400', 06:40:39 INFO - 'USER': 'cltbld', 06:40:39 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:39 INFO - '_': '/tools/buildbot/bin/python'} 06:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:39 INFO - Downloading/unpacking jsonschema==2.5.1 06:40:39 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 06:40:39 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 06:40:39 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 06:40:39 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 06:40:39 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 06:40:39 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 06:40:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:40:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:40:41 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 06:40:41 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 06:40:41 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 06:40:41 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 06:40:41 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 06:40:41 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 06:40:42 INFO - Downloading functools32-3.2.3-2.tar.gz 06:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:40:42 INFO - warning: no files found matching '*.txt' 06:40:42 INFO - no previously-included directories found matching 'build' 06:40:42 INFO - no previously-included directories found matching 'dist' 06:40:42 INFO - no previously-included directories found matching '.git*' 06:40:42 INFO - Installing collected packages: jsonschema, functools32 06:40:42 INFO - Running setup.py install for functools32 06:40:42 INFO - warning: no files found matching '*.txt' 06:40:42 INFO - no previously-included directories found matching 'build' 06:40:42 INFO - no previously-included directories found matching 'dist' 06:40:42 INFO - no previously-included directories found matching '.git*' 06:40:42 INFO - Successfully installed jsonschema functools32 06:40:42 INFO - Cleaning up... 06:40:42 INFO - Return code: 0 06:40:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:42 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:42 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 06:40:42 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 06:40:42 INFO - Using env: {'DISPLAY': ':0', 06:40:42 INFO - 'HOME': '/home/cltbld', 06:40:42 INFO - 'LANG': 'en_US.UTF-8', 06:40:42 INFO - 'LANGUAGE': 'en_US:en', 06:40:42 INFO - 'LOGNAME': 'cltbld', 06:40:42 INFO - 'MAIL': '/var/mail/cltbld', 06:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:42 INFO - 'MOZ_NO_REMOTE': '1', 06:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:42 INFO - 'NO_EM_RESTART': '1', 06:40:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:42 INFO - 'PWD': '/builds/slave/test', 06:40:42 INFO - 'SHELL': '/bin/bash', 06:40:42 INFO - 'SHLVL': '1', 06:40:42 INFO - 'TERM': 'linux', 06:40:42 INFO - 'TMOUT': '86400', 06:40:42 INFO - 'USER': 'cltbld', 06:40:42 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:42 INFO - '_': '/tools/buildbot/bin/python'} 06:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:40:42 INFO - Cleaning up... 06:40:42 INFO - Return code: 0 06:40:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:42 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:42 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 06:40:42 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 06:40:42 INFO - Using env: {'DISPLAY': ':0', 06:40:42 INFO - 'HOME': '/home/cltbld', 06:40:42 INFO - 'LANG': 'en_US.UTF-8', 06:40:42 INFO - 'LANGUAGE': 'en_US:en', 06:40:42 INFO - 'LOGNAME': 'cltbld', 06:40:42 INFO - 'MAIL': '/var/mail/cltbld', 06:40:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:42 INFO - 'MOZ_NO_REMOTE': '1', 06:40:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:42 INFO - 'NO_EM_RESTART': '1', 06:40:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:42 INFO - 'PWD': '/builds/slave/test', 06:40:42 INFO - 'SHELL': '/bin/bash', 06:40:42 INFO - 'SHLVL': '1', 06:40:42 INFO - 'TERM': 'linux', 06:40:42 INFO - 'TMOUT': '86400', 06:40:42 INFO - 'USER': 'cltbld', 06:40:42 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:42 INFO - '_': '/tools/buildbot/bin/python'} 06:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:42 INFO - Downloading/unpacking blobuploader==1.2.4 06:40:42 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 06:40:42 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 06:40:42 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 06:40:42 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 06:40:42 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 06:40:42 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 06:40:44 INFO - Downloading blobuploader-1.2.4.tar.gz 06:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:40:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:40:44 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 06:40:44 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 06:40:44 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 06:40:44 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 06:40:44 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 06:40:44 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 06:40:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:40:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:40:45 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 06:40:45 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 06:40:45 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 06:40:45 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 06:40:45 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 06:40:45 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 06:40:45 INFO - Downloading docopt-0.6.1.tar.gz 06:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:40:45 INFO - Installing collected packages: blobuploader, requests, docopt 06:40:45 INFO - Running setup.py install for blobuploader 06:40:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:40:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:40:45 INFO - Running setup.py install for requests 06:40:45 INFO - Running setup.py install for docopt 06:40:45 INFO - Successfully installed blobuploader requests docopt 06:40:45 INFO - Cleaning up... 06:40:46 INFO - Return code: 0 06:40:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:40: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')]} 06:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40: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')]} 06:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:46 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:46 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 06:40:46 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 06:40:46 INFO - Using env: {'DISPLAY': ':0', 06:40:46 INFO - 'HOME': '/home/cltbld', 06:40:46 INFO - 'LANG': 'en_US.UTF-8', 06:40:46 INFO - 'LANGUAGE': 'en_US:en', 06:40:46 INFO - 'LOGNAME': 'cltbld', 06:40:46 INFO - 'MAIL': '/var/mail/cltbld', 06:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:46 INFO - 'MOZ_NO_REMOTE': '1', 06:40:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:46 INFO - 'NO_EM_RESTART': '1', 06:40:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:46 INFO - 'PWD': '/builds/slave/test', 06:40:46 INFO - 'SHELL': '/bin/bash', 06:40:46 INFO - 'SHLVL': '1', 06:40:46 INFO - 'TERM': 'linux', 06:40:46 INFO - 'TMOUT': '86400', 06:40:46 INFO - 'USER': 'cltbld', 06:40:46 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:46 INFO - '_': '/tools/buildbot/bin/python'} 06:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:40:46 INFO - Running setup.py (path:/tmp/pip-4fapQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:40:46 INFO - Running setup.py (path:/tmp/pip-N75Rei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:40:46 INFO - Running setup.py (path:/tmp/pip-lqGnCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:40:46 INFO - Running setup.py (path:/tmp/pip-XWDsLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:40:46 INFO - Running setup.py (path:/tmp/pip-Z3lRxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:40:46 INFO - Running setup.py (path:/tmp/pip-M2K5fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:40:46 INFO - Running setup.py (path:/tmp/pip-n4y71D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:40:46 INFO - Running setup.py (path:/tmp/pip-GvIDO7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:40:46 INFO - Running setup.py (path:/tmp/pip-aAHJfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:40:46 INFO - Running setup.py (path:/tmp/pip-SkxU5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:40:46 INFO - Running setup.py (path:/tmp/pip-d6dBNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:40:47 INFO - Running setup.py (path:/tmp/pip-p2vm1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:40:47 INFO - Running setup.py (path:/tmp/pip-mHwQkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:40:47 INFO - Running setup.py (path:/tmp/pip-cbV9my-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:40:47 INFO - Running setup.py (path:/tmp/pip-bMb0Bh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:40:47 INFO - Running setup.py (path:/tmp/pip-shblzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:40:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:40:47 INFO - Running setup.py (path:/tmp/pip-BB9MTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:40:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:40:47 INFO - Running setup.py install for manifestparser 06:40:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:40:47 INFO - Running setup.py install for mozcrash 06:40:47 INFO - Running setup.py install for mozdebug 06:40:47 INFO - Running setup.py install for mozdevice 06:40:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:40:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:40:47 INFO - Running setup.py install for mozfile 06:40:47 INFO - Running setup.py install for mozhttpd 06:40:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for mozinfo 06:40:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for mozInstall 06:40:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for mozleak 06:40:48 INFO - Running setup.py install for mozlog 06:40:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for moznetwork 06:40:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for mozprocess 06:40:48 INFO - Running setup.py install for mozprofile 06:40:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for mozrunner 06:40:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:40:48 INFO - Running setup.py install for mozscreenshot 06:40:49 INFO - Running setup.py install for moztest 06:40:49 INFO - Running setup.py install for mozversion 06:40:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:40:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:40:49 INFO - Cleaning up... 06:40:49 INFO - Return code: 0 06:40:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:49 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:49 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 06:40:49 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 06:40:49 INFO - Using env: {'DISPLAY': ':0', 06:40:49 INFO - 'HOME': '/home/cltbld', 06:40:49 INFO - 'LANG': 'en_US.UTF-8', 06:40:49 INFO - 'LANGUAGE': 'en_US:en', 06:40:49 INFO - 'LOGNAME': 'cltbld', 06:40:49 INFO - 'MAIL': '/var/mail/cltbld', 06:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:49 INFO - 'MOZ_NO_REMOTE': '1', 06:40:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:49 INFO - 'NO_EM_RESTART': '1', 06:40:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:49 INFO - 'PWD': '/builds/slave/test', 06:40:49 INFO - 'SHELL': '/bin/bash', 06:40:49 INFO - 'SHLVL': '1', 06:40:49 INFO - 'TERM': 'linux', 06:40:49 INFO - 'TMOUT': '86400', 06:40:49 INFO - 'USER': 'cltbld', 06:40:49 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:49 INFO - '_': '/tools/buildbot/bin/python'} 06:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:40:49 INFO - Running setup.py (path:/tmp/pip-_J0G0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:40:49 INFO - Running setup.py (path:/tmp/pip-FhGylQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:40:49 INFO - Running setup.py (path:/tmp/pip-i8e6Ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:40:49 INFO - Running setup.py (path:/tmp/pip-Zhgq4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:40:49 INFO - Running setup.py (path:/tmp/pip-wq3c8E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:40:49 INFO - Running setup.py (path:/tmp/pip-3izPXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:40:49 INFO - Running setup.py (path:/tmp/pip-CRS197-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:40:49 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)) 06:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:40:49 INFO - Running setup.py (path:/tmp/pip-awopeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:40:50 INFO - Running setup.py (path:/tmp/pip-OsZ2t5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:40:50 INFO - Running setup.py (path:/tmp/pip-ro9vej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:40:50 INFO - Running setup.py (path:/tmp/pip-OivCw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:40:50 INFO - Running setup.py (path:/tmp/pip-bdrqRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:40:50 INFO - Running setup.py (path:/tmp/pip-jATyST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:40:50 INFO - Running setup.py (path:/tmp/pip-C2BA5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:40:50 INFO - Running setup.py (path:/tmp/pip-dnjXln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:40:50 INFO - Running setup.py (path:/tmp/pip-88hz6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:40:50 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)) 06:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:40:50 INFO - Running setup.py (path:/tmp/pip-sMY7Dg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:40:50 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)) 06:40:50 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)) 06:40:50 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)) 06:40:50 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)) 06:40:50 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)) 06:40:50 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)) 06:40:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:40:50 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 06:40:50 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 06:40:50 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 06:40:50 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 06:40:50 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 06:40:50 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 06:40:52 INFO - Downloading blessings-1.6.tar.gz 06:40:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:40:52 INFO - Installing collected packages: blessings 06:40:52 INFO - Running setup.py install for blessings 06:40:53 INFO - Successfully installed blessings 06:40:53 INFO - Cleaning up... 06:40:53 INFO - Return code: 0 06:40:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:40:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:40:53 INFO - Reading from file tmpfile_stdout 06:40:53 INFO - Current package versions: 06:40:53 INFO - argparse == 1.2.1 06:40:53 INFO - blessings == 1.6 06:40:53 INFO - blobuploader == 1.2.4 06:40:53 INFO - docopt == 0.6.1 06:40:53 INFO - functools32 == 3.2.3-2 06:40:53 INFO - jsonschema == 2.5.1 06:40:53 INFO - manifestparser == 1.1 06:40:53 INFO - mozInstall == 1.12 06:40:53 INFO - mozcrash == 0.17 06:40:53 INFO - mozdebug == 0.1 06:40:53 INFO - mozdevice == 0.48 06:40:53 INFO - mozfile == 1.2 06:40:53 INFO - mozhttpd == 0.7 06:40:53 INFO - mozinfo == 0.9 06:40:53 INFO - mozleak == 0.1 06:40:53 INFO - mozlog == 3.4 06:40:53 INFO - moznetwork == 0.27 06:40:53 INFO - mozprocess == 0.23 06:40:53 INFO - mozprofile == 0.28 06:40:53 INFO - mozrunner == 6.12 06:40:53 INFO - mozscreenshot == 0.1 06:40:53 INFO - mozsystemmonitor == 0.3 06:40:53 INFO - moztest == 0.7 06:40:53 INFO - mozversion == 1.4 06:40:53 INFO - psutil == 3.1.1 06:40:53 INFO - requests == 1.2.3 06:40:53 INFO - wsgiref == 0.1.2 06:40:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:53 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 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:53 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 06:40:53 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 06:40:53 INFO - Using env: {'DISPLAY': ':0', 06:40:53 INFO - 'HOME': '/home/cltbld', 06:40:53 INFO - 'LANG': 'en_US.UTF-8', 06:40:53 INFO - 'LANGUAGE': 'en_US:en', 06:40:53 INFO - 'LOGNAME': 'cltbld', 06:40:53 INFO - 'MAIL': '/var/mail/cltbld', 06:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:53 INFO - 'MOZ_NO_REMOTE': '1', 06:40:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:53 INFO - 'NO_EM_RESTART': '1', 06:40:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:53 INFO - 'PWD': '/builds/slave/test', 06:40:53 INFO - 'SHELL': '/bin/bash', 06:40:53 INFO - 'SHLVL': '1', 06:40:53 INFO - 'TERM': 'linux', 06:40:53 INFO - 'TMOUT': '86400', 06:40:53 INFO - 'USER': 'cltbld', 06:40:53 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:53 INFO - '_': '/tools/buildbot/bin/python'} 06:40:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 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)) 06:40:53 INFO - Cleaning up... 06:40:53 INFO - Return code: 0 06:40:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:40:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:40:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:40:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:40:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f37b2568f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf037b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, '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': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', '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 06:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:40:53 INFO - Using env: {'DISPLAY': ':0', 06:40:53 INFO - 'HOME': '/home/cltbld', 06:40:53 INFO - 'LANG': 'en_US.UTF-8', 06:40:53 INFO - 'LANGUAGE': 'en_US:en', 06:40:53 INFO - 'LOGNAME': 'cltbld', 06:40:53 INFO - 'MAIL': '/var/mail/cltbld', 06:40:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:40:53 INFO - 'MOZ_NO_REMOTE': '1', 06:40:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:40:53 INFO - 'NO_EM_RESTART': '1', 06:40:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:40:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:40:53 INFO - 'PWD': '/builds/slave/test', 06:40:53 INFO - 'SHELL': '/bin/bash', 06:40:53 INFO - 'SHLVL': '1', 06:40:53 INFO - 'TERM': 'linux', 06:40:53 INFO - 'TMOUT': '86400', 06:40:53 INFO - 'USER': 'cltbld', 06:40:53 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:40:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:40:53 INFO - '_': '/tools/buildbot/bin/python'} 06:40:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:40:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:40:53 INFO - Cleaning up... 06:40:53 INFO - Return code: 0 06:40:53 INFO - Running post-action listener: _resource_record_post_action 06:40:53 INFO - Running post-action listener: _start_resource_monitoring 06:40:53 INFO - Starting resource monitoring. 06:40:53 INFO - [mozharness: 2016-10-18 13:40:53.836336Z] Finished create-virtualenv step (success) 06:40:53 INFO - [mozharness: 2016-10-18 13:40:53.836845Z] Running install step. 06:40:53 INFO - Running pre-action listener: _resource_record_pre_action 06:40:53 INFO - Running main action method: install 06:40:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:40:54 INFO - Reading from file tmpfile_stdout 06:40:54 INFO - Detecting whether we're running mozinstall >=1.0... 06:40:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:40:54 INFO - Reading from file tmpfile_stdout 06:40:54 INFO - Output received: 06:40:54 INFO - Usage: mozinstall [options] installer 06:40:54 INFO - Options: 06:40:54 INFO - -h, --help show this help message and exit 06:40:54 INFO - -d DEST, --destination=DEST 06:40:54 INFO - Directory to install application into. [default: 06:40:54 INFO - "/builds/slave/test"] 06:40:54 INFO - --app=APP Application being installed. [default: firefox] 06:40:54 INFO - mkdir: /builds/slave/test/build/application 06:40:54 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'] 06:40:54 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 06:41:11 INFO - Reading from file tmpfile_stdout 06:41:11 INFO - Output received: 06:41:11 INFO - /builds/slave/test/build/application/firefox/firefox 06:41:11 INFO - Running post-action listener: _resource_record_post_action 06:41:11 INFO - [mozharness: 2016-10-18 13:41:11.254905Z] Finished install step (success) 06:41:11 INFO - [mozharness: 2016-10-18 13:41:11.255180Z] Running run-tests step. 06:41:11 INFO - Running pre-action listener: _resource_record_pre_action 06:41:11 INFO - Running main action method: run_tests 06:41:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:41:11 INFO - Python 2.7.3 06:41:11 INFO - Return code: 0 06:41:11 INFO - grabbing minidump binary from tooltool 06:41:11 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')]} 06:41:11 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 0xf92790>, '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 0xf93690>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf93b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:41:11 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 06:41:11 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 06:41:11 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 06:41:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:41:11 INFO - Return code: 0 06:41:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 06:41:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:41:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:41:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 06:41:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:41:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 06:41:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-020 --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 06:41:11 INFO - Using env: {'DISPLAY': ':0', 06:41:11 INFO - 'HOME': '/home/cltbld', 06:41:11 INFO - 'LANG': 'en_US.UTF-8', 06:41:11 INFO - 'LANGUAGE': 'en_US:en', 06:41:11 INFO - 'LOGNAME': 'cltbld', 06:41:11 INFO - 'MAIL': '/var/mail/cltbld', 06:41:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:41:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 06:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:41:11 INFO - 'MOZ_NO_REMOTE': '1', 06:41:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:41:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 06:41:11 INFO - 'NO_EM_RESTART': '1', 06:41:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 06:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:41:11 INFO - 'PWD': '/builds/slave/test', 06:41:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:41:11 INFO - 'SHELL': '/bin/bash', 06:41:11 INFO - 'SHLVL': '1', 06:41:11 INFO - 'TERM': 'linux', 06:41:11 INFO - 'TMOUT': '86400', 06:41:11 INFO - 'USER': 'cltbld', 06:41:11 INFO - 'XDG_SESSION_COOKIE': 'af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160', 06:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:41:11 INFO - '_': '/tools/buildbot/bin/python'} 06:41:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-020', '--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 06:41:11 INFO - mozversion application_buildid: 20161018033016 06:41:11 INFO - mozversion application_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 06:41:11 INFO - mozversion application_display_name: Nightly 06:41:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:41:11 INFO - mozversion application_name: Firefox 06:41:11 INFO - mozversion application_remotingname: firefox 06:41:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:41:11 INFO - mozversion application_vendor: Mozilla 06:41:11 INFO - mozversion application_version: 52.0a1 06:41:11 INFO - mozversion platform_buildid: 20161018033016 06:41:11 INFO - mozversion platform_changeset: dc89484d4b45abf442162e5ea2dd46f9de40197d 06:41:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:41:11 INFO - mozversion platform_version: 52.0a1 06:41:11 INFO - using testdate: 1476798071 06:41:11 INFO - actual date: 1476798071 06:41:11 INFO - starting webserver on 'localhost:60598' 06:41:11 INFO - SUITE-START | Running 2 tests 06:41:11 INFO - TEST-START | dromaeo_css 06:41:11 INFO - operating with platform_type : linux_ 06:41:11 INFO - Initialising browser for dromaeo_css test... 06:41:11 INFO - TEST-INFO | started process 12154 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAWabmN/profile http://localhost:60598/getInfo.html) 06:41:15 INFO - PROCESS | 12154 | __metrics Screen width/height:1600/1200 06:41:15 INFO - PROCESS | 12154 | colorDepth:24 06:41:15 INFO - PROCESS | 12154 | Browser inner width/height: 1024/697 06:41:15 INFO - PROCESS | 12154 | __metrics 06:41:16 INFO - PROCESS | 12154 | 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" 06:41:16 INFO - PROCESS | 12154 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:41:17 INFO - PROCESS | 12154 | console.error: PushService: 06:41:17 INFO - PROCESS | 12154 | stateChangeProcessEnqueue: Error transitioning state 06:41:17 INFO - PROCESS | 12154 | UnknownError 06:41:17 INFO - TEST-INFO | 12154: exit 0 06:41:17 INFO - Browser initialized. 06:41:17 INFO - Running cycle 1/1 for dromaeo_css test... 06:41:17 INFO - TEST-INFO | started process 12239 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpAWabmN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:41:30 INFO - PROCESS | 12239 | RSS: Main: 172150784 06:41:30 INFO - PROCESS | 12239 | 06:42:21 INFO - PROCESS | 12239 | 1476798141646 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] 06:44:31 INFO - PROCESS | 12239 | Cycle 1(1): loaded http://localhost:60598/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60598/tests/dromaeo/cssquery-ext.html) 06:44:34 INFO - PROCESS | 12239 | RSS: Main: 442355712 06:44:34 INFO - PROCESS | 12239 | 06:47:36 INFO - PROCESS | 12239 | Cycle 1(1): loaded http://localhost:60598/tests/dromaeo/cssquery-ext.html (next: http://localhost:60598/tests/dromaeo/cssquery-jquery.html) 06:47:37 INFO - PROCESS | 12239 | RSS: Main: 285175808 06:47:37 INFO - PROCESS | 12239 | 06:50:39 INFO - PROCESS | 12239 | Cycle 1(1): loaded http://localhost:60598/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60598/tests/dromaeo/cssquery-mootools.html) 06:50:40 INFO - PROCESS | 12239 | RSS: Main: 220004352 06:50:40 INFO - PROCESS | 12239 | 06:53:42 INFO - PROCESS | 12239 | Cycle 1(1): loaded http://localhost:60598/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60598/tests/dromaeo/cssquery-prototype.html) 06:53:42 INFO - PROCESS | 12239 | RSS: Main: 282681344 06:53:42 INFO - PROCESS | 12239 | 06:56:45 INFO - PROCESS | 12239 | Cycle 1(1): loaded http://localhost:60598/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60598/tests/dromaeo/cssquery-yui.html) 06:56:46 INFO - PROCESS | 12239 | RSS: Main: 221007872 06:56:46 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | Cycle 1(1): loaded http://localhost:60598/tests/dromaeo/cssquery-yui.html (next: http://localhost:60598/tests/dromaeo/cssquery-dojo.html) 06:59:49 INFO - PROCESS | 12239 | RSS: Main: 309489664 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | __start_tp_report 06:59:49 INFO - PROCESS | 12239 | _x_x_mozilla_page_load 06:59:49 INFO - PROCESS | 12239 | _x_x_mozilla_page_load_details 06:59:49 INFO - PROCESS | 12239 | |i|pagename|runs| 06:59:49 INFO - PROCESS | 12239 | |0;dojo.html;2997.1607949774066;3031.605891234139;3061.73975212107;3070.4166208420397;3080.861361238744;5157.174852023677;5269.789208431658;5273.367195936484;5340.279062326589;5348.839534813957;5266.420693723697;5283.947160528393;5339.279197308362;5369.5704343652515;5400.78396864125;4732.313814496896;4734.881627959311;4774.618030557564;4781.235002399617;4791.185498465252;4802.399700037496;4829.203181475053;4868.780904859272;4869.342638743759;4930.975345123269;4734.644901632391;4905.730184839832;4916.557509824133;4921.704697718131;5026.949730502684;4767.594754445841;4788.257820037899;4816.975915120402;4834.9516504834855;4839.782209800533;4768.523147685239;4779.784909679072;4786.617070634341;4817.903641927177;4851.854444366674;5254.842354729364;5282.207668849704;5286.81496147633;5298.36419629643;5298.470152984671;5424.186372044185;5461.672299661993;5595.21666966625;5631.267935168402;5658.858528536778;5732.8853422931725;5747.1379293106365;5750.252467179282;5800.7679692812235;5818.505427038707;5334.146536554133;5343.19852022192;5345.251664766897;5345.625806193529;5349.465053494659;5649.209110724501;5651.519620832193;5839.824805255849;5840.766369345179;5912.881742365129;5547.916781248285;5600.467955544217;5605.5515558755205;5614.129809879475;5675.460831221028;5619.522340601095;5685.20407142992;5687.914681279784;5702.48677619008;5706;5495.422980587061;5689.374168841424;5693.60144789869;5701.116326969326;5701.600887937804;5502.862428439321;5509.035918714209;5561.332640083216;5581.2186293918885;5673.290838645169;4346.47842258931;4350.195213885378;4350.390945267665;4350.434443522322;4407.845725399659;3869;3887.8056097195026;3888.105735680809;3920.5295364556437;3929;5119.718415487109;5155.304033955443;5253.23828044939;5260.552853007461;5361.919571206435;5696.572757043238;5696.857578560486;5702.7433765480655;5730.77076916927;5767.480926716616;5338.786448542015;5375.731213439312;5385.784568617211;5386.218998245233;5388.865278368072;4859.538343857328;4868.513148685174;4902.803887844446;4953.8018479261145;5007.273945277914;5858.53131749459;5866.765329386777;5870.471657550841;5877.73550190251;5878.2358293421585;5664.773409063591;5671.574631902541;5678.488935995781;5678.801241956512;5686.147077938343;5828.154917537018;5836.241288632451;5839.737211825393;5846.590738648254;5850;5532.921080389447;5576.776928922797;5590.496855282881;5590.664560126405;5601.215829783752;5649.887002260014;5660.235868157911;5689.118186681071;5718.656880587178;5720.799772008127;5436.401995780382;5458.808941687181;5540.252065971204;5544.445555444585;5553.139263414178;5479.178123281539;5479.589030822784;5481.232627432242;5485.862853428536;5489.862753431196;5303.681779093266;5413.593980451403;5416.350037995466;5421.890884445969;5484.670919744828;5280;5422.349318081855;5453.263809385682;5498.037843377473;5570.58220633446;5382.569394448513;5393.164059570773;5397.649152805055;5400.729963501888;5401.081816091352 06:59:49 INFO - PROCESS | 12239 | |1;ext.html;593.0629605223747;603.8218725475983;611.8256296955368;628.183361629881;639.4213237020496;31337.843310783443;34605.480917786255;34729.479057814155;34757.65242347576;34811.65188348116;41840.37239441411;43099;43174.35238471426;47252.29121563171;47310.29034564476;8677.73966780996;8809.867851982226;8813.38306318558;8815.867761983543;8816.294696424271;8044.6782128714785;8703.869441958375;8784.868226976601;8789;8795.032546419889;8001.199880011981;10350.741231469197;10610.522526486287;10678.733031674232;11006.559737610485;10605.310654807452;10659;10660.680179594625;10663.466826658714;10665.786684266348;10403.531841067095;10464.215183861164;10464.52909619069;10466.476676166161;10468.68593942183;40624.59375406238;40849.59150408488;40909.38635920463;40924.79537602323;40944.181116377804;3558.982205088958;3918.5493668228232;3958.9010274743073;3968.861089861841;4025.838966441339;30576.388472230432;30845.228869278224;30957.22606934822;30993.535096973563;31090.689093109006;4137.97931010346;4296.355546667994;4305.827766889321;4458.242098843204;4512.097580483916;220704.8964755182;221224;221817;222393;222914;54558.45441545613;54595.45404545904;54879;54912.725436372566;54917.45082549203;56855.43144568584;57260.14109788356;57262.42737572571;57325.39480196177;57392;15655.530334089995;15850.920745396199;15888.60278493047;15900.443484477988;15910.363585456684;12156.088293378032;12234;12238.510459581696;12239.632811015506;12247.632571022883;14037.78943315851;14281.357338919774;14620.268986550629;15201.163935983406;15715.607109822346;5851.561132915049;5898.616589921728;5928.970355148283;5930.8813823723285;5931.169636250928;935.611721135721;958.1281034258791;966.468442356701;971.6162115964296;1074.8656417947757;30351.392972140435;30826.53760193599;31372.215694607818;31415.21461963469;31453.808727911037;1068.6847380022912;1088.9237753357188;1089.7874914391618;1109.3842917181041;1115.9330440173615;16001.839981600035;16341.673166536602;16404.425845095364;16405;16683.165841707865;482616;485066;485544;486492;487326;48066.27900581494;49116;52398.47601524012;53486.465135348146;53821;1273.7834568123442;1274.1908887856277;1306.3664122900386;1314.8750868667653;1319.881210691004;30051.849740751157;31829;33974.830125849214;34119;34169;52095.218571720696;52455.47544524506;52499.47500524946;52511;52697;403341;403560;403864;403877;405350;25866.870665647304;26312.21063368102;26316;26332;26598.93604255808;44332.778336109404;44796.55203447924;45133.32300015568;45310.77344613256;46390.53609463862;36043;39429;39885;40063.38954072545;41676.37485437781;39492.01269968274;40240;40391;40929.79535102305;41629.37555936724;38831.2233755329;39934.001649958984;40598.39102413525;40599.3910091343;40697.38953915753;30721;35239.647603523634;35731.22135883684;35988;36365.45451818278;27201.19970007498;27697.53101829844;27698.307542311602;27726;27947.580786287814 06:59:49 INFO - PROCESS | 12239 | |2;jquery.html;344.15166614295754;365.0872817955112;367.3406235806727;373.9083924438513;385.545056832937;14595.854041459583;14640.926795366022;17904.82095179048;18992;19114.426567203005;17245;18031.549211269692;18041.368552100677;18152.18315175817;18317.17572709228;5330.813421530247;5453.2092846537225;5482.862928426782;5487.368952570446;5493.258410114643;4001.9399709004388;4213.283741763892;5968;6181.505479561647;6215.595986260879;5476;6201;6208.137558732362;6220.284667263255;6222.657753823538;5687.886242275152;5804.680742559158;5805.390434004411;5807.157962095514;5808.796692115777;5443.1835224716215;5571.247881536022;5611.887762244733;5614.550835933115;5635.3237611486475;12965.611031669065;13176.341182940814;13179.670508237275;13188.538401155913;20458.36333093349;2308.972403272776;2400.84394514356;2433.1848830641757;2757.5725762506845;2847.501687204731;9505.097015783558;10193.949030254875;10201.183905287558;13754;14095.859041409558;1852.6572584071855;1864.5152260412256;1882.0213488985787;1927.4699457649258;1967.7933816949158;81126;81525;81670;82128;83231.58384208161;9879.604815807432;10179.134773544332;10254.205514035015;10300.278980471445;10416.208544069812;14515.709685806436;14525.491607793676;14526.636834079232;14532.200728959797;14544.563663090124;7502.812429689192;7622.923770762332;7674;7837.05955285358;7884.842303153905;7256;7469.813254668677;7642.770716878503;7657.693692252247;7683.039620047494;4291.592298731615;4373.846915357947;4410.713303635318;4489.9775501122285;4525.683202175882;1759.5689056181318;1791.6864548703925;1861.65030352993;1874.9718754218698;1891.640588288221;721.942244620429;806.6007326373483;807.6516427144844;909.1635695160336;976.345848281653;17980.55048623795;18312.908435458005;18918.905405472884;19127;19350.4194874154;931.5901003558413;933.1275257634134;958.0132463562542;972.7665360313474;1002.8896821349646;10107.69676909694;10655.413952232702;10728.249022568501;10779.191560632828;10801.297915635458;454585;458408;462656;472359;474918;82975.585122074;84721;85028;86218.56890715631;88855.55572222227;923.3997901364032;955.8852937647388;962.3696478806393;963.8072385522923;976.7948730766618;15647.452339168074;16016.279267432761;16976.490705278862;17032.211617779954;17284.654306914043;39363.015924602114;40487.797561012005;41810.790946045076;42214.155716884874;44293.33559996667;52989.7350513245;53692.463075368745;55578.16632750431;56337;57211.5697107559;7482.3639990601405;7545.773626791205;7564.886526702214;7614.505057171266;7822.374210063067;10768.3538987661;11012.449377531251;11493.597724079616;11551.42242887869;11677.941610291895;10251.436171010666;10374.900107709731;10484.737881553023;10522.894771052497;10590.099841513249;10007.849882251918;10065.84901226497;10171.847422288523;10370.585176593153;10386.376817390981;10181.236407269336;10376.670231159138;10434;10498.527566259383;10947.34315941046;7509.098908131059;7570.507916985378;7665.923340766744;7781.299683028555;7813.10149332834;10104.898951010397;10221.642242521477;10298.073173414428;10552.525136368728;10649.467526623794 06:59:49 INFO - PROCESS | 12239 | |3;mootools.html;1398.0563119894068;1448.7681970884657;1505.6160679026846;1665.78344815174;1670.6575152093822;7464.8507029859375;7719.884201736979;8197.426180167384;8856.91143088569;8862.689805856797;8267.338612910968;8533.658653653862;8534.74395768128;8702.73891783246;8713.477191368507;2208.61349263879;2242.8766417847014;2243.663450482424;2245.2702871566726;2247.1910112359537;1978.0801927103894;2036.7657719362242;2163.935081947544;2209.2709405896094;2249.257744944173;1954.560223949607;1985.9007049647532;1987.32430973469;2200.570888676709;2246.764089770575;2180.2478145039936;2194.122351059581;2194.462356722597;2203.504211552404;2205.4155648753012;2138.550904310097;2193.5722534105857;2195.3194509701884;2197.4176843136584;2200.185931205448;8648.308135349156;8724.956375218084;8788.692395766182;8800.955995220047;8805.119488051208;4981.9750901245625;5105.923411148836;5109;5114.309568208281;5122.871928201787;3178.634457037447;3286.2277364819247;3369.7641165118453;3376.088456116859;3385.221399078201;1104.8729396119468;1151.2631915574038;1164.569109429508;1179.151011271883;1188.851393575803;12283.078769092353;12290.446929888176;12294.631161065183;12365.629031129081;12371.62885113439;4382.9342559861625;4417.646588272931;4590.586847183769;4606.493285738633;4630.976845115752;10330;10333.173346132291;10335.379877207391;10342.896571034344;10351.741206469898;5145.871353216125;5172.301739265226;5172.508611681884;5173;5179.45615710345;4615.215413379734;4664.55686709762;4678.578927896506;4679.719216847;4728.716277023321;3757.0231739747865;3774.584795672474;3822.3119838429348;3990.3216453203027;4018.0356714388336;1243.5958313548133;1244.875512448748;1246.5823948977013;1249.9062570307262;1260.1493991555662;849.3799526345679;849.4648371526023;875.1729615513278;888.6534251641865;935.6351023100996;5455.345358556999;5564.693941833157;5647.971760141255;5760.42395760429;5818.5054270386645;1015.7359086637528;1030.8402197659375;1031.5770534081141;1041.8437234414746;1063.3832377221347;3695.3533131701843;3757.1734218471893;3842.9231415371537;3853.9421908671393;3906.2968665640456;10661.893381066246;11067.889321106846;11098.500567474483;11129.332240065622;11132;11000.614978475714;11022.669319920415;11043.72390690223;11044.723881902855;11069.889301107047;8110.391720620977;8120.75637730869;8200.548969806718;8244.340452763765;8272.958635206785;5557.472040156179;5662.178984047372;5739.5695322850115;5858.091995740667;5863.765449381977;9790.804183916423;9883.654072107442;9899.65351212704;10031.347962382422;10171.135453486537;9893.802123957337;10003.249756268455;10211.285210035523;10216.897831021595;10294.279400441897;7673.657110005615;7684.6926122954455;7752.7674169775;7797.805054873674;7861.921380786119;8221.958890205511;8393.706220282262;8399.70601028961;8405.285550728257;8405.411621186458;7526.548407095592;7611.885821712568;7703.884441733267;7725.652345644572;7859.371250300077;7296.379807716404;7500.099988001475;7546.849063018819;7595.08858936909;7758.9612051939375;6860.5540639858245;6980.476464265302;6988.825279368057;6988.825279368057;6990.021397004525;4758.857234282977;4819.156647586588;4878.5609295162185;5054.241863720471;5124.871878203075;4768.713877167381;4810.735409552508;4874.512548745097;4922.507749225048;5025.422076461254 06:59:49 INFO - PROCESS | 12239 | |4;prototype.html;292.16149650503047;298.97010298970093;318.28068565043003;324.47435155048817;329.9241174529858;5034.244863270511;5144.893847822717;5245.429739427682;5261.86845328867;5264.473552644734;5026.597872170227;5097.286379906816;5159.613029022819;5165.870853228671;5172.948270517294;4079.7960101994854;4101.036256479725;4107.213362187458;4118.135191609766;4124.154548317602;4024.4566983457153;4097.09863829957;4101.564452441651;4126.401671757609;4147.377893316012;4100.507939047318;4103.897402564945;4107.609777071173;4115.609017143382;4136.172765446922;4095.948518353816;4105.753654780708;4107.445494858216;4110.568390319018;4129.69027322952;3485.7385696072897;3651.7991510466913;3653.963460365389;3654.177809992757;3670.7981061041633;5058.2412638104215;5067.265246539268;5068.2144267638205;5076.720780357079;5102.668326558762;4644.651651126179;4694.600958918496;4706.10583989041;4708.7174769513595;4713.6464765142755;5148.9485105149215;5431.511163995259;5445.9727701361635;5450.482204190595;5453.263809385722;3968.980155099206;3974.7813870237123;3994.4807175067053;3995.6603688686496;4002.8799136025964;5564.777408903639;5593.216949627055;5595.300587426572;5602.915956260659;5614.157876318544;4046.5346485153996;4058.2086493133556;4078.530968938551;4081.775502347399;4091.0590564170407;3916.9020774480864;4035.2938235808615;4124.711270211056;4125.401816736617;4127.133302006552;4429.8703830523655;4526.162659907927;4578.771061446981;4653.697509661861;4685.67200295976;5415.187721841755;5423.294971653739;5431.891362172734;5480.177973303957;5496.148097044965;1353.6006877971022;1376.4975783838827;1380.5375199308144;1408.5422237772764;1428.014669877781;969.1762002298132;1003.1773945364877;1004.6031817432073;1015.4362282085654;1021.4331046269243;5094.414142373675;5259;5260.2109683547005;5272.602760268532;5272.868178295574;5506.752196151183;5527.585431092603;5534.1145416733125;5538.504603757044;5539.8892022160135;5042.647014708935;5268.6575372600655;5272.156454967187;5273.943162166758;5286.171898034533;2576.5233431815172;2586.6590735384507;2595.688517377927;2610.4387556675406;2612.667539554841;4998.475160108209;5004.549590536869;5004.674696144739;5028.698278103326;5074.391073071255;4784.407263436857;4809.326694262806;4977.751112444364;4983.227599722049;5011.423686276052;4929.359183306147;4962.602991760723;5038.254151487448;5075.5178258065425;5082.923756143587;4862.003289325609;4875.465287666413;4907.533784290486;4919.508049195052;4920.6555541111775;4579.816807327803;4815.831445899376;4825.997211157471;4830.927536086891;4840.419149702058;4842.757862106951;4932.497926472245;4968.466082297982;4995.600351971906;5023.120953832991;3601.2077342984503;3602.423612222032;3626.8186590669834;3635.0730563706084;3650.3064417760543;4876.268559715929;5405.324334458192;5421.83734487966;5518.951399234133;5564.443555644403;5466.098093814635;5470.8085217017215;5473.917891231555;5487.533559647474;5490.3411590609385;5237.214417837355;5455.399906010256;5467.261919640798;5484.561235101262;5500.642458240201;5361.257591282771;5380.408155102858;5383.946160538345;5391.32608423947;5406.567474602101;5472.890542189213;5497.670139791625;5512.724363781715;5526.198701188386;5528.622692242375;5221.765020574007;5326.2010698394;5361.410244873139;5367.221752845893;5371.731413429235 06:59:49 INFO - PROCESS | 12239 | |5;yui.html;717.8061923280711;719.8560287942413;742.8180095876507;784.3725019984013;840.1262686805723;14959.476418325361;16051.759223611625;16494.092824894655;16519.834801651963;16594.834051659494;15237.4666886659;15838.524844254662;16039.679206415894;16052.83947160528;16102.275397607107;775.2751177648902;794.8251384695358;796.0725754496005;796.2276591706036;796.4305521552089;658.799066284783;669.0432681265788;775.7013549783318;786.6971216081821;794.8052727081873;829.4815740162398;832.8209434971479;833.5082301442147;834.837206744682;836.2348451167175;824.9216324449168;831.1895901496054;832.4630613254477;833.204289903141;833.3374966901267;506.39485814451746;513.4429144378354;514.2183880501653;514.3339375508725;588.3616276340176;14445.205513696743;14476.42094316226;14937.551873443705;14957.326920288613;14973.850261497355;14021.929890350584;14528.70942581153;14611.308124476962;14680.632984175481;14861;12825.871741282654;12997.545085921947;13245.20528768277;13349.86650133496;13967.441302347894;4499.6850220484575;4608.861734147948;4615.376924115235;4635.860924172281;4799.424069111694;80504.99999999942;81099;81821.59089204576;82237;82621.17378826195;9991.750206244757;10615.31000484966;10696.786064278827;10778.78442431147;10906.291091079054;19876.50308742293;20507.38477845667;20528;20532.58934821295;20564;8007.559584222807;8135.613394699226;8220.58897055145;8266.13205613402;8558.058613552505;4302.806373713191;4380.080183161508;4387.561243875587;4405.31717583775;4425;3172.888948886778;3278.016595021484;3285.507173923883;3285.737141028712;3289.5394644749754;561.7893290016244;567.9659220446787;570.6845720613942;573.6357413042748;589.2163422647868;639.9541053006433;653.5882493813435;665.3479590001825;684.852756657321;695.7877847256635;8762.299016078698;9096.226820720312;9270.53647317645;9975.351602145838;10437.582496700199;703.655208947612;708.1926603671818;727.9563226206444;738.8337624034549;741.388354607451;4147.854825081168;4231.555686652919;4474.053786602838;4514.683972121986;4605.792739326738;108276.91723082669;110139.44930275297;110847;111365;112554;93229.5338523303;93238;93443;95234;96839;86237.56881215554;86529.56735216283;87287.56356218306;88121.55939220391;88316.5584172075;8911.910880891368;8928.598213080557;9003.189712925869;9031.735557022153;9205.953970230106;47679.52320476751;48505.75747121242;48672;49152.26271605857;49998;68721;69207;70520.29479705138;70861.29138708547;70942.29057709356;11120.221584489009;11122.833157502482;11253.437328133397;11339.092872570009;11409;12883.48466061347;13192.538261160813;13213.273269970245;13222.338883056002;13286.269255191057;11972.221805582609;11979.221350612183;12488.388585355338;12689;13082.149660272053;11776.76446471083;11984.820227696417;12486.2247620189;12501.687457813627;12895.290759007969;11538.192326537062;11853;11891.881081189078;12000;12367.505299788269;9408.721176090201;9687.128158465772;10011.849822252527;10120.240981926532;10261.9486902565;9575.569099390405;9618.759531011781;9642.517874106126;10477.214208934209;10686.519106640064 06:59:49 INFO - PROCESS | 12239 | __end_tp_report 06:59:49 INFO - PROCESS | 12239 | __start_cc_report 06:59:49 INFO - PROCESS | 12239 | _x_x_mozilla_cycle_collect,2849 06:59:49 INFO - PROCESS | 12239 | __end_cc_report 06:59:49 INFO - PROCESS | 12239 | __startTimestamp1476799189507__endTimestamp 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | ------- Summary: start ------- 06:59:49 INFO - PROCESS | 12239 | Number of tests: 6 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | [#0] dojo.html Cycles:165 Average:5255.36 Median:5413.59 stddev:591.00 (10.9%) stddev-sans-first:565.64 06:59:49 INFO - PROCESS | 12239 | Values: 2997.2 3031.6 3061.7 3070.4 3080.9 5157.2 5269.8 5273.4 5340.3 5348.8 5266.4 5283.9 5339.3 5369.6 5400.8 4732.3 4734.9 4774.6 4781.2 4791.2 4802.4 4829.2 4868.8 4869.3 4931.0 4734.6 4905.7 4916.6 4921.7 5026.9 4767.6 4788.3 4817.0 4835.0 4839.8 4768.5 4779.8 4786.6 4817.9 4851.9 5254.8 5282.2 5286.8 5298.4 5298.5 5424.2 5461.7 5595.2 5631.3 5658.9 5732.9 5747.1 5750.3 5800.8 5818.5 5334.1 5343.2 5345.3 5345.6 5349.5 5649.2 5651.5 5839.8 5840.8 5912.9 5547.9 5600.5 5605.6 5614.1 5675.5 5619.5 5685.2 5687.9 5702.5 5706.0 5495.4 5689.4 5693.6 5701.1 5701.6 5502.9 5509.0 5561.3 5581.2 5673.3 4346.5 4350.2 4350.4 4350.4 4407.8 3869.0 3887.8 3888.1 3920.5 3929.0 5119.7 5155.3 5253.2 5260.6 5361.9 5696.6 5696.9 5702.7 5730.8 5767.5 5338.8 5375.7 5385.8 5386.2 5388.9 4859.5 4868.5 4902.8 4953.8 5007.3 5858.5 5866.8 5870.5 5877.7 5878.2 5664.8 5671.6 5678.5 5678.8 5686.1 5828.2 5836.2 5839.7 5846.6 5850.0 5532.9 5576.8 5590.5 5590.7 5601.2 5649.9 5660.2 5689.1 5718.7 5720.8 5436.4 5458.8 5540.3 5544.4 5553.1 5479.2 5479.6 5481.2 5485.9 5489.9 5303.7 5413.6 5416.4 5421.9 5484.7 5280.0 5422.3 5453.3 5498.0 5570.6 5382.6 5393.2 5397.6 5400.7 5401.1 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | [#1] ext.html Cycles:180 Average:53368.88 Median:21725.46 stddev:102477.50 (471.7%) stddev-sans-first:102688.37 06:59:49 INFO - PROCESS | 12239 | Values: 593.1 603.8 611.8 628.2 639.4 31337.8 34605.5 34729.5 34757.7 34811.7 41840.4 43099.0 43174.4 47252.3 47310.3 8677.7 8809.9 8813.4 8815.9 8816.3 8044.7 8703.9 8784.9 8789.0 8795.0 8001.2 10350.7 10610.5 10678.7 11006.6 10605.3 10659.0 10660.7 10663.5 10665.8 10403.5 10464.2 10464.5 10466.5 10468.7 40624.6 40849.6 40909.4 40924.8 40944.2 3559.0 3918.5 3958.9 3968.9 4025.8 30576.4 30845.2 30957.2 30993.5 31090.7 4138.0 4296.4 4305.8 4458.2 4512.1 220704.9 221224.0 221817.0 222393.0 222914.0 54558.5 54595.5 54879.0 54912.7 54917.5 56855.4 57260.1 57262.4 57325.4 57392.0 15655.5 15850.9 15888.6 15900.4 15910.4 12156.1 12234.0 12238.5 12239.6 12247.6 14037.8 14281.4 14620.3 15201.2 15715.6 5851.6 5898.6 5929.0 5930.9 5931.2 935.6 958.1 966.5 971.6 1074.9 30351.4 30826.5 31372.2 31415.2 31453.8 1068.7 1088.9 1089.8 1109.4 1115.9 16001.8 16341.7 16404.4 16405.0 16683.2 482616.0 485066.0 485544.0 486492.0 487326.0 48066.3 49116.0 52398.5 53486.5 53821.0 1273.8 1274.2 1306.4 1314.9 1319.9 30051.8 31829.0 33974.8 34119.0 34169.0 52095.2 52455.5 52499.5 52511.0 52697.0 403341.0 403560.0 403864.0 403877.0 405350.0 25866.9 26312.2 26316.0 26332.0 26598.9 44332.8 44796.6 45133.3 45310.8 46390.5 36043.0 39429.0 39885.0 40063.4 41676.4 39492.0 40240.0 40391.0 40929.8 41629.4 38831.2 39934.0 40598.4 40599.4 40697.4 30721.0 35239.6 35731.2 35988.0 36365.5 27201.2 27697.5 27698.3 27726.0 27947.6 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | [#2] jquery.html Cycles:180 Average:27390.13 Median:22244.87 stddev:76722.69 (344.9%) stddev-sans-first:76911.04 06:59:49 INFO - PROCESS | 12239 | Values: 344.2 365.1 367.3 373.9 385.5 14595.9 14640.9 17904.8 18992.0 19114.4 17245.0 18031.5 18041.4 18152.2 18317.2 5330.8 5453.2 5482.9 5487.4 5493.3 4001.9 4213.3 5968.0 6181.5 6215.6 5476.0 6201.0 6208.1 6220.3 6222.7 5687.9 5804.7 5805.4 5807.2 5808.8 5443.2 5571.2 5611.9 5614.6 5635.3 12965.6 13176.3 13179.7 13188.5 20458.4 2309.0 2400.8 2433.2 2757.6 2847.5 9505.1 10193.9 10201.2 13754.0 14095.9 1852.7 1864.5 1882.0 1927.5 1967.8 81126.0 81525.0 81670.0 82128.0 83231.6 9879.6 10179.1 10254.2 10300.3 10416.2 14515.7 14525.5 14526.6 14532.2 14544.6 7502.8 7622.9 7674.0 7837.1 7884.8 7256.0 7469.8 7642.8 7657.7 7683.0 4291.6 4373.8 4410.7 4490.0 4525.7 1759.6 1791.7 1861.7 1875.0 1891.6 721.9 806.6 807.7 909.2 976.3 17980.6 18312.9 18918.9 19127.0 19350.4 931.6 933.1 958.0 972.8 1002.9 10107.7 10655.4 10728.2 10779.2 10801.3 454585.0 458408.0 462656.0 472359.0 474918.0 82975.6 84721.0 85028.0 86218.6 88855.6 923.4 955.9 962.4 963.8 976.8 15647.5 16016.3 16976.5 17032.2 17284.7 39363.0 40487.8 41810.8 42214.2 44293.3 52989.7 53692.5 55578.2 56337.0 57211.6 7482.4 7545.8 7564.9 7614.5 7822.4 10768.4 11012.4 11493.6 11551.4 11677.9 10251.4 10374.9 10484.7 10522.9 10590.1 10007.8 10065.8 10171.8 10370.6 10386.4 10181.2 10376.7 10434.0 10498.5 10947.3 7509.1 7570.5 7665.9 7781.3 7813.1 10104.9 10221.6 10298.1 10552.5 10649.5 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | [#3] mootools.html Cycles:180 Average:5698.48 Median:4623.10 stddev:3312.72 (71.7%) stddev-sans-first:3306.25 06:59:49 INFO - PROCESS | 12239 | Values: 1398.1 1448.8 1505.6 1665.8 1670.7 7464.9 7719.9 8197.4 8856.9 8862.7 8267.3 8533.7 8534.7 8702.7 8713.5 2208.6 2242.9 2243.7 2245.3 2247.2 1978.1 2036.8 2163.9 2209.3 2249.3 1954.6 1985.9 1987.3 2200.6 2246.8 2180.2 2194.1 2194.5 2203.5 2205.4 2138.6 2193.6 2195.3 2197.4 2200.2 8648.3 8725.0 8788.7 8801.0 8805.1 4982.0 5105.9 5109.0 5114.3 5122.9 3178.6 3286.2 3369.8 3376.1 3385.2 1104.9 1151.3 1164.6 1179.2 1188.9 12283.1 12290.4 12294.6 12365.6 12371.6 4382.9 4417.6 4590.6 4606.5 4631.0 10330.0 10333.2 10335.4 10342.9 10351.7 5145.9 5172.3 5172.5 5173.0 5179.5 4615.2 4664.6 4678.6 4679.7 4728.7 3757.0 3774.6 3822.3 3990.3 4018.0 1243.6 1244.9 1246.6 1249.9 1260.1 849.4 849.5 875.2 888.7 935.6 5455.3 5564.7 5648.0 5760.4 5818.5 1015.7 1030.8 1031.6 1041.8 1063.4 3695.4 3757.2 3842.9 3853.9 3906.3 10661.9 11067.9 11098.5 11129.3 11132.0 11000.6 11022.7 11043.7 11044.7 11069.9 8110.4 8120.8 8200.5 8244.3 8273.0 5557.5 5662.2 5739.6 5858.1 5863.8 9790.8 9883.7 9899.7 10031.3 10171.1 9893.8 10003.2 10211.3 10216.9 10294.3 7673.7 7684.7 7752.8 7797.8 7861.9 8222.0 8393.7 8399.7 8405.3 8405.4 7526.5 7611.9 7703.9 7725.7 7859.4 7296.4 7500.1 7546.8 7595.1 7759.0 6860.6 6980.5 6988.8 6988.8 6990.0 4758.9 4819.2 4878.6 5054.2 5124.9 4768.7 4810.7 4874.5 4922.5 5025.4 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | [#4] prototype.html Cycles:180 Average:4451.24 Median:4965.53 stddev:1276.97 (25.7%) stddev-sans-first:1241.81 06:59:49 INFO - PROCESS | 12239 | Values: 292.2 299.0 318.3 324.5 329.9 5034.2 5144.9 5245.4 5261.9 5264.5 5026.6 5097.3 5159.6 5165.9 5172.9 4079.8 4101.0 4107.2 4118.1 4124.2 4024.5 4097.1 4101.6 4126.4 4147.4 4100.5 4103.9 4107.6 4115.6 4136.2 4095.9 4105.8 4107.4 4110.6 4129.7 3485.7 3651.8 3654.0 3654.2 3670.8 5058.2 5067.3 5068.2 5076.7 5102.7 4644.7 4694.6 4706.1 4708.7 4713.6 5148.9 5431.5 5446.0 5450.5 5453.3 3969.0 3974.8 3994.5 3995.7 4002.9 5564.8 5593.2 5595.3 5602.9 5614.2 4046.5 4058.2 4078.5 4081.8 4091.1 3916.9 4035.3 4124.7 4125.4 4127.1 4429.9 4526.2 4578.8 4653.7 4685.7 5415.2 5423.3 5431.9 5480.2 5496.1 1353.6 1376.5 1380.5 1408.5 1428.0 969.2 1003.2 1004.6 1015.4 1021.4 5094.4 5259.0 5260.2 5272.6 5272.9 5506.8 5527.6 5534.1 5538.5 5539.9 5042.6 5268.7 5272.2 5273.9 5286.2 2576.5 2586.7 2595.7 2610.4 2612.7 4998.5 5004.5 5004.7 5028.7 5074.4 4784.4 4809.3 4977.8 4983.2 5011.4 4929.4 4962.6 5038.3 5075.5 5082.9 4862.0 4875.5 4907.5 4919.5 4920.7 4579.8 4815.8 4826.0 4830.9 4840.4 4842.8 4932.5 4968.5 4995.6 5023.1 3601.2 3602.4 3626.8 3635.1 3650.3 4876.3 5405.3 5421.8 5519.0 5564.4 5466.1 5470.8 5473.9 5487.5 5490.3 5237.2 5455.4 5467.3 5484.6 5500.6 5361.3 5380.4 5383.9 5391.3 5406.6 5472.9 5497.7 5512.7 5526.2 5528.6 5221.8 5326.2 5361.4 5367.2 5371.7 06:59:49 INFO - PROCESS | 12239 | 06:59:49 INFO - PROCESS | 12239 | [#5] yui.html Cycles:180 Average:20257.76 Median:26652.59 stddev:29365.32 (110.2%) stddev-sans-first:29411.05 06:59:49 INFO - PROCESS | 12239 | Values: 717.8 719.9 742.8 784.4 840.1 14959.5 16051.8 16494.1 16519.8 16594.8 15237.5 15838.5 16039.7 16052.8 16102.3 775.3 794.8 796.1 796.2 796.4 658.8 669.0 775.7 786.7 794.8 829.5 832.8 833.5 834.8 836.2 824.9 831.2 832.5 833.2 833.3 506.4 513.4 514.2 514.3 588.4 14445.2 14476.4 14937.6 14957.3 14973.9 14021.9 14528.7 14611.3 14680.6 14861.0 12825.9 12997.5 13245.2 13349.9 13967.4 4499.7 4608.9 4615.4 4635.9 4799.4 80505.0 81099.0 81821.6 82237.0 82621.2 9991.8 10615.3 10696.8 10778.8 10906.3 19876.5 20507.4 20528.0 20532.6 20564.0 8007.6 8135.6 8220.6 8266.1 8558.1 4302.8 4380.1 4387.6 4405.3 4425.0 3172.9 3278.0 3285.5 3285.7 3289.5 561.8 568.0 570.7 573.6 589.2 640.0 653.6 665.3 684.9 695.8 8762.3 9096.2 9270.5 9975.4 10437.6 703.7 708.2 728.0 738.8 741.4 4147.9 4231.6 4474.1 4514.7 4605.8 108276.9 110139.4 110847.0 111365.0 112554.0 93229.5 93238.0 93443.0 95234.0 96839.0 86237.6 86529.6 87287.6 88121.6 88316.6 8911.9 8928.6 9003.2 9031.7 9206.0 47679.5 48505.8 48672.0 49152.3 49998.0 68721.0 69207.0 70520.3 70861.3 70942.3 11120.2 11122.8 11253.4 11339.1 11409.0 12883.5 13192.5 13213.3 13222.3 13286.3 11972.2 11979.2 12488.4 12689.0 13082.1 11776.8 11984.8 12486.2 12501.7 12895.3 11538.2 11853.0 11891.9 12000.0 12367.5 9408.7 9687.1 10011.8 10120.2 10261.9 9575.6 9618.8 9642.5 10477.2 10686.5 06:59:49 INFO - PROCESS | 12239 | -------- Summary: end -------- 06:59:49 INFO - PROCESS | 12239 | 06:59:50 INFO - TEST-INFO | 12239: exit 0 06:59:50 INFO - TEST-OK | dromaeo_css | took 1118957ms 06:59:50 INFO - TEST-START | kraken 06:59:50 INFO - operating with platform_type : linux_ 06:59:50 INFO - Initialising browser for kraken test... 06:59:50 INFO - TEST-INFO | started process 12546 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCEpK9u/profile http://localhost:60598/getInfo.html) 06:59:54 INFO - PROCESS | 12546 | __metrics Screen width/height:1600/1200 06:59:54 INFO - PROCESS | 12546 | colorDepth:24 06:59:54 INFO - PROCESS | 12546 | Browser inner width/height: 1024/697 06:59:54 INFO - PROCESS | 12546 | __metrics 06:59:55 INFO - PROCESS | 12546 | 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" 06:59:55 INFO - PROCESS | 12546 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:59:55 INFO - PROCESS | 12546 | console.error: 06:59:55 INFO - PROCESS | 12546 | Message: Error: Connection closed before committing the transaction. 06:59:55 INFO - PROCESS | 12546 | Stack: 06:59:55 INFO - PROCESS | 12546 | ConnectionData.prototype<.executeTransaction/promise resource://gre/modules/Promise-backend.js:937:23 06:59:55 INFO - PROCESS | 12546 | this.PromiseWalker.walkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:816:7 06:59:55 INFO - PROCESS | 12546 | Promise*this.PromiseWalker.scheduleWalkerLoop@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:747:11 06:59:55 INFO - PROCESS | 12546 | this.PromiseWalker.schedulePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:779:7 06:59:55 INFO - PROCESS | 12546 | this.PromiseWalker.completePromise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:714:7 06:59:55 INFO - PROCESS | 12546 | TaskImpl_run@resource://gre/modules/Task.jsm:327:15 06:59:55 INFO - PROCESS | 12546 | Spinner.prototype.observe@resource://gre/modules/AsyncShutdown.jsm:551:9 06:59:55 INFO - PROCESS | 12546 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker/<@resource://gre/components/nsAsyncShutdown.js:157:15 06:59:55 INFO - PROCESS | 12546 | Promise@resource://gre/modules/Promise.jsm -> resource://gre/modules/Promise-backend.js:388:5 06:59:55 INFO - PROCESS | 12546 | nsAsyncShutdownClient.prototype.addBlocker/moduleBlocker@resource://gre/components/nsAsyncShutdown.js:153:29 06:59:55 INFO - PROCESS | 12546 | Barrier/this.client.addBlocker/promise13.1% 07:00:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 07:00:53 INFO - TinderboxPrint: I/O write bytes / time
393,392,128 / 374,672 07:00:53 INFO - TinderboxPrint: CPU idle
8,310.6 (86.9%) 07:00:53 INFO - TinderboxPrint: CPU user
1,204.9 (12.6%) 07:00:53 INFO - TinderboxPrint: Swap in / out
0 / 0 07:00:53 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 105758720; Read time: 0; Write time: 118792 07:00:53 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 287633408; Read time: 0; Write time: 255880 07:00:53 INFO - Running post-run listener: _upload_blobber_files 07:00:53 INFO - Blob upload gear active. 07:00:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:00:53 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 07:00:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:00:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:00:54 INFO - (blobuploader) - INFO - Open directory for files ... 07:00:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:00:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:00:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:00:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:00:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:00:58 INFO - (blobuploader) - INFO - Done attempting. 07:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:00:59 INFO - (blobuploader) - INFO - Done attempting. 07:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:00:59 INFO - (blobuploader) - INFO - Done attempting. 07:00:59 INFO - (blobuploader) - INFO - Iteration through files over. 07:00:59 INFO - Return code: 0 07:00:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:00:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:00:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e197bf4451eb02c8e8ff07946f6ceffaec50890050d2c07b9a08bd910e339fcddaa33e7a1ae9df8fccf045c9bbccd8d63ef204a358164770807e830b3177345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cd8ee4b56dc0a6363d6d95b89b80e3013fb6516f7fc41716fc5fb6a7408d78a507c7ced87ac1f1e7b5bf01f80c497a37919ef9bc3173e2e667ad2e943a6068d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d92ee461d51b5d6d5816d57bdeffce20ab49b0b340efeba60dcf0a02d558adb23cc67aa91a66db50939ca06f20607c42908aba2f5cfd59f7caf86a409aa16b45"} 07:00:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:00:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:00:59 INFO - Contents: 07:00:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e197bf4451eb02c8e8ff07946f6ceffaec50890050d2c07b9a08bd910e339fcddaa33e7a1ae9df8fccf045c9bbccd8d63ef204a358164770807e830b3177345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cd8ee4b56dc0a6363d6d95b89b80e3013fb6516f7fc41716fc5fb6a7408d78a507c7ced87ac1f1e7b5bf01f80c497a37919ef9bc3173e2e667ad2e943a6068d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d92ee461d51b5d6d5816d57bdeffce20ab49b0b340efeba60dcf0a02d558adb23cc67aa91a66db50939ca06f20607c42908aba2f5cfd59f7caf86a409aa16b45"} 07:00:59 INFO - Running post-run listener: copy_logs_to_upload_dir 07:00:59 INFO - Copying logs to upload dir... 07:00:59 INFO - mkdir: /builds/slave/test/build/upload/logs 07:00:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.841945 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-10-18 07:00:59.284707) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 07:00:59.288452) ========= 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=af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e197bf4451eb02c8e8ff07946f6ceffaec50890050d2c07b9a08bd910e339fcddaa33e7a1ae9df8fccf045c9bbccd8d63ef204a358164770807e830b3177345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cd8ee4b56dc0a6363d6d95b89b80e3013fb6516f7fc41716fc5fb6a7408d78a507c7ced87ac1f1e7b5bf01f80c497a37919ef9bc3173e2e667ad2e943a6068d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d92ee461d51b5d6d5816d57bdeffce20ab49b0b340efeba60dcf0a02d558adb23cc67aa91a66db50939ca06f20607c42908aba2f5cfd59f7caf86a409aa16b45"} build_url:https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://archive.mozilla.org/pub/firefox/tinderbox-builds/fx-team-linux64-pgo/1476786616/firefox-52.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e197bf4451eb02c8e8ff07946f6ceffaec50890050d2c07b9a08bd910e339fcddaa33e7a1ae9df8fccf045c9bbccd8d63ef204a358164770807e830b3177345", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3cd8ee4b56dc0a6363d6d95b89b80e3013fb6516f7fc41716fc5fb6a7408d78a507c7ced87ac1f1e7b5bf01f80c497a37919ef9bc3173e2e667ad2e943a6068d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d92ee461d51b5d6d5816d57bdeffce20ab49b0b340efeba60dcf0a02d558adb23cc67aa91a66db50939ca06f20607c42908aba2f5cfd59f7caf86a409aa16b45"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 07:00:59.313575) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 07:00:59.313920) ========= 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=af724c981e2a0f424dbabf99000001e7-1476798017.607950-559486160 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 07:00:59.396293) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 07:00:59.396600) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 07:00:59.396891) ========= ========= Total master_lag: 0.16 =========