builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-052 starttime: 1486048349.9 results: retry (5) revision: d1569ed822af8f33141533522e7ea3b3ab6f2eea ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:29.905137) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:29.905572) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:29.905847) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:29.921898) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:29.922165) ========= 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=826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-02 07:12:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.9M=0s 2017-02-02 07:12:30 (42.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118743 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.054049) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.054353) ========= 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=826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019598 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.103652) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.103972) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d1569ed822af8f33141533522e7ea3b3ab6f2eea --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d1569ed822af8f33141533522e7ea3b3ab6f2eea --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=826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-02 07:12:30,672 truncating revision to first 12 chars 2017-02-02 07:12:30,672 Setting DEBUG logging. 2017-02-02 07:12:30,672 attempt 1/10 2017-02-02 07:12:30,673 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d1569ed822af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-02 07:12:30,984 unpacking tar archive at: mozilla-aurora-d1569ed822af/testing/mozharness/ program finished with exit code 0 elapsedTime=0.631345 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.747882) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.748208) ========= script_repo_revision: d1569ed822af8f33141533522e7ea3b3ab6f2eea ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.748555) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.748814) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-02 07:12:30.755986) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 39 secs) (at 2017-02-02 07:12:30.756249) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora-Non-PGO'] 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=826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:12:31 INFO - MultiFileLogger online at 20170202 07:12:31 in /builds/slave/test 07:12:31 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora-Non-PGO 07:12:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:12:31 INFO - {'append_to_log': False, 07:12:31 INFO - 'base_work_dir': '/builds/slave/test', 07:12:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora-Non-PGO', 07:12:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:12:31 INFO - 'branch': 'Mozilla-Aurora-Non-PGO', 07:12:31 INFO - 'buildbot_json_path': 'buildprops.json', 07:12:31 INFO - 'config_files': ('talos/linux_config.py',), 07:12:31 INFO - 'default_actions': ('clobber', 07:12:31 INFO - 'read-buildbot-config', 07:12:31 INFO - 'download-and-extract', 07:12:31 INFO - 'populate-webroot', 07:12:31 INFO - 'create-virtualenv', 07:12:31 INFO - 'install', 07:12:31 INFO - 'run-tests'), 07:12:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:12:31 INFO - 'download_minidump_stackwalk': True, 07:12:31 INFO - 'download_symbols': 'ondemand', 07:12:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:12:31 INFO - 'tooltool.py': '/tools/tooltool.py', 07:12:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:12:31 INFO - '/tools/misc-python/virtualenv.py')}, 07:12:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:12:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:12:31 INFO - 'installer_path': 'installer.exe', 07:12:31 INFO - 'log_level': 'info', 07:12:31 INFO - 'log_name': 'talos', 07:12:31 INFO - 'log_to_console': True, 07:12:31 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:12:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:12:31 INFO - 'opt_config_files': (), 07:12:31 INFO - 'pip_index': False, 07:12:31 INFO - 'sps_profile': False, 07:12:31 INFO - 'sps_profile_interval': 0, 07:12:31 INFO - 'suite': 'dromaeojs', 07:12:31 INFO - 'system_bits': '32', 07:12:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:12:31 INFO - 'title': 'talos-linux64-ix-052', 07:12:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:12:31 INFO - 'use_talos_json': True, 07:12:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:12:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:12:31 INFO - 'work_dir': 'build'} 07:12:31 INFO - [mozharness: 2017-02-02 15:12:31.416561Z] Running clobber step. 07:12:31 INFO - Running pre-action listener: _resource_record_pre_action 07:12:31 INFO - Running main action method: clobber 07:12:31 INFO - rmtree: /builds/slave/test/build 07:12:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:12:31 INFO - Running post-action listener: _resource_record_post_action 07:12:31 INFO - [mozharness: 2017-02-02 15:12:31.917221Z] Finished clobber step (success) 07:12:31 INFO - [mozharness: 2017-02-02 15:12:31.917323Z] Running read-buildbot-config step. 07:12:31 INFO - Running pre-action listener: _resource_record_pre_action 07:12:31 INFO - Running main action method: read_buildbot_config 07:12:31 INFO - Using buildbot properties: 07:12:31 INFO - { 07:12:31 INFO - "project": "", 07:12:31 INFO - "product": "firefox", 07:12:31 INFO - "who": "bkelly@mozilla.com", 07:12:31 INFO - "installer_path": "public/build/target.tar.bz2", 07:12:31 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:12:31 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-aurora/", 07:12:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora talos dromaeojs", 07:12:31 INFO - "stage_platform": "linux64", 07:12:31 INFO - "basedir": "/builds/slave/test", 07:12:31 INFO - "buildnumber": 13, 07:12:31 INFO - "platform": "ubuntu64_hw", 07:12:31 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:12:31 INFO - "slavebuilddir": "test", 07:12:31 INFO - "taskId": "GAUSw4_6TNCfh5668nGmsw", 07:12:31 INFO - "branch": "mozilla-aurora", 07:12:31 INFO - "script_repo_revision": "production", 07:12:31 INFO - "revision": "d1569ed822af8f33141533522e7ea3b3ab6f2eea", 07:12:31 INFO - "slavename": "talos-linux64-ix-052", 07:12:31 INFO - "repo_path": "releases/mozilla-aurora" 07:12:31 INFO - } 07:12:31 INFO - Finding installer, test and symbols from parent task. 07:12:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GAUSw4_6TNCfh5668nGmsw'}, attempt #1 07:12:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GAUSw4_6TNCfh5668nGmsw'}, attempt #1 07:12:32 INFO - Task dependencies: fSSRwJDqRiirXOll4z_1Sw 07:12:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw'}, attempt #1 07:12:33 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2 07:12:33 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/test_packages.json 07:12:33 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.crashreporter-symbols.zip 07:12:33 INFO - Running post-action listener: _resource_record_post_action 07:12:33 INFO - [mozharness: 2017-02-02 15:12:33.326879Z] Finished read-buildbot-config step (success) 07:12:33 INFO - [mozharness: 2017-02-02 15:12:33.327098Z] Running download-and-extract step. 07:12:33 INFO - Running pre-action listener: _resource_record_pre_action 07:12:33 INFO - Running main action method: download_and_extract 07:12:33 INFO - mkdir: /builds/slave/test/build/tests 07:12:33 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')]} 07:12:33 INFO - trying https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.test_packages.json 07:12:33 INFO - Downloading https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 07:12:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 07:12:33 INFO - Downloaded 896 bytes. 07:12:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 07:12:33 INFO - Using the following test package requirements: 07:12:33 INFO - {u'common': [u'target.common.tests.zip'], 07:12:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 07:12:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 07:12:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 07:12:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 07:12:33 INFO - u'mozbase': [u'target.common.tests.zip'], 07:12:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 07:12:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 07:12:33 INFO - u'web-platform': [u'target.common.tests.zip', 07:12:33 INFO - u'target.web-platform.tests.zip'], 07:12:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 07:12:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 07:12:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.common.tests.zip 07:12:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:12:33 INFO - Fetch https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.common.tests.zip into memory 07:12:34 INFO - Expected file size: 21860828 07:12:34 INFO - Obtained file size: 21860828 07:12:38 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 07:12:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.common.tests.zip 07:12:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.common.tests.zip'}, attempt #1 07:12:38 INFO - Fetch https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.common.tests.zip into memory 07:12:39 INFO - Expected file size: 21860828 07:12:39 INFO - Obtained file size: 21860828 07:12:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.talos.tests.zip 07:12:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 07:12:44 INFO - Fetch https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.talos.tests.zip into memory 07:12:44 INFO - Expected file size: 14403485 07:12:44 INFO - Obtained file size: 14403485 07:12:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:45 INFO - trying https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2 07:12:45 INFO - Downloading https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 07:12:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 07:12:46 INFO - Downloaded 66063532 bytes. 07:12:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2 07:12:46 INFO - mkdir: /builds/slave/test/properties 07:12:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:12:46 INFO - Writing to file /builds/slave/test/properties/build_url 07:12:46 INFO - Contents: 07:12:46 INFO - build_url:https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2 07:12:46 INFO - Running post-action listener: _resource_record_post_action 07:12:46 INFO - Running post-action listener: set_extra_try_arguments 07:12:46 INFO - [mozharness: 2017-02-02 15:12:46.284225Z] Finished download-and-extract step (success) 07:12:46 INFO - [mozharness: 2017-02-02 15:12:46.284321Z] Running populate-webroot step. 07:12:46 INFO - Running pre-action listener: _resource_record_pre_action 07:12:46 INFO - Running main action method: populate_webroot 07:12:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:12:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:12:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:12:46 INFO - '--tppagecycles', 07:12:46 INFO - '7'], 07:12:46 INFO - 'tests': ['tsvgm']}}, 07:12:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:12:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:12:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:12:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:12:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:12:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['damp', 'tps']}, 07:12:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'tests': ['damp', 'tps']}, 07:12:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['dromaeo_dom']}, 07:12:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:12:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['basic_compositor_video']}, 07:12:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:12:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['a11yr', 07:12:46 INFO - 'ts_paint', 07:12:46 INFO - 'tpaint', 07:12:46 INFO - 'sessionrestore', 07:12:46 INFO - 'sessionrestore_no_auto_restore', 07:12:46 INFO - 'tabpaint']}, 07:12:46 INFO - 'other-e10s': {'tests': ['a11yr', 07:12:46 INFO - 'ts_paint', 07:12:46 INFO - 'tpaint', 07:12:46 INFO - 'sessionrestore', 07:12:46 INFO - 'sessionrestore_no_auto_restore', 07:12:46 INFO - 'tabpaint']}, 07:12:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:12:46 INFO - 'ts_paint', 07:12:46 INFO - 'tpaint', 07:12:46 INFO - 'sessionrestore', 07:12:46 INFO - 'sessionrestore_no_auto_restore', 07:12:46 INFO - 'tabpaint']}, 07:12:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:12:46 INFO - 'ts_paint', 07:12:46 INFO - 'tpaint', 07:12:46 INFO - 'sessionrestore', 07:12:46 INFO - 'sessionrestore_no_auto_restore', 07:12:46 INFO - 'tabpaint']}, 07:12:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['a11yr', 07:12:46 INFO - 'ts_paint', 07:12:46 INFO - 'tpaint', 07:12:46 INFO - 'sessionrestore', 07:12:46 INFO - 'sessionrestore_no_auto_restore', 07:12:46 INFO - 'tabpaint']}, 07:12:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['a11yr', 07:12:46 INFO - 'ts_paint', 07:12:46 INFO - 'tpaint', 07:12:46 INFO - 'sessionrestore', 07:12:46 INFO - 'sessionrestore_no_auto_restore', 07:12:46 INFO - 'tabpaint']}, 07:12:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['tsvgx', 07:12:46 INFO - 'tsvgr_opacity', 07:12:46 INFO - 'tart', 07:12:46 INFO - 'tscrollx', 07:12:46 INFO - 'cart', 07:12:46 INFO - 'tsvg_static']}, 07:12:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:12:46 INFO - 'tsvgr_opacity', 07:12:46 INFO - 'tart', 07:12:46 INFO - 'tscrollx', 07:12:46 INFO - 'cart', 07:12:46 INFO - 'tsvg_static']}, 07:12:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'talos_options': ['--disable-e10s'], 07:12:46 INFO - 'tests': ['tp5o']}, 07:12:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:12:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'talos_options': ['--disable-e10s', 07:12:46 INFO - '--xperf_path', 07:12:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:46 INFO - 'tests': ['tp5n']}, 07:12:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:12:46 INFO - 'talos_options': ['--xperf_path', 07:12:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:12:46 INFO - 'tests': ['tp5n']}}, 07:12:46 INFO - 'talos.zip': {'path': '', 07:12:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:12:46 INFO - Running post-action listener: _resource_record_post_action 07:12:46 INFO - [mozharness: 2017-02-02 15:12:46.297540Z] Finished populate-webroot step (success) 07:12:46 INFO - [mozharness: 2017-02-02 15:12:46.297629Z] Running create-virtualenv step. 07:12:46 INFO - Running pre-action listener: _resource_record_pre_action 07:12:46 INFO - Running main action method: create_virtualenv 07:12:46 INFO - Creating virtualenv /builds/slave/test/build/venv 07:12:46 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:12:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:12:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:12:46 INFO - Using real prefix '/usr' 07:12:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:12:47 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:48 INFO - Installing pip.................done. 07:12:48 INFO - Return code: 0 07:12:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:12:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:12:49 INFO - Reading from file tmpfile_stdout 07:12:49 INFO - Output received: 07:12:49 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:12:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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')]} 07:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:12:49 INFO - Using env: {'DISPLAY': ':0', 07:12:49 INFO - 'HOME': '/home/cltbld', 07:12:49 INFO - 'LANG': 'en_US.UTF-8', 07:12:49 INFO - 'LANGUAGE': 'en_US:en', 07:12:49 INFO - 'LOGNAME': 'cltbld', 07:12:49 INFO - 'MAIL': '/var/mail/cltbld', 07:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:49 INFO - 'MOZ_NO_REMOTE': '1', 07:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:49 INFO - 'NO_EM_RESTART': '1', 07:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:49 INFO - 'PWD': '/builds/slave/test', 07:12:49 INFO - 'SHELL': '/bin/bash', 07:12:49 INFO - 'SHLVL': '1', 07:12:49 INFO - 'TERM': 'linux', 07:12:49 INFO - 'TMOUT': '86400', 07:12:49 INFO - 'USER': 'cltbld', 07:12:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:49 INFO - '_': '/tools/buildbot/bin/python'} 07:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:12:49 INFO - Cleaning up... 07:12:49 INFO - Return code: 0 07:12:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12: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')]} 07:12:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:12:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:12:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:12:49 INFO - Using env: {'DISPLAY': ':0', 07:12:49 INFO - 'HOME': '/home/cltbld', 07:12:49 INFO - 'LANG': 'en_US.UTF-8', 07:12:49 INFO - 'LANGUAGE': 'en_US:en', 07:12:49 INFO - 'LOGNAME': 'cltbld', 07:12:49 INFO - 'MAIL': '/var/mail/cltbld', 07:12:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:49 INFO - 'MOZ_NO_REMOTE': '1', 07:12:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:49 INFO - 'NO_EM_RESTART': '1', 07:12:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:49 INFO - 'PWD': '/builds/slave/test', 07:12:49 INFO - 'SHELL': '/bin/bash', 07:12:49 INFO - 'SHLVL': '1', 07:12:49 INFO - 'TERM': 'linux', 07:12:49 INFO - 'TMOUT': '86400', 07:12:49 INFO - 'USER': 'cltbld', 07:12:49 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:12:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:49 INFO - '_': '/tools/buildbot/bin/python'} 07:12:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:49 INFO - Downloading/unpacking psutil>=3.1.1 07:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:51 INFO - Installing collected packages: psutil 07:12:51 INFO - Running setup.py install for psutil 07:12:51 INFO - building 'psutil._psutil_linux' extension 07:12:51 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 07:12:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 07:12:52 INFO - building 'psutil._psutil_posix' extension 07:12:52 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 07:12:52 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 07:12:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:52 INFO - Successfully installed psutil 07:12:52 INFO - Cleaning up... 07:12:52 INFO - Return code: 0 07:12:52 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:12:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:12:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:12:52 INFO - Using env: {'DISPLAY': ':0', 07:12:52 INFO - 'HOME': '/home/cltbld', 07:12:52 INFO - 'LANG': 'en_US.UTF-8', 07:12:52 INFO - 'LANGUAGE': 'en_US:en', 07:12:52 INFO - 'LOGNAME': 'cltbld', 07:12:52 INFO - 'MAIL': '/var/mail/cltbld', 07:12:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:52 INFO - 'MOZ_NO_REMOTE': '1', 07:12:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:52 INFO - 'NO_EM_RESTART': '1', 07:12:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:52 INFO - 'PWD': '/builds/slave/test', 07:12:52 INFO - 'SHELL': '/bin/bash', 07:12:52 INFO - 'SHLVL': '1', 07:12:52 INFO - 'TERM': 'linux', 07:12:52 INFO - 'TMOUT': '86400', 07:12:52 INFO - 'USER': 'cltbld', 07:12:52 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:12:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:52 INFO - '_': '/tools/buildbot/bin/python'} 07:12:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:52 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:12:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:12:54 INFO - Installing collected packages: mozsystemmonitor 07:12:54 INFO - Running setup.py install for mozsystemmonitor 07:12:54 INFO - Successfully installed mozsystemmonitor 07:12:54 INFO - Cleaning up... 07:12:54 INFO - Return code: 0 07:12:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:12:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:12:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:12:54 INFO - Using env: {'DISPLAY': ':0', 07:12:54 INFO - 'HOME': '/home/cltbld', 07:12:54 INFO - 'LANG': 'en_US.UTF-8', 07:12:54 INFO - 'LANGUAGE': 'en_US:en', 07:12:54 INFO - 'LOGNAME': 'cltbld', 07:12:54 INFO - 'MAIL': '/var/mail/cltbld', 07:12:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:54 INFO - 'MOZ_NO_REMOTE': '1', 07:12:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:54 INFO - 'NO_EM_RESTART': '1', 07:12:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:54 INFO - 'PWD': '/builds/slave/test', 07:12:54 INFO - 'SHELL': '/bin/bash', 07:12:54 INFO - 'SHLVL': '1', 07:12:54 INFO - 'TERM': 'linux', 07:12:54 INFO - 'TMOUT': '86400', 07:12:54 INFO - 'USER': 'cltbld', 07:12:54 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:12:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:54 INFO - '_': '/tools/buildbot/bin/python'} 07:12:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:55 INFO - Downloading/unpacking jsonschema==2.5.1 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 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 07:12:55 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 07:12:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:57 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:57 INFO - warning: no files found matching '*.txt' 07:12:57 INFO - no previously-included directories found matching 'build' 07:12:57 INFO - no previously-included directories found matching 'dist' 07:12:57 INFO - no previously-included directories found matching '.git*' 07:12:57 INFO - Installing collected packages: jsonschema, functools32 07:12:57 INFO - Running setup.py install for functools32 07:12:57 INFO - warning: no files found matching '*.txt' 07:12:57 INFO - no previously-included directories found matching 'build' 07:12:57 INFO - no previously-included directories found matching 'dist' 07:12:57 INFO - no previously-included directories found matching '.git*' 07:12:57 INFO - Successfully installed jsonschema functools32 07:12:57 INFO - Cleaning up... 07:12:57 INFO - Return code: 0 07:12:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:12:57 INFO - Using env: {'DISPLAY': ':0', 07:12:57 INFO - 'HOME': '/home/cltbld', 07:12:57 INFO - 'LANG': 'en_US.UTF-8', 07:12:57 INFO - 'LANGUAGE': 'en_US:en', 07:12:57 INFO - 'LOGNAME': 'cltbld', 07:12:57 INFO - 'MAIL': '/var/mail/cltbld', 07:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:57 INFO - 'MOZ_NO_REMOTE': '1', 07:12:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:57 INFO - 'NO_EM_RESTART': '1', 07:12:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:57 INFO - 'PWD': '/builds/slave/test', 07:12:57 INFO - 'SHELL': '/bin/bash', 07:12:57 INFO - 'SHLVL': '1', 07:12:57 INFO - 'TERM': 'linux', 07:12:57 INFO - 'TMOUT': '86400', 07:12:57 INFO - 'USER': 'cltbld', 07:12:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:57 INFO - '_': '/tools/buildbot/bin/python'} 07:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:57 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:12:57 INFO - Cleaning up... 07:12:57 INFO - Return code: 0 07:12:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:12:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:12:57 INFO - Using env: {'DISPLAY': ':0', 07:12:57 INFO - 'HOME': '/home/cltbld', 07:12:57 INFO - 'LANG': 'en_US.UTF-8', 07:12:57 INFO - 'LANGUAGE': 'en_US:en', 07:12:57 INFO - 'LOGNAME': 'cltbld', 07:12:57 INFO - 'MAIL': '/var/mail/cltbld', 07:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:57 INFO - 'MOZ_NO_REMOTE': '1', 07:12:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:12:57 INFO - 'NO_EM_RESTART': '1', 07:12:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:57 INFO - 'PWD': '/builds/slave/test', 07:12:57 INFO - 'SHELL': '/bin/bash', 07:12:57 INFO - 'SHLVL': '1', 07:12:57 INFO - 'TERM': 'linux', 07:12:57 INFO - 'TMOUT': '86400', 07:12:57 INFO - 'USER': 'cltbld', 07:12:57 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:57 INFO - '_': '/tools/buildbot/bin/python'} 07:12:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:57 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:12:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:59 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:13:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:13:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:13:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:13:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:13:00 INFO - Downloading docopt-0.6.1.tar.gz 07:13:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:13:00 INFO - Installing collected packages: blobuploader, requests, docopt 07:13:00 INFO - Running setup.py install for blobuploader 07:13:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:13:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:13:00 INFO - Running setup.py install for requests 07:13:01 INFO - Running setup.py install for docopt 07:13:01 INFO - Successfully installed blobuploader requests docopt 07:13:01 INFO - Cleaning up... 07:13:01 INFO - Return code: 0 07:13:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:13:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:13:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:13:01 INFO - Using env: {'DISPLAY': ':0', 07:13:01 INFO - 'HOME': '/home/cltbld', 07:13:01 INFO - 'LANG': 'en_US.UTF-8', 07:13:01 INFO - 'LANGUAGE': 'en_US:en', 07:13:01 INFO - 'LOGNAME': 'cltbld', 07:13:01 INFO - 'MAIL': '/var/mail/cltbld', 07:13:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:01 INFO - 'MOZ_NO_REMOTE': '1', 07:13:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:01 INFO - 'NO_EM_RESTART': '1', 07:13:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:01 INFO - 'PWD': '/builds/slave/test', 07:13:01 INFO - 'SHELL': '/bin/bash', 07:13:01 INFO - 'SHLVL': '1', 07:13:01 INFO - 'TERM': 'linux', 07:13:01 INFO - 'TMOUT': '86400', 07:13:01 INFO - 'USER': 'cltbld', 07:13:01 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:13:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:01 INFO - '_': '/tools/buildbot/bin/python'} 07:13:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:13:01 INFO - Running setup.py (path:/tmp/pip-D4uxib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:13:01 INFO - Running setup.py (path:/tmp/pip-MwdA_l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:13:01 INFO - Running setup.py (path:/tmp/pip-5ei0Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:01 INFO - Running setup.py (path:/tmp/pip-GWlQAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:01 INFO - Running setup.py (path:/tmp/pip-QeuGid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:01 INFO - Running setup.py (path:/tmp/pip-b01KZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:02 INFO - Running setup.py (path:/tmp/pip-U4MU5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:02 INFO - Running setup.py (path:/tmp/pip-k9kI91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:02 INFO - Running setup.py (path:/tmp/pip-RKiEZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:02 INFO - Running setup.py (path:/tmp/pip-CHCQYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:02 INFO - Running setup.py (path:/tmp/pip-L56VrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:02 INFO - Running setup.py (path:/tmp/pip-5rKP10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:02 INFO - Running setup.py (path:/tmp/pip-JRP85K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:02 INFO - Running setup.py (path:/tmp/pip-ENTKkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:02 INFO - Running setup.py (path:/tmp/pip-itDmkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:02 INFO - Running setup.py (path:/tmp/pip-WBZ9M6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:02 INFO - Running setup.py (path:/tmp/pip-fYA44O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:13:02 INFO - Running setup.py install for manifestparser 07:13:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:13:02 INFO - Running setup.py install for mozcrash 07:13:03 INFO - Running setup.py install for mozdebug 07:13:03 INFO - Running setup.py install for mozdevice 07:13:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Running setup.py install for mozfile 07:13:03 INFO - Running setup.py install for mozhttpd 07:13:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Running setup.py install for mozinfo 07:13:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Running setup.py install for mozInstall 07:13:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Running setup.py install for mozleak 07:13:03 INFO - Running setup.py install for mozlog 07:13:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:13:03 INFO - Running setup.py install for moznetwork 07:13:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:13:04 INFO - Running setup.py install for mozprocess 07:13:04 INFO - Running setup.py install for mozprofile 07:13:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:13:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:13:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:13:04 INFO - Running setup.py install for mozrunner 07:13:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:13:04 INFO - Running setup.py install for mozscreenshot 07:13:04 INFO - Running setup.py install for moztest 07:13:04 INFO - Running setup.py install for mozversion 07:13:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:13:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:13:04 INFO - Cleaning up... 07:13:04 INFO - Return code: 0 07:13:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:13:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:13:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:13:04 INFO - Using env: {'DISPLAY': ':0', 07:13:04 INFO - 'HOME': '/home/cltbld', 07:13:04 INFO - 'LANG': 'en_US.UTF-8', 07:13:04 INFO - 'LANGUAGE': 'en_US:en', 07:13:04 INFO - 'LOGNAME': 'cltbld', 07:13:04 INFO - 'MAIL': '/var/mail/cltbld', 07:13:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:04 INFO - 'MOZ_NO_REMOTE': '1', 07:13:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:04 INFO - 'NO_EM_RESTART': '1', 07:13:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:04 INFO - 'PWD': '/builds/slave/test', 07:13:04 INFO - 'SHELL': '/bin/bash', 07:13:04 INFO - 'SHLVL': '1', 07:13:04 INFO - 'TERM': 'linux', 07:13:04 INFO - 'TMOUT': '86400', 07:13:04 INFO - 'USER': 'cltbld', 07:13:04 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:13:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:04 INFO - '_': '/tools/buildbot/bin/python'} 07:13:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:13:04 INFO - Running setup.py (path:/tmp/pip-B0ZbRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:13:05 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)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:13:05 INFO - Running setup.py (path:/tmp/pip-H9CIwr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:13:05 INFO - Running setup.py (path:/tmp/pip-7CzkJh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:13:05 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)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:13:05 INFO - Running setup.py (path:/tmp/pip-sHpcGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:13:05 INFO - Running setup.py (path:/tmp/pip-6JTlC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:13:05 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)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:13:05 INFO - Running setup.py (path:/tmp/pip-pPtprG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:13:05 INFO - Running setup.py (path:/tmp/pip-ohoqfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:13:05 INFO - Running setup.py (path:/tmp/pip-zk6ya7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:13:05 INFO - Running setup.py (path:/tmp/pip-PeigHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:13:05 INFO - Running setup.py (path:/tmp/pip-n9yAlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:13:05 INFO - Running setup.py (path:/tmp/pip-d4hMCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:13:05 INFO - Running setup.py (path:/tmp/pip-1VYfMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:13:05 INFO - Running setup.py (path:/tmp/pip-3H322u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:13:05 INFO - Running setup.py (path:/tmp/pip-j7wj7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:13:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:13:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:05 INFO - Running setup.py (path:/tmp/pip-2RYA5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:13:06 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)) 07:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:13:06 INFO - Running setup.py (path:/tmp/pip-xoDhFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 07:13:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:13:06 INFO - Running setup.py (path:/tmp/pip-zvQamO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:13:06 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)) 07:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:13:06 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)) 07:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:13:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:13:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:13:06 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 07:13:06 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 07:13:06 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 07:13:06 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 07:13:06 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 07:13:06 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 07:13:08 INFO - Downloading blessings-1.6.tar.gz 07:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:13:08 INFO - Installing collected packages: blessings 07:13:08 INFO - Running setup.py install for blessings 07:13:08 INFO - Successfully installed blessings 07:13:08 INFO - Cleaning up... 07:13:08 INFO - Return code: 0 07:13:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:13:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:08 INFO - Reading from file tmpfile_stdout 07:13:08 INFO - Current package versions: 07:13:08 INFO - argparse == 1.2.1 07:13:08 INFO - blessings == 1.6 07:13:08 INFO - blobuploader == 1.2.4 07:13:08 INFO - docopt == 0.6.1 07:13:08 INFO - functools32 == 3.2.3-2 07:13:08 INFO - jsonschema == 2.5.1 07:13:08 INFO - manifestparser == 1.1 07:13:08 INFO - mozInstall == 1.12 07:13:08 INFO - mozcrash == 1.0 07:13:08 INFO - mozdebug == 0.1 07:13:08 INFO - mozdevice == 0.49 07:13:08 INFO - mozfile == 1.2 07:13:08 INFO - mozhttpd == 0.7 07:13:08 INFO - mozinfo == 0.9 07:13:08 INFO - mozleak == 0.1 07:13:08 INFO - mozlog == 3.4 07:13:08 INFO - moznetwork == 0.27 07:13:08 INFO - mozprocess == 0.24 07:13:08 INFO - mozprofile == 0.28 07:13:08 INFO - mozrunner == 6.13 07:13:08 INFO - mozscreenshot == 0.1 07:13:08 INFO - mozsystemmonitor == 0.3 07:13:08 INFO - moztest == 0.8 07:13:08 INFO - mozversion == 1.4 07:13:08 INFO - psutil == 3.1.1 07:13:08 INFO - requests == 1.2.3 07:13:08 INFO - wsgiref == 0.1.2 07:13:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:13:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:13:08 INFO - Using env: {'DISPLAY': ':0', 07:13:08 INFO - 'HOME': '/home/cltbld', 07:13:08 INFO - 'LANG': 'en_US.UTF-8', 07:13:08 INFO - 'LANGUAGE': 'en_US:en', 07:13:08 INFO - 'LOGNAME': 'cltbld', 07:13:08 INFO - 'MAIL': '/var/mail/cltbld', 07:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:08 INFO - 'MOZ_NO_REMOTE': '1', 07:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:08 INFO - 'NO_EM_RESTART': '1', 07:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:08 INFO - 'PWD': '/builds/slave/test', 07:13:08 INFO - 'SHELL': '/bin/bash', 07:13:08 INFO - 'SHLVL': '1', 07:13:08 INFO - 'TERM': 'linux', 07:13:08 INFO - 'TMOUT': '86400', 07:13:08 INFO - 'USER': 'cltbld', 07:13:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:08 INFO - '_': '/tools/buildbot/bin/python'} 07:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:13:08 INFO - Cleaning up... 07:13:08 INFO - Return code: 0 07:13:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:13:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xbdd1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdaaa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf4f980>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, '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': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', '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 07:13:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:13:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:13:08 INFO - Using env: {'DISPLAY': ':0', 07:13:08 INFO - 'HOME': '/home/cltbld', 07:13:08 INFO - 'LANG': 'en_US.UTF-8', 07:13:08 INFO - 'LANGUAGE': 'en_US:en', 07:13:08 INFO - 'LOGNAME': 'cltbld', 07:13:08 INFO - 'MAIL': '/var/mail/cltbld', 07:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:08 INFO - 'MOZ_NO_REMOTE': '1', 07:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:08 INFO - 'NO_EM_RESTART': '1', 07:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:08 INFO - 'PWD': '/builds/slave/test', 07:13:08 INFO - 'SHELL': '/bin/bash', 07:13:08 INFO - 'SHLVL': '1', 07:13:08 INFO - 'TERM': 'linux', 07:13:08 INFO - 'TMOUT': '86400', 07:13:08 INFO - 'USER': 'cltbld', 07:13:08 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:08 INFO - '_': '/tools/buildbot/bin/python'} 07:13:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:13:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:13:09 INFO - Cleaning up... 07:13:09 INFO - Return code: 0 07:13:09 INFO - Running post-action listener: _resource_record_post_action 07:13:09 INFO - Running post-action listener: _start_resource_monitoring 07:13:09 INFO - Starting resource monitoring. 07:13:09 INFO - [mozharness: 2017-02-02 15:13:09.136513Z] Finished create-virtualenv step (success) 07:13:09 INFO - [mozharness: 2017-02-02 15:13:09.136975Z] Running install step. 07:13:09 INFO - Running pre-action listener: _resource_record_pre_action 07:13:09 INFO - Running main action method: install 07:13:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:13:09 INFO - Reading from file tmpfile_stdout 07:13:09 INFO - Detecting whether we're running mozinstall >=1.0... 07:13:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:13:09 INFO - Reading from file tmpfile_stdout 07:13:09 INFO - Output received: 07:13:09 INFO - Usage: mozinstall [options] installer 07:13:09 INFO - Options: 07:13:09 INFO - -h, --help show this help message and exit 07:13:09 INFO - -d DEST, --destination=DEST 07:13:09 INFO - Directory to install application into. [default: 07:13:09 INFO - "/builds/slave/test"] 07:13:09 INFO - --app=APP Application being installed. [default: firefox] 07:13:09 INFO - mkdir: /builds/slave/test/build/application 07:13:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:13:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 07:13:25 INFO - Reading from file tmpfile_stdout 07:13:25 INFO - Output received: 07:13:25 INFO - /builds/slave/test/build/application/firefox/firefox 07:13:25 INFO - Running post-action listener: _resource_record_post_action 07:13:25 INFO - [mozharness: 2017-02-02 15:13:25.889951Z] Finished install step (success) 07:13:25 INFO - [mozharness: 2017-02-02 15:13:25.890226Z] Running run-tests step. 07:13:25 INFO - Running pre-action listener: _resource_record_pre_action 07:13:25 INFO - Running main action method: run_tests 07:13:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:13:25 INFO - Python 2.7.3 07:13:25 INFO - Return code: 0 07:13:25 INFO - grabbing minidump binary from tooltool 07:13:25 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')]} 07:13:25 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 0xf4cd00>, '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 0xf4da00>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf4deb0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:13:25 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 07:13:25 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 07:13:25 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 07:13:25 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:13:25 INFO - Return code: 0 07:13:25 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:13:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:13:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:13:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:13:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:13:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 07:13:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 07:13:25 INFO - Using env: {'DISPLAY': ':0', 07:13:25 INFO - 'HOME': '/home/cltbld', 07:13:25 INFO - 'LANG': 'en_US.UTF-8', 07:13:25 INFO - 'LANGUAGE': 'en_US:en', 07:13:25 INFO - 'LOGNAME': 'cltbld', 07:13:25 INFO - 'MAIL': '/var/mail/cltbld', 07:13:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:13:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:13:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:13:25 INFO - 'MOZ_NO_REMOTE': '1', 07:13:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:13:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:13:25 INFO - 'NO_EM_RESTART': '1', 07:13:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:13:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:13:25 INFO - 'PWD': '/builds/slave/test', 07:13:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:13:25 INFO - 'SHELL': '/bin/bash', 07:13:25 INFO - 'SHLVL': '1', 07:13:25 INFO - 'TERM': 'linux', 07:13:25 INFO - 'TMOUT': '86400', 07:13:25 INFO - 'USER': 'cltbld', 07:13:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404', 07:13:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:13:25 INFO - '_': '/tools/buildbot/bin/python'} 07:13:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 07:13:26 INFO - mozversion application_buildid: 20170202141157 07:13:26 INFO - mozversion application_changeset: d1569ed822af8f33141533522e7ea3b3ab6f2eea 07:13:26 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:13:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:13:26 INFO - mozversion application_name: Firefox 07:13:26 INFO - mozversion application_remotingname: firefox-dev 07:13:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:13:26 INFO - mozversion application_vendor: Mozilla 07:13:26 INFO - mozversion application_version: 53.0a2 07:13:26 INFO - mozversion platform_buildid: 20170202141157 07:13:26 INFO - mozversion platform_changeset: d1569ed822af8f33141533522e7ea3b3ab6f2eea 07:13:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:13:26 INFO - mozversion platform_version: 53.0a2 07:13:26 INFO - using testdate: 1486048406 07:13:26 INFO - actual date: 1486048406 07:13:26 INFO - starting webserver on 'localhost:40425' 07:13:26 INFO - SUITE-START | Running 2 tests 07:13:26 INFO - TEST-START | dromaeo_css 07:13:26 INFO - operating with platform_type : linux_ 07:13:26 INFO - Initialising browser for dromaeo_css test... 07:13:26 INFO - TEST-INFO | started process 14992 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBcdZEl/profile http://localhost:40425/getInfo.html) 07:13:29 INFO - PROCESS | 14992 | __metrics Screen width/height:1600/1200 07:13:29 INFO - PROCESS | 14992 | colorDepth:24 07:13:29 INFO - PROCESS | 14992 | Browser inner width/height: 1024/697 07:13:29 INFO - PROCESS | 14992 | __metrics 07:13:30 INFO - PROCESS | 14992 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:13:30 INFO - PROCESS | 14992 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:13:30 INFO - PROCESS | 14992 | console.error: PushService: 07:13:30 INFO - PROCESS | 14992 | stateChangeProcessEnqueue: Error transitioning state 07:13:30 INFO - PROCESS | 14992 | UnknownError 07:13:31 INFO - TEST-INFO | 14992: exit 0 07:13:31 INFO - Browser initialized. 07:13:31 INFO - Running cycle 1/1 for dromaeo_css test... 07:13:31 INFO - TEST-INFO | started process 15083 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpBcdZEl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:13:43 INFO - PROCESS | 15083 | RSS: Main: 138866688 07:13:43 INFO - PROCESS | 15083 | 07:16:42 INFO - PROCESS | 15083 | Cycle 1(1): loaded http://localhost:40425/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40425/tests/dromaeo/cssquery-ext.html) 07:16:44 INFO - PROCESS | 15083 | RSS: Main: 283815936 07:16:44 INFO - PROCESS | 15083 | 07:18:32 INFO - PROCESS | 15083 | ************************* 07:18:32 INFO - PROCESS | 15083 | A coding exception was thrown and uncaught in a Task. 07:18:32 INFO - PROCESS | 15083 | 07:18:32 ERROR - PROCESS | 15083 | Full message: TypeError: NetworkError when attempting to fetch resource. 07:18:32 INFO - PROCESS | 15083 | Full stack: 07:18:32 INFO - PROCESS | 15083 | ************************* 07:19:46 INFO - PROCESS | 15083 | Cycle 1(1): loaded http://localhost:40425/tests/dromaeo/cssquery-ext.html (next: http://localhost:40425/tests/dromaeo/cssquery-jquery.html) 07:19:47 INFO - PROCESS | 15083 | RSS: Main: 226193408 07:19:47 INFO - PROCESS | 15083 | 07:22:48 INFO - PROCESS | 15083 | Cycle 1(1): loaded http://localhost:40425/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40425/tests/dromaeo/cssquery-mootools.html) 07:22:49 INFO - PROCESS | 15083 | RSS: Main: 176177152 07:22:49 INFO - PROCESS | 15083 | 07:25:51 INFO - PROCESS | 15083 | Cycle 1(1): loaded http://localhost:40425/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40425/tests/dromaeo/cssquery-prototype.html) 07:25:51 INFO - PROCESS | 15083 | RSS: Main: 211898368 07:25:51 INFO - PROCESS | 15083 | 07:28:54 INFO - PROCESS | 15083 | Cycle 1(1): loaded http://localhost:40425/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40425/tests/dromaeo/cssquery-yui.html) 07:28:55 INFO - PROCESS | 15083 | RSS: Main: 171491328 07:28:55 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | Cycle 1(1): loaded http://localhost:40425/tests/dromaeo/cssquery-yui.html (next: http://localhost:40425/tests/dromaeo/cssquery-dojo.html) 07:31:57 INFO - PROCESS | 15083 | RSS: Main: 263028736 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | __start_tp_report 07:31:57 INFO - PROCESS | 15083 | _x_x_mozilla_page_load 07:31:57 INFO - PROCESS | 15083 | _x_x_mozilla_page_load_details 07:31:57 INFO - PROCESS | 15083 | |i|pagename|runs| 07:31:57 INFO - PROCESS | 15083 | |0;dojo.html;3190.393825173217;3217.420864244435;3241.935161296775;3253;3257.1857035741064;5350.705711185883;5418.810341638044;5423.620346575742;5428.348598168221;5471.316085489314;5440.564754819615;5464.617476776627;5469.5624350052;5472.288602481672;5478.890422191554;4965.0814599299065;4982.177940639808;5025.472325405834;5032.798688052473;5054.898902021957;4860;4871.269309603553;5030.37120359955;5074.8731281718065;5115.411727651312;4937.086638971802;4959.280904268879;4985.42667593226;5040.6471546991725;5077.898442031175;5063.594912407017;5069.670471419383;5078.162103252959;5100.566451851597;5129.615278854064;4798.808047678089;4988.351514303117;4992.650514463989;5040.243963405518;5061.139606266906;5217.50433708797;5309.52214300711;5401.567874570064;5401.5948803839865;5496.422875598085;5840.094785308242;5845.415458454163;5848.766049358063;5853.824385268449;5857.502112320458;5824.271966004249;5861.736221869984;5881.764729410818;5884.205632239636;5893.174955506232;5327.920081198824;5349.679019258818;5363.275957745733;5375;5377.300950876361;5886.175935369051;5888.793892213752;5889.528837692974;5892.587518873724;5919.970400147971;5644.125160600031;5692.601517893708;5692.857678557986;5697.373288938214;5698.544116470673;5630.183623374588;5684.289463817023;5698.230738850285;5698.8290351289525;5741.483266506035;5713.257276554022;5747.195392645033;5755.971220143872;5782.161586569924;5790.855228619318;5766;5768.278965129359;5770.682612456355;5773.682447465346;5774.133879917962;4342.32693932441;4406.889827754268;4421.358902959053;4469.9776501117285;4496.303073023687;3954.031262340745;3988.1624858779983;4004.2992476316526;4023.879283621496;4025.456563363967;5648;5649.915251271234;5653.406392328746;5653.491185793298;5653.802116925888;5636.520895723827;5646.6612003279115;5691.544676425876;5774.624649397776;5841.065429531254;5558.6942718150085;5566.4990150886615;5584.162375643605;5584.413636568183;5587.860303492445;4808.831690890802;4852.956614327936;4874.025194960951;4876.463589005207;4883.780229889663;6038.6678732670125;6086.878262434727;6088.756449741961;6090.025595904634;6094.573379863367;5592.077307244259;5618.410066943075;5751.884962300815;5815.04051831443;5831.70841457917;6034.306054803754;6079.118527813531;6080.726367313393;6117.388261173847;6144.4777193937225;5694.857628559319;5752.453516915886;5757.222774925332;5788.507976822018;5793.420657934173;5678.4605462480995;5719.914201286901;5811.738471768866;5862.032764593794;5864.032434648235;5595.832125036255;5611.943880561305;5646.378898321099;5714.79998200061;5744.798932037359;5669.149627555899;5674.886502269849;5679.460451257124;5680.062789639828;5702.5437964961875;5690.658560486384;5700.372958974593;5700.401457846866;5701.230333904869;5701.686407247641;5512.779488820403;5544.445555444423;5666.829995100154;5734.139879018181;5746.051901436216;5551.805686800943;5587.664740115443;5601.887962240814;5602.215689803512;5629.662220266797 07:31:57 INFO - PROCESS | 15083 | |1;ext.html;560.48155456203;603.2700432476704;616.7101462312713;621.7233331167629;642.5502148496054;39555.2088958221;49757.25364119541;51508.227376589384;51521.96956060881;55890.72054639721;53115.46884531154;53684;53749.462505375035;53750;53796.73101634496;8907.153820387086;9373.859392109123;9514.38156519824;9560.043995600454;9575.138237558618;8859.911400886005;11187.384693841836;11427.314361138317;11449.313041217554;11809.350485723282;11619.302841829518;11649.534018639244;11656.941715291412;11662.008729257981;11699.473523691455;11450.885491145065;11471.483783229774;11516.596919107791;11530.308181509135;11532.884671153264;11400.71498212543;11504.597339093176;11518.769624607461;11573.36346500942;11580.305181689126;50497.49502505001;50794.746026270004;50899.49100508985;50974.7451262745;51016;4611.608013318872;4632.374629425019;4681.976590117061;4767.904641907143;4772.188727916263;35702.64297357045;36377.45433818495;36416.453753193724;36888.63111368879;38214.80892595547;4608.608268297199;4673.532646735333;4692.7653617319;4715.599174070208;4732.124556956974;385866;386914;387759;388270.99999999715;388907;74169.6291518539;74612.62693686497;74677.25322746811;74720.62639686766;74760.62619686867;78753.21246787574;79149.60425197837;79314;79329;79332;19926.800731992495;20706.482337941372;20716;20751.37745867596;20759.065842037144;13008.739825203444;13040.087193896336;15387;15850.365985360457;15942.28259728315;20920.267790627255;21741;21909.671354929687;21911.890440547697;21988.120475180815;6598.571092879043;6662.300458451889;6682.532222744361;6734.45107625249;6823.55646882951;1009.399407352624;1019.2763138171981;1025.9487025648837;1028.9176865850714;1029.6807989523122;35078.29843403168;35139;35559.82220088883;35735.4639680405;35865.282694345966;1198.3049831097942;1202.5911190195375;1215.562397536886;1226.8527776666856;1296.5656505070717;18447;18632.44102676922;18826.341078062203;18847;18922.432327030212;169282;170261;170298.14850925666;170372;170515;70821;71056.289437106;71099.28900710927;71196;71714;1513.1526345246698;1552.5575211065018;1560.7034663413915;1580.5732452238062;1589.9364025439083;33309.50035749417;34566.13584660403;34774.47838282478;34951.475727864614;35123.47314790229;59567;61047.389526105944;61358.38641613527;61576.384236157064;61627.38372616396;422555;423762;424188;425668;426775;41519.79240103901;41911.7904410476;42979.14041719211;43045.784771075945;48068.75965620289;47272.2909156356;47362;50272.748636258046;50939.2359114621;51738;40307.59692403038;40902.386464202464;41491;41718.58281417268;42428.15143697047;40491.59508404996;40720;41412;41867.16265674731;42380.36429453622;40971.79514102411;41487;41572.37641435441;41618;41840.790796045825;34256.31487370288;36300.09249768777;36644.26711465809;36649.45025824668;37002.259954801295;27539.586906196022;27587;27691.723082768913;27766.86116569404;27812.443751124458 07:31:57 INFO - PROCESS | 15083 | |2;jquery.html;432.98051587678543;438.8946652803328;441.37545373296786;445.6412587866767;458.4200985753021;20211;20830.791692083094;22025.449363765914;22202.66695999557;22365.217217397396;21747.565048699016;22358;22573.209937652184;22899.42751431211;23113.537729245407;6096.298925623543;6096.298925623566;6310;6314.179156709622;6316.74733010679;6054.364291749369;6120.908186377208;6158.630482171084;6163.198784158053;6167.414095660905;5919;6060.939390606081;6132.264128304588;6143.8771224575485;6147.538934579924;6048.092786082079;6053.303870054956;6054.788082417094;6054.878902421949;6058.212432383762;5421.322334708161;5465.59008074392;5497.972510137463;5505.174223866412;5518.2826232589905;25333.366665833317;25515.362115947064;25651.871740641363;25749.098781542558;25811.741882581122;2905.4770141374543;2969.049904030711;3019.8593462798663;3054.7861649684532;3092.427900838352;15873.365065397371;15953.361865525481;16015;16130.435434759727;16487.83512164875;2020.3433883987764;2037.5721098569318;2042.1116814185727;2043.3767700851236;2065.4715510522255;78444.60777696075;89008;89436.55281723615;91776;91863;11943.940280298542;12068.043329384356;12131.272123672608;12197.573084941985;12338.198017128858;17646.470605881845;17681.911590441967;17699.38052168168;17713.29146834138;17747.290108395777;9354.204892584206;9523.619055237852;9555.22898359482;9577.23382129442;9650.90349096514;9134.954325228331;9225.769355766026;9231.492267925329;9306.953465232766;9463.337566370426;7653.617319134021;7776.883346749803;7836.882446763303;7858.607069646495;7987.2012798720825;2068.803463670949;2112.714783504238;2112.926047588358;2118.5339225370394;2144.488135864212;984.3208186351395;992.6823416506649;993.2252842782641;1000.8999100089933;1001.3891526169031;21380.465488362603;21792.673109903364;21902.12391504322;21946.780532194793;22164.113435462725;1017.872765904262;1019.4953498018529;1020.974475638115;1039.4127318065268;1046.2676126711333;11843.28940263587;12233.204841685261;12317.137800354069;12566.937165314115;12573;758744;774726;804997;810457;811033;91184;94722;97386.5130674342;98226.50886745521;99377;1186.5669030803695;1188.8454500914825;1203.1638011582043;1214.993925030387;1216.2459275249403;18501;18707.71938420952;19083.522911927314;19194.424167275003;19212.80787192166;32104.518432223067;32572;42723;43454.78272608617;44272.77863610661;54178.729106354214;54583;54593;56031;56421;8784.604692788713;8813.148737397596;8838.602262898057;8907.688230911886;9519.619215231312;13882.652933676738;13936.790948135584;14112.717745644824;14347.282635868374;14867.702645946803;11633.709157271136;11991.82012269834;12132.75734485323;12211.389430528616;12603.74792504163;11876.821847672465;12139.635810925687;12446.875531244572;12543.12198146121;13102.606921792361;12693.492260309853;12698.111132220656;12861.614151575468;12912.612621621367;12914.22514649124;9234.722958311262;9316.44101353921;9460.195883349992;9552.331336806636;9585.089416505421;12533.686657833627;12802.16785908913;12903.741925161632;13095.803562946372;13242.404091815699 07:31:57 INFO - PROCESS | 15083 | |3;mootools.html;1453.505808025271;1481.9851801481982;1508.8868334874885;1517.2641268984548;1520.9771853422196;7495.662695178717;8333.874991875127;8599.742007739762;8602.225799678019;8843.292536597073;8623.956880215606;8704.303655707545;8715.607797649105;8734.170253825892;8734.213920747132;2108.114591871418;2170.370592528173;2314.6990891184123;2346.249200255919;2353.188150088217;2383.2731017039796;2464.5194187133516;2468.2101727447225;2475.0471068638612;2480.888360023803;2380.416797884512;2392.1986134644917;2402.7477114902945;2410.8915098820594;2415.9637605435933;2381.6903802505562;2383.189715496731;2402.519496100787;2407.3981504623844;2412.216029790325;2363.9645405318934;2390.2829151254555;2399.6400539918977;2401.4836810085917;2401.639754036891;8704.347173961978;8751.474911505331;8770.912290877137;8777.166169213915;8780.604872780741;5147.562457191143;5291.1534154535475;5292.470752924715;5294.311739473882;5298.576113910878;3095.7213850753315;3134.3417882244757;3260;3314.96685033149;3319.6514365991466;1397.6156556946921;1412.427966673499;1468.4566710317144;1535.71589255988;1536.784850120984;11783.175177737561;11801.763964720743;11818.881811181864;11826.290422574673;11835.585754498552;4595.724256544551;4650.767461626905;4663.160631086437;4730.195866702668;4745.83389581363;10144.188464922789;10175.28772985884;10177.745556361002;10187.949060254652;10200.0819926207;5372.838814835562;5374.48942350476;5378.569714422837;5386.030514507348;5387.9461205388225;4774.069056533943;4879.365682461257;4880.82917097907;4898.877528061756;4915.95084049155;4062.070550722107;4086.2644723949975;4130.545639979599;4137.50349958007;4155.397467367276;1264.879836415539;1267.5056727876135;1267.6513958661442;1268.0552988023796;1273.5351596667335;1003.2174903575223;1006.0141061759371;1021.918246540275;1023.8976102389851;1024.8257796174669;5467.480589344006;5502.2296878437055;5541.591920968575;5568.220449137124;5587.106063029895;633.5818359882455;637.4836382530166;650.3236633900785;650.9902351464732;659.1924892007348;3954.7824869632445;4011.679065674739;4025.5370632377653;4028.7179897407486;4047.534533528623;10069.093781559695;10217.897821021843;10490.21323400748;10491.10825579819;10494.527746251437;10250;10251.333663311862;10257.743556411;10264.127549158256;10275.640352587772;7986.081600616004;8049.275565199043;8158.510489370657;8159.224873637114;8165.550894700848;5725.8568535786935;5790.594658373873;5824.446677565625;5834.94165058344;5940.46535811779;9253.72238832836;9565.473898935625;9578.56896439648;9588.616455341707;9631.614735410505;8429.831403372022;8704.738857834274;9666.854997175189;9750.561224744763;9756.365836220622;7229.060222171084;7337.376323012603;7392.334689877936;7516.210797866145;7598.544087354777;8182.509049457051;8230.794230144294;8231.300339471212;8304.54325012106;8319.043310019426;7294.890576641461;7328.523645963209;7329.120505539369;7365.668544915598;7503.5497870127965;6967.1291088613925;7047.753328633473;7087.574745515286;7145.642717863982;7161.892571611535;6384.776532821329;6487.772927947688;6518.445932095636;6523.836904077436;6547.279799221987;4997.150484417731;5141.28022076917;5179.559737422362;5192.428832828461;5215.895682086261;4881.29221262922;4928.334674818853;4995.500449954975;5025.773840177274;5092.2361645753435 07:31:57 INFO - PROCESS | 15083 | |4;prototype.html;385.9035241189701;396.0435548151215;401.1455599572911;407.6249850137873;413.7517489506296;5324.361076670805;5345.198220266957;5347.251384806121;5357.517823395893;5363.704996225211;5045.823396181126;5050.217216331474;5170.741462926858;5172.482751724826;5250.789968401259;4264.97867510662;4266.4027036214875;4271.765052922088;4286.742795432268;4295.892602684942;4300;4303.849365272216;4310.698251122422;4313.590208930146;4321.891952701192;3995.8801235962824;4121.4848143982;4249.683922262359;4258.382534532507;4297.205017071836;4243.308321760089;4259.8083086261195;4267.210566045292;4274.658027357804;4281.67887408444;3887.436321733333;3893.143508428141;3893.415987601854;3895.3962135868987;3896.7272290939645;5097.5667068299235;5172.844814655567;5174.8356619760625;5190.896182076337;5202.4537423570355;4866.513348665105;4873.975630121827;4923.630727695401;4928.26076088586;4933.407991041062;5504.834854954318;5513.8345849624575;5534.557235421156;5552.583556233299;5596.132599447093;4165.75005499668;4226.556211597769;4260.424842646234;4322.308430651081;4326.6754993375625;5672.744726487318;5683.658980461144;5685.356309316187;5690.577355661085;5709.657420554739;4378.365137055109;4408.493023302297;4412.051408947028;4428.4243048403505;4432.756198409118;4321.58944900234;4427.2252355838245;4453.287474004143;4496.3255511673515;4511.120331535385;4862.319275301461;4870.780814863339;4882.951170488321;4895.951040489621;4905.5094490550655;5503.642263252876;5551.084071128299;5560.499555039985;5567.47109024642;5595.908797784393;1424.7934049562962;1483.866452019302;1484.5768955847532;1493.589262952675;1507.216247551267;1027.7584767579613;1029.9288739710767;1060.6529707271723;1091.247039542713;1103.7406209540752;5270.341207349082;5282.1512470635735;5300.522952934254;5313.707746073926;5333.919991200135;5450.5912056595125;5465.535429488538;5469.671819690831;5476.2333273341765;5503.7798488060025;5098.515641014098;5248.317718696545;5263.841954769944;5264.263003179558;5279.894402111936;2888.4511942730815;2894.609227754226;2898.5797059426686;2900.796944213927;2903.736874459617;5447.210154527651;5650.604457688005;5677.829665110054;5687.374388817227;5707.286589176353;5474.863128421742;5486.753096110685;5500.339959204921;5516.834494965157;5561.221428999943;5647.237622920935;5660.88678226425;5682.7442765075675;5690.317161940594;5712.285964254385;5290.338707661543;5297.629165958346;5300.628955973045;5441.700706461183;5451.528087416296;5323.547498462519;5364.9731751341;5437.362879003759;5475.315585551806;5492.1212605982855;5475.863103422446;5738.885222295614;5740.483356497935;5742.3060197241075;5777.7977770777825;4286.30690877108;4288.442502474658;4294.9785251073545;4345.456817897762;4355.586219309161;5503;5514.5864060194845;5572.164175373727;5572.4706152915405;5573.804916827891;5173.241098026689;5299.49654782795;5340.626156169186;5366.221897824716;5371.731413429391;5290.365156181283;5303.798923446727;5319.627626066138;5325.813596524258;5328.600354973315;5270.657407268515;5303.0378759859095;5316.56452757761;5332.813351532678;5393.40672526014;5411.864703382447;5466.590005749665;5497.670139791625;5501.551809444759;5524.447555244444;5346.25152478661;5360.383555890967;5367.973160134175;5377.865553361197;5381.542568881689 07:31:57 INFO - PROCESS | 15083 | |5;yui.html;825.7068740597084;836.9079401265864;849.4521033933113;910.7131253655098;931.7950050988783;15210.695786084285;16471.91764041181;16490.75263871043;16505.91747041266;16570.917145414285;16976.15119244039;17027;17036.744448833277;17052;17194.484165475023;697.1285892634207;712.5332906945958;715.5599306426562;820.7332616899502;835.807764214231;722.7181399254295;813.6989313953816;829.2826704900278;830.796454868555;841.5665932044972;876.5135349880813;876.5617191404298;877.2499512916428;877.6313948141795;878.3587980774039;733.1422235983912;869.9608517616659;870.5038128266859;871.263782104121;871.4727589808169;551.806867596342;622.8972219583763;632.2886752403546;635.9077933699588;642.7910928948064;15764.448244311508;15898.602534936603;15910.920445397698;15913.124778137199;15975.680486390207;15054.473093441786;15329.31018104188;15329.616759580987;15527.689446211125;15667.92166039174;14113.605814706048;14300.642483937881;14342.498012569615;14379;14464.493742718954;4679.508651591602;4697.600703940169;4701.905961880743;4736.478987311393;4748.596369308613;79727;80137;80663.5966820168;80773.19226807715;80954;11220.438978051228;11458.828117578243;11551.595694150665;11779.764404712028;11801.52793888235;21368.14527418917;21476.24833130833;21544.0305186267;21565.35303940884;21764.45588860291;8682;8847.159519845605;8892.688755893512;9017.188453039258;9058.365914386062;4777.546133117348;4854.441739199967;4883;4924.679895806761;5020.849374518771;3670.79810610419;3776.4146557283116;3778.131029863147;3782.243551289698;3785.716071294664;628.8773689130667;645.2192846655599;646.896496560548;647.4464332995316;648.4974145037634;783.5103060587134;812.6180695073424;827.2513375395276;829.4815740162398;831.8585840407145;10551.630692925712;10745.677629671123;10909.890901090888;10939.343639381663;11047;806.1173015547965;813.581005782011;816.8747823491345;821.2286614768216;823.0904850140508;5129.487051294841;5149.940343362626;5190.532852043334;5220.425753167148;5256.973715131477;81610.59194704107;84140;84214.5789271062;87050;88162;71319.64340178265;72320.63839680873;72533.637331813;73237.6338118306;73382;65183.67408162929;66292.668536657;66302.66848665726;67231.66384168145;67663.3233667667;9981.900180998096;10046.69859904204;10089.545970431202;10183.49082545855;10212.795744084928;44359;44896;45216.77391613021;45504;46064.7696761514;55391.72304138453;55651.16523252073;57110.714446427504;57124.71437642785;57739;13089.541866034642;13331.133476324008;13358.866411335763;13395.263260520374;13457.327133643474;13797.862021379658;13913.860861391257;14055.648608784453;14096.08375455592;14573.781393279321;10898.509567069343;12670.429830657458;13148.342582870628;13269.469221231042;14326.176517192485;14909.850901490847;15239.3904243829;15247.237638118271;15294.847051529343;15603.219839007777;12695;12733.363331833556;12738.426770795522;12741.49034038628;12842.486300548248;11947.283163010246;12588.685282868002;12810;12980;13199.472021119047;10949.452527373758;11020.614278500214;11168.38573878412;11232.7753444929;11322.37726925027 07:31:57 INFO - PROCESS | 15083 | __end_tp_report 07:31:57 INFO - PROCESS | 15083 | __start_cc_report 07:31:57 INFO - PROCESS | 15083 | _x_x_mozilla_cycle_collect,1363 07:31:57 INFO - PROCESS | 15083 | __end_cc_report 07:31:57 INFO - PROCESS | 15083 | __startTimestamp1486049517887__endTimestamp 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | ------- Summary: start ------- 07:31:57 INFO - PROCESS | 15083 | Number of tests: 6 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | [#0] dojo.html Cycles:165 Average:5405.21 Median:5636.52 stddev:597.06 (10.6%) stddev-sans-first:573.05 07:31:57 INFO - PROCESS | 15083 | Values: 3190.4 3217.4 3241.9 3253.0 3257.2 5350.7 5418.8 5423.6 5428.3 5471.3 5440.6 5464.6 5469.6 5472.3 5478.9 4965.1 4982.2 5025.5 5032.8 5054.9 4860.0 4871.3 5030.4 5074.9 5115.4 4937.1 4959.3 4985.4 5040.6 5077.9 5063.6 5069.7 5078.2 5100.6 5129.6 4798.8 4988.4 4992.7 5040.2 5061.1 5217.5 5309.5 5401.6 5401.6 5496.4 5840.1 5845.4 5848.8 5853.8 5857.5 5824.3 5861.7 5881.8 5884.2 5893.2 5327.9 5349.7 5363.3 5375.0 5377.3 5886.2 5888.8 5889.5 5892.6 5920.0 5644.1 5692.6 5692.9 5697.4 5698.5 5630.2 5684.3 5698.2 5698.8 5741.5 5713.3 5747.2 5756.0 5782.2 5790.9 5766.0 5768.3 5770.7 5773.7 5774.1 4342.3 4406.9 4421.4 4470.0 4496.3 3954.0 3988.2 4004.3 4023.9 4025.5 5648.0 5649.9 5653.4 5653.5 5653.8 5636.5 5646.7 5691.5 5774.6 5841.1 5558.7 5566.5 5584.2 5584.4 5587.9 4808.8 4853.0 4874.0 4876.5 4883.8 6038.7 6086.9 6088.8 6090.0 6094.6 5592.1 5618.4 5751.9 5815.0 5831.7 6034.3 6079.1 6080.7 6117.4 6144.5 5694.9 5752.5 5757.2 5788.5 5793.4 5678.5 5719.9 5811.7 5862.0 5864.0 5595.8 5611.9 5646.4 5714.8 5744.8 5669.1 5674.9 5679.5 5680.1 5702.5 5690.7 5700.4 5700.4 5701.2 5701.7 5512.8 5544.4 5666.8 5734.1 5746.1 5551.8 5587.7 5601.9 5602.2 5629.7 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | [#1] ext.html Cycles:180 Average:54482.27 Median:40399.60 stddev:91340.20 (226.1%) stddev-sans-first:91506.71 07:31:57 INFO - PROCESS | 15083 | Values: 560.5 603.3 616.7 621.7 642.6 39555.2 49757.3 51508.2 51522.0 55890.7 53115.5 53684.0 53749.5 53750.0 53796.7 8907.2 9373.9 9514.4 9560.0 9575.1 8859.9 11187.4 11427.3 11449.3 11809.4 11619.3 11649.5 11656.9 11662.0 11699.5 11450.9 11471.5 11516.6 11530.3 11532.9 11400.7 11504.6 11518.8 11573.4 11580.3 50497.5 50794.7 50899.5 50974.7 51016.0 4611.6 4632.4 4682.0 4767.9 4772.2 35702.6 36377.5 36416.5 36888.6 38214.8 4608.6 4673.5 4692.8 4715.6 4732.1 385866.0 386914.0 387759.0 388271.0 388907.0 74169.6 74612.6 74677.3 74720.6 74760.6 78753.2 79149.6 79314.0 79329.0 79332.0 19926.8 20706.5 20716.0 20751.4 20759.1 13008.7 13040.1 15387.0 15850.4 15942.3 20920.3 21741.0 21909.7 21911.9 21988.1 6598.6 6662.3 6682.5 6734.5 6823.6 1009.4 1019.3 1025.9 1028.9 1029.7 35078.3 35139.0 35559.8 35735.5 35865.3 1198.3 1202.6 1215.6 1226.9 1296.6 18447.0 18632.4 18826.3 18847.0 18922.4 169282.0 170261.0 170298.1 170372.0 170515.0 70821.0 71056.3 71099.3 71196.0 71714.0 1513.2 1552.6 1560.7 1580.6 1589.9 33309.5 34566.1 34774.5 34951.5 35123.5 59567.0 61047.4 61358.4 61576.4 61627.4 422555.0 423762.0 424188.0 425668.0 426775.0 41519.8 41911.8 42979.1 43045.8 48068.8 47272.3 47362.0 50272.7 50939.2 51738.0 40307.6 40902.4 41491.0 41718.6 42428.2 40491.6 40720.0 41412.0 41867.2 42380.4 40971.8 41487.0 41572.4 41618.0 41840.8 34256.3 36300.1 36644.3 36649.5 37002.3 27539.6 27587.0 27691.7 27766.9 27812.4 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | [#2] jquery.html Cycles:180 Average:38596.76 Median:25780.42 stddev:129513.30 (502.4%) stddev-sans-first:129844.91 07:31:57 INFO - PROCESS | 15083 | Values: 433.0 438.9 441.4 445.6 458.4 20211.0 20830.8 22025.4 22202.7 22365.2 21747.6 22358.0 22573.2 22899.4 23113.5 6096.3 6096.3 6310.0 6314.2 6316.7 6054.4 6120.9 6158.6 6163.2 6167.4 5919.0 6060.9 6132.3 6143.9 6147.5 6048.1 6053.3 6054.8 6054.9 6058.2 5421.3 5465.6 5498.0 5505.2 5518.3 25333.4 25515.4 25651.9 25749.1 25811.7 2905.5 2969.0 3019.9 3054.8 3092.4 15873.4 15953.4 16015.0 16130.4 16487.8 2020.3 2037.6 2042.1 2043.4 2065.5 78444.6 89008.0 89436.6 91776.0 91863.0 11943.9 12068.0 12131.3 12197.6 12338.2 17646.5 17681.9 17699.4 17713.3 17747.3 9354.2 9523.6 9555.2 9577.2 9650.9 9135.0 9225.8 9231.5 9307.0 9463.3 7653.6 7776.9 7836.9 7858.6 7987.2 2068.8 2112.7 2112.9 2118.5 2144.5 984.3 992.7 993.2 1000.9 1001.4 21380.5 21792.7 21902.1 21946.8 22164.1 1017.9 1019.5 1021.0 1039.4 1046.3 11843.3 12233.2 12317.1 12566.9 12573.0 758744.0 774726.0 804997.0 810457.0 811033.0 91184.0 94722.0 97386.5 98226.5 99377.0 1186.6 1188.8 1203.2 1215.0 1216.2 18501.0 18707.7 19083.5 19194.4 19212.8 32104.5 32572.0 42723.0 43454.8 44272.8 54178.7 54583.0 54593.0 56031.0 56421.0 8784.6 8813.1 8838.6 8907.7 9519.6 13882.7 13936.8 14112.7 14347.3 14867.7 11633.7 11991.8 12132.8 12211.4 12603.7 11876.8 12139.6 12446.9 12543.1 13102.6 12693.5 12698.1 12861.6 12912.6 12914.2 9234.7 9316.4 9460.2 9552.3 9585.1 12533.7 12802.2 12903.7 13095.8 13242.4 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | [#3] mootools.html Cycles:180 Average:5632.32 Median:4922.14 stddev:3108.48 (63.2%) stddev-sans-first:3101.33 07:31:57 INFO - PROCESS | 15083 | Values: 1453.5 1482.0 1508.9 1517.3 1521.0 7495.7 8333.9 8599.7 8602.2 8843.3 8624.0 8704.3 8715.6 8734.2 8734.2 2108.1 2170.4 2314.7 2346.2 2353.2 2383.3 2464.5 2468.2 2475.0 2480.9 2380.4 2392.2 2402.7 2410.9 2416.0 2381.7 2383.2 2402.5 2407.4 2412.2 2364.0 2390.3 2399.6 2401.5 2401.6 8704.3 8751.5 8770.9 8777.2 8780.6 5147.6 5291.2 5292.5 5294.3 5298.6 3095.7 3134.3 3260.0 3315.0 3319.7 1397.6 1412.4 1468.5 1535.7 1536.8 11783.2 11801.8 11818.9 11826.3 11835.6 4595.7 4650.8 4663.2 4730.2 4745.8 10144.2 10175.3 10177.7 10187.9 10200.1 5372.8 5374.5 5378.6 5386.0 5387.9 4774.1 4879.4 4880.8 4898.9 4916.0 4062.1 4086.3 4130.5 4137.5 4155.4 1264.9 1267.5 1267.7 1268.1 1273.5 1003.2 1006.0 1021.9 1023.9 1024.8 5467.5 5502.2 5541.6 5568.2 5587.1 633.6 637.5 650.3 651.0 659.2 3954.8 4011.7 4025.5 4028.7 4047.5 10069.1 10217.9 10490.2 10491.1 10494.5 10250.0 10251.3 10257.7 10264.1 10275.6 7986.1 8049.3 8158.5 8159.2 8165.6 5725.9 5790.6 5824.4 5834.9 5940.5 9253.7 9565.5 9578.6 9588.6 9631.6 8429.8 8704.7 9666.9 9750.6 9756.4 7229.1 7337.4 7392.3 7516.2 7598.5 8182.5 8230.8 8231.3 8304.5 8319.0 7294.9 7328.5 7329.1 7365.7 7503.5 6967.1 7047.8 7087.6 7145.6 7161.9 6384.8 6487.8 6518.4 6523.8 6547.3 4997.2 5141.3 5179.6 5192.4 5215.9 4881.3 4928.3 4995.5 5025.8 5092.2 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | [#4] prototype.html Cycles:180 Average:4668.38 Median:5270.50 stddev:1285.98 (24.4%) stddev-sans-first:1248.77 07:31:57 INFO - PROCESS | 15083 | Values: 385.9 396.0 401.1 407.6 413.8 5324.4 5345.2 5347.3 5357.5 5363.7 5045.8 5050.2 5170.7 5172.5 5250.8 4265.0 4266.4 4271.8 4286.7 4295.9 4300.0 4303.8 4310.7 4313.6 4321.9 3995.9 4121.5 4249.7 4258.4 4297.2 4243.3 4259.8 4267.2 4274.7 4281.7 3887.4 3893.1 3893.4 3895.4 3896.7 5097.6 5172.8 5174.8 5190.9 5202.5 4866.5 4874.0 4923.6 4928.3 4933.4 5504.8 5513.8 5534.6 5552.6 5596.1 4165.8 4226.6 4260.4 4322.3 4326.7 5672.7 5683.7 5685.4 5690.6 5709.7 4378.4 4408.5 4412.1 4428.4 4432.8 4321.6 4427.2 4453.3 4496.3 4511.1 4862.3 4870.8 4883.0 4896.0 4905.5 5503.6 5551.1 5560.5 5567.5 5595.9 1424.8 1483.9 1484.6 1493.6 1507.2 1027.8 1029.9 1060.7 1091.2 1103.7 5270.3 5282.2 5300.5 5313.7 5333.9 5450.6 5465.5 5469.7 5476.2 5503.8 5098.5 5248.3 5263.8 5264.3 5279.9 2888.5 2894.6 2898.6 2900.8 2903.7 5447.2 5650.6 5677.8 5687.4 5707.3 5474.9 5486.8 5500.3 5516.8 5561.2 5647.2 5660.9 5682.7 5690.3 5712.3 5290.3 5297.6 5300.6 5441.7 5451.5 5323.5 5365.0 5437.4 5475.3 5492.1 5475.9 5738.9 5740.5 5742.3 5777.8 4286.3 4288.4 4295.0 4345.5 4355.6 5503.0 5514.6 5572.2 5572.5 5573.8 5173.2 5299.5 5340.6 5366.2 5371.7 5290.4 5303.8 5319.6 5325.8 5328.6 5270.7 5303.0 5316.6 5332.8 5393.4 5411.9 5466.6 5497.7 5501.6 5524.4 5346.3 5360.4 5368.0 5377.9 5381.5 07:31:57 INFO - PROCESS | 15083 | 07:31:57 INFO - PROCESS | 15083 | [#5] yui.html Cycles:180 Average:18499.22 Median:4699.75 stddev:23513.89 (500.3%) stddev-sans-first:23542.40 07:31:57 INFO - PROCESS | 15083 | Values: 825.7 836.9 849.5 910.7 931.8 15210.7 16471.9 16490.8 16505.9 16570.9 16976.2 17027.0 17036.7 17052.0 17194.5 697.1 712.5 715.6 820.7 835.8 722.7 813.7 829.3 830.8 841.6 876.5 876.6 877.2 877.6 878.4 733.1 870.0 870.5 871.3 871.5 551.8 622.9 632.3 635.9 642.8 15764.4 15898.6 15910.9 15913.1 15975.7 15054.5 15329.3 15329.6 15527.7 15667.9 14113.6 14300.6 14342.5 14379.0 14464.5 4679.5 4697.6 4701.9 4736.5 4748.6 79727.0 80137.0 80663.6 80773.2 80954.0 11220.4 11458.8 11551.6 11779.8 11801.5 21368.1 21476.2 21544.0 21565.4 21764.5 8682.0 8847.2 8892.7 9017.2 9058.4 4777.5 4854.4 4883.0 4924.7 5020.8 3670.8 3776.4 3778.1 3782.2 3785.7 628.9 645.2 646.9 647.4 648.5 783.5 812.6 827.3 829.5 831.9 10551.6 10745.7 10909.9 10939.3 11047.0 806.1 813.6 816.9 821.2 823.1 5129.5 5149.9 5190.5 5220.4 5257.0 81610.6 84140.0 84214.6 87050.0 88162.0 71319.6 72320.6 72533.6 73237.6 73382.0 65183.7 66292.7 66302.7 67231.7 67663.3 9981.9 10046.7 10089.5 10183.5 10212.8 44359.0 44896.0 45216.8 45504.0 46064.8 55391.7 55651.2 57110.7 57124.7 57739.0 13089.5 13331.1 13358.9 13395.3 13457.3 13797.9 13913.9 14055.6 14096.1 14573.8 10898.5 12670.4 13148.3 13269.5 14326.2 14909.9 15239.4 15247.2 15294.8 15603.2 12695.0 12733.4 12738.4 12741.5 12842.5 11947.3 12588.7 12810.0 12980.0 13199.5 10949.5 11020.6 11168.4 11232.8 11322.4 07:31:57 INFO - PROCESS | 15083 | -------- Summary: end -------- 07:31:57 INFO - PROCESS | 15083 | 07:31:58 INFO - TEST-INFO | 15083: exit 0 07:31:58 INFO - TEST-OK | dromaeo_css | took 1112535ms 07:31:58 INFO - TEST-START | kraken 07:31:58 INFO - operating with platform_type : linux_ 07:31:58 INFO - Initialising browser for kraken test... 07:31:58 INFO - TEST-INFO | started process 15409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Gnrxd/profile http://localhost:40425/getInfo.html) 07:32:02 INFO - PROCESS | 15409 | __metrics Screen width/height:1600/1200 07:32:02 INFO - PROCESS | 15409 | colorDepth:24 07:32:02 INFO - PROCESS | 15409 | Browser inner width/height: 1024/697 07:32:02 INFO - PROCESS | 15409 | __metrics 07:32:02 INFO - PROCESS | 15409 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:32:02 INFO - PROCESS | 15409 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:32:03 INFO - PROCESS | 15409 | console.error: PushService: 07:32:03 INFO - PROCESS | 15409 | stateChangeProcessEnqueue: Error transitioning state 07:32:03 INFO - PROCESS | 15409 | UnknownError 07:32:03 INFO - TEST-INFO | 15409: exit 0 07:32:03 INFO - Browser initialized. 07:32:03 INFO - Running cycle 1/1 for kraken test... 07:32:03 INFO - TEST-INFO | started process 15500 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0Gnrxd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:32:04 CRITICAL - PROCESS | 15500 | Inconsistency detected by ld.so: dl-open.c: 667: _dl_open: Assertion `_dl_debug_initialize (0, args.nsid)->r_state == RT_CONSISTENT' failed! 07:32:04 INFO - Terminating psutil.Process(pid=15500, name='firefox') 07:32:04 INFO - TEST-INFO | 15500: exit 127 07:32:04 INFO - TEST-UNEXPECTED-ERROR | kraken | Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 07:32:04 ERROR - Traceback (most recent call last): 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/run_tests.py", line 202, in run_tests 07:32:04 INFO - talos_results.add(mytest.runTest(browser_config, test)) 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 70, in runTest 07:32:04 INFO - return self._runTest(browser_config, test_config, setup) 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/ttest.py", line 232, in _runTest 07:32:04 INFO - else None) 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 94, in add 07:32:04 INFO - global_counters=self.global_counters 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 311, in __init__ 07:32:04 INFO - self.parse() 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 338, in parse 07:32:04 INFO - % self.report_tokens) 07:32:04 INFO - File "/builds/slave/test/build/tests/talos/talos/results.py", line 322, in error 07:32:04 INFO - raise utils.TalosError(message) 07:32:04 INFO - TalosError: Could not find report in browser output: [('tsformat', ('__start_report', '__end_report')), ('tpformat', ('__start_tp_report', '__end_tp_report'))] 07:32:04 INFO - TEST-INFO took 5403ms 07:32:04 INFO - SUITE-END | took 1117s 07:32:04 ERROR - Return code: 2 07:32:04 WARNING - setting return code to 2 07:32:04 CRITICAL - # TBPL RETRY # 07:32:04 WARNING - setting return code to 4 07:32:04 INFO - Running post-action listener: _resource_record_post_action 07:32:04 INFO - [mozharness: 2017-02-02 15:32:04.402673Z] Finished run-tests step (success) 07:32:04 INFO - Running post-run listener: _resource_record_post_run 07:32:04 INFO - Total resource usage - Wall time: 1135s; CPU: 13.0%; Read bytes: 266240; Write bytes: 312934400; Read time: 28; Write time: 244216 07:32:04 INFO - TinderboxPrint: CPU usage
13.2% 07:32:04 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 28 07:32:04 INFO - TinderboxPrint: I/O write bytes / time
312,934,400 / 244,216 07:32:04 INFO - TinderboxPrint: CPU idle
7,863.5 (86.8%) 07:32:04 INFO - TinderboxPrint: CPU user
1,159.9 (12.8%) 07:32:04 INFO - TinderboxPrint: Swap in / out
0 / 0 07:32:04 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 111013888; Read time: 0; Write time: 97844 07:32:04 INFO - run-tests - Wall time: 1119s; CPU: 13.0%; Read bytes: 266240; Write bytes: 201920512; Read time: 28; Write time: 146372 07:32:05 INFO - Running post-run listener: _upload_blobber_files 07:32:05 INFO - Blob upload gear active. 07:32:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:05 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:32:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:32:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:32:05 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:32:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:32:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:06 INFO - (blobuploader) - INFO - Done attempting. 07:32:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:32:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:09 INFO - (blobuploader) - INFO - Done attempting. 07:32:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:32:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:32:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:10 INFO - (blobuploader) - INFO - Done attempting. 07:32:10 INFO - (blobuploader) - INFO - Iteration through files over. 07:32:10 INFO - Return code: 0 07:32:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:32:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:32:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/4956374435d5950cf22180bd7d1e77ef2872f9cffd0468740984ca56df076920656eff56471a3782d718624d606f96ac4dc466271976cd0ff7632c2e71c9ffb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/707c20d4fab7e783a9dfe5de42ee187dc12b97ff6cb3c807cc22c1f86c4f97a91b4d3ac9265f4623e708def3042217445abf3e4920f2b5e9e9321bcf0fc590e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1a055e54fc9ee8380cd06e9dcaa57bd6603451da70f92e46cd6d8d97d1172a55e60cd812cb3fbd5d318a427d1ef3294f12693dfe821446041512420e653980a3"} 07:32:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:32:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:32:10 INFO - Contents: 07:32:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/4956374435d5950cf22180bd7d1e77ef2872f9cffd0468740984ca56df076920656eff56471a3782d718624d606f96ac4dc466271976cd0ff7632c2e71c9ffb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/707c20d4fab7e783a9dfe5de42ee187dc12b97ff6cb3c807cc22c1f86c4f97a91b4d3ac9265f4623e708def3042217445abf3e4920f2b5e9e9321bcf0fc590e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1a055e54fc9ee8380cd06e9dcaa57bd6603451da70f92e46cd6d8d97d1172a55e60cd812cb3fbd5d318a427d1ef3294f12693dfe821446041512420e653980a3"} 07:32:10 INFO - Running post-run listener: copy_logs_to_upload_dir 07:32:10 INFO - Copying logs to upload dir... 07:32:10 INFO - mkdir: /builds/slave/test/build/upload/logs 07:32:10 INFO - Copying logs to upload dir... 07:32:10 WARNING - returning nonzero exit status 4 program finished with exit code 4 elapsedTime=1179.063712 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' failed (results: 5, elapsed: 19 mins, 39 secs) (at 2017-02-02 07:32:09.820462) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 07:32:09.824366) ========= 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=826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/4956374435d5950cf22180bd7d1e77ef2872f9cffd0468740984ca56df076920656eff56471a3782d718624d606f96ac4dc466271976cd0ff7632c2e71c9ffb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/707c20d4fab7e783a9dfe5de42ee187dc12b97ff6cb3c807cc22c1f86c4f97a91b4d3ac9265f4623e708def3042217445abf3e4920f2b5e9e9321bcf0fc590e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1a055e54fc9ee8380cd06e9dcaa57bd6603451da70f92e46cd6d8d97d1172a55e60cd812cb3fbd5d318a427d1ef3294f12693dfe821446041512420e653980a3"} build_url:https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011316 build_url: 'https://queue.taskcluster.net/v1/task/fSSRwJDqRiirXOll4z_1Sw/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/4956374435d5950cf22180bd7d1e77ef2872f9cffd0468740984ca56df076920656eff56471a3782d718624d606f96ac4dc466271976cd0ff7632c2e71c9ffb2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/707c20d4fab7e783a9dfe5de42ee187dc12b97ff6cb3c807cc22c1f86c4f97a91b4d3ac9265f4623e708def3042217445abf3e4920f2b5e9e9321bcf0fc590e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora-Non-PGO/sha512/1a055e54fc9ee8380cd06e9dcaa57bd6603451da70f92e46cd6d8d97d1172a55e60cd812cb3fbd5d318a427d1ef3294f12693dfe821446041512420e653980a3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-02 07:32:09.848470) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:32:09.848751) ========= 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=826874ccc97bd7bf541b4875000001d5-1486048349.866780-1661956404 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004455 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-02 07:32:09.900439) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 07:32:09.900731) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-02 07:32:09.901003) ========= ========= Total master_lag: 0.12 =========