builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1496506075.5 results: success (0) revision: 98f1390029f9bd558de991a53c92342ac0addfc4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.503092) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.503723) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.504245) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.523958) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.524352) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-06-03 09:07:55-- 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.8M=0s 2017-06-03 09:07:55 (42.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125730 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.663630) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.664977) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018439 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.714440) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:55.717854) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 98f1390029f9bd558de991a53c92342ac0addfc4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 98f1390029f9bd558de991a53c92342ac0addfc4 --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=7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-06-03 09:07:55,566 truncating revision to first 12 chars 2017-06-03 09:07:55,566 Setting DEBUG logging. 2017-06-03 09:07:55,566 attempt 1/10 2017-06-03 09:07:55,566 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/98f1390029f9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-03 09:07:55,896 unpacking tar archive at: mozilla-central-98f1390029f9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630392 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:56.361749) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:56.365067) ========= script_repo_revision: 98f1390029f9bd558de991a53c92342ac0addfc4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:56.365472) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:56.365754) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-03 09:07:56.376099) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2017-06-03 09:07:56.376498) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:07:56 INFO - MultiFileLogger online at 20170603 09:07:56 in /builds/slave/test 09:07:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 09:07:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:07:56 INFO - {'append_to_log': False, 09:07:56 INFO - 'base_work_dir': '/builds/slave/test', 09:07:56 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 09:07:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:07:56 INFO - 'branch': 'Firefox-Non-PGO', 09:07:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:07:56 INFO - 'config_files': ('talos/linux_config.py',), 09:07:56 INFO - 'default_actions': ('clobber', 09:07:56 INFO - 'read-buildbot-config', 09:07:56 INFO - 'download-and-extract', 09:07:56 INFO - 'populate-webroot', 09:07:56 INFO - 'create-virtualenv', 09:07:56 INFO - 'install', 09:07:56 INFO - 'setup-mitmproxy', 09:07:56 INFO - 'run-tests'), 09:07:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:07:56 INFO - 'download_minidump_stackwalk': True, 09:07:56 INFO - 'download_symbols': 'ondemand', 09:07:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:07:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:07:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:07:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:07:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:07:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:07:56 INFO - 'gecko_profile': False, 09:07:56 INFO - 'gecko_profile_interval': 0, 09:07:56 INFO - 'installer_path': 'installer.exe', 09:07:56 INFO - 'log_level': 'info', 09:07:56 INFO - 'log_name': 'talos', 09:07:56 INFO - 'log_to_console': True, 09:07:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:07:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:07:56 INFO - 'opt_config_files': (), 09:07:56 INFO - 'pip_index': False, 09:07:56 INFO - 'suite': 'dromaeojs-e10s', 09:07:56 INFO - 'system_bits': '32', 09:07:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:07:56 INFO - 'title': 'talos-linux64-ix-005', 09:07:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:07:56 INFO - 'use_talos_json': True, 09:07:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:07:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:07:56 INFO - 'work_dir': 'build'} 09:07:56 INFO - [mozharness: 2017-06-03 16:07:56.325680Z] Running clobber step. 09:07:56 INFO - Running pre-action listener: _resource_record_pre_action 09:07:56 INFO - Running main action method: clobber 09:07:56 INFO - rmtree: /builds/slave/test/build 09:07:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:07:57 INFO - Running post-action listener: _resource_record_post_action 09:07:57 INFO - [mozharness: 2017-06-03 16:07:57.330916Z] Finished clobber step (success) 09:07:57 INFO - [mozharness: 2017-06-03 16:07:57.331017Z] Running read-buildbot-config step. 09:07:57 INFO - Running pre-action listener: _resource_record_pre_action 09:07:57 INFO - Running main action method: read_buildbot_config 09:07:57 INFO - Using buildbot properties: 09:07:57 INFO - { 09:07:57 INFO - "project": "", 09:07:57 INFO - "product": "firefox", 09:07:57 INFO - "who": "ffxbld@noreply.mozilla.org", 09:07:57 INFO - "installer_path": "public/build/target.tar.bz2", 09:07:57 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:07:57 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:07:57 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 09:07:57 INFO - "stage_platform": "linux64", 09:07:57 INFO - "basedir": "/builds/slave/test", 09:07:57 INFO - "buildnumber": 652, 09:07:57 INFO - "platform": "ubuntu64_hw", 09:07:57 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:07:57 INFO - "slavebuilddir": "test", 09:07:57 INFO - "taskId": "bQtQhiv5TgeHN6k4rGuTNQ", 09:07:57 INFO - "branch": "mozilla-central", 09:07:57 INFO - "script_repo_revision": "production", 09:07:57 INFO - "revision": "98f1390029f9bd558de991a53c92342ac0addfc4", 09:07:57 INFO - "slavename": "talos-linux64-ix-005", 09:07:57 INFO - "repo_path": "mozilla-central" 09:07:57 INFO - } 09:07:57 INFO - Finding installer, test and symbols from parent task. 09:07:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bQtQhiv5TgeHN6k4rGuTNQ'}, attempt #1 09:07:57 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bQtQhiv5TgeHN6k4rGuTNQ'}, attempt #1 09:07:58 INFO - Task dependencies: PZKpTjq4QjWO3upaqXKHsg 09:07:58 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg'}, attempt #1 09:07:58 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2 09:07:58 INFO - Set test_url: https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/test_packages.json 09:07:58 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.crashreporter-symbols.zip 09:07:58 INFO - Running post-action listener: _resource_record_post_action 09:07:58 INFO - [mozharness: 2017-06-03 16:07:58.682630Z] Finished read-buildbot-config step (success) 09:07:58 INFO - [mozharness: 2017-06-03 16:07:58.682851Z] Running download-and-extract step. 09:07:58 INFO - Running pre-action listener: _resource_record_pre_action 09:07:58 INFO - Running main action method: download_and_extract 09:07:58 INFO - mkdir: /builds/slave/test/build/tests 09:07:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:58 INFO - trying https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.test_packages.json 09:07:58 INFO - Downloading https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 09:07:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 09:07:59 INFO - Downloaded 989 bytes. 09:07:59 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 09:07:59 INFO - Using the following test package requirements: 09:07:59 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 09:07:59 INFO - u'common': [u'target.common.tests.zip'], 09:07:59 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 09:07:59 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 09:07:59 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 09:07:59 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 09:07:59 INFO - u'mozbase': [u'target.common.tests.zip'], 09:07:59 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 09:07:59 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 09:07:59 INFO - u'web-platform': [u'target.common.tests.zip', 09:07:59 INFO - u'target.web-platform.tests.tar.gz'], 09:07:59 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 09:07:59 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 09:07:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.common.tests.zip 09:07:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:07:59 INFO - Fetch https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.common.tests.zip into memory 09:08:00 INFO - Content-Length response header: 65102712 09:08:00 INFO - Bytes received: 65102712 09:08:11 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 09:08:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.common.tests.zip 09:08:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.common.tests.zip'}, attempt #1 09:08:11 INFO - Fetch https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.common.tests.zip into memory 09:08:12 INFO - Content-Length response header: 65102712 09:08:12 INFO - Bytes received: 65102712 09:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.talos.tests.zip 09:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.talos.tests.zip'}, attempt #1 09:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.talos.tests.zip into memory 09:08:26 INFO - Content-Length response header: 14447425 09:08:26 INFO - Bytes received: 14447425 09:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:26 INFO - trying https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2 09:08:26 INFO - Downloading https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 09:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 09:08:27 INFO - Downloaded 58521968 bytes. 09:08:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2 09:08:27 INFO - mkdir: /builds/slave/test/properties 09:08:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:08:27 INFO - Writing to file /builds/slave/test/properties/build_url 09:08:27 INFO - Contents: 09:08:27 INFO - build_url:https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2 09:08:27 INFO - Running post-action listener: _resource_record_post_action 09:08:27 INFO - Running post-action listener: set_extra_try_arguments 09:08:27 INFO - [mozharness: 2017-06-03 16:08:27.847813Z] Finished download-and-extract step (success) 09:08:27 INFO - [mozharness: 2017-06-03 16:08:27.847910Z] Running populate-webroot step. 09:08:27 INFO - Running pre-action listener: _resource_record_pre_action 09:08:27 INFO - Running main action method: populate_webroot 09:08:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:08:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:08:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:08:27 INFO - '--tppagecycles', 09:08:27 INFO - '7'], 09:08:27 INFO - 'tests': ['tsvgm']}}, 09:08:27 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:08:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:08:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:08:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:08:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['damp', 'tps']}, 09:08:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'tests': ['damp', 'tps']}, 09:08:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['dromaeo_dom']}, 09:08:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:08:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:08:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:08:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:08:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['a11yr', 09:08:27 INFO - 'ts_paint', 09:08:27 INFO - 'tpaint', 09:08:27 INFO - 'sessionrestore', 09:08:27 INFO - 'sessionrestore_no_auto_restore', 09:08:27 INFO - 'tabpaint']}, 09:08:27 INFO - 'other-e10s': {'tests': ['a11yr', 09:08:27 INFO - 'ts_paint', 09:08:27 INFO - 'tpaint', 09:08:27 INFO - 'sessionrestore', 09:08:27 INFO - 'sessionrestore_no_auto_restore', 09:08:27 INFO - 'tabpaint']}, 09:08:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:08:27 INFO - 'ts_paint', 09:08:27 INFO - 'tpaint', 09:08:27 INFO - 'sessionrestore', 09:08:27 INFO - 'sessionrestore_no_auto_restore', 09:08:27 INFO - 'tabpaint']}, 09:08:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:08:27 INFO - 'ts_paint', 09:08:27 INFO - 'tpaint', 09:08:27 INFO - 'sessionrestore', 09:08:27 INFO - 'sessionrestore_no_auto_restore', 09:08:27 INFO - 'tabpaint']}, 09:08:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['a11yr', 09:08:27 INFO - 'ts_paint', 09:08:27 INFO - 'tpaint', 09:08:27 INFO - 'sessionrestore', 09:08:27 INFO - 'sessionrestore_no_auto_restore', 09:08:27 INFO - 'tabpaint']}, 09:08:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['a11yr', 09:08:27 INFO - 'ts_paint', 09:08:27 INFO - 'tpaint', 09:08:27 INFO - 'sessionrestore', 09:08:27 INFO - 'sessionrestore_no_auto_restore', 09:08:27 INFO - 'tabpaint']}, 09:08:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:08:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:08:27 INFO - 'bloom_basic_ref']}, 09:08:27 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:08:27 INFO - 'talos_options': ['--mitmproxy', 09:08:27 INFO - 'mitmproxy-recording-1.mp', 09:08:27 INFO - '--firstNonBlankPaint'], 09:08:27 INFO - 'tests': ['Quantum_1']}, 09:08:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['tsvgx', 09:08:27 INFO - 'tsvgr_opacity', 09:08:27 INFO - 'tart', 09:08:27 INFO - 'tscrollx', 09:08:27 INFO - 'cart', 09:08:27 INFO - 'tsvg_static']}, 09:08:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:08:27 INFO - 'tsvgr_opacity', 09:08:27 INFO - 'tart', 09:08:27 INFO - 'tscrollx', 09:08:27 INFO - 'cart', 09:08:27 INFO - 'tsvg_static']}, 09:08:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'talos_options': ['--disable-e10s'], 09:08:27 INFO - 'tests': ['tp5o']}, 09:08:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:08:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'talos_options': ['--disable-e10s', 09:08:27 INFO - '--xperf_path', 09:08:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:27 INFO - 'tests': ['tp5n']}, 09:08:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:08:27 INFO - 'talos_options': ['--xperf_path', 09:08:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:08:27 INFO - 'tests': ['tp5n']}}, 09:08:27 INFO - 'talos.zip': {'path': '', 09:08:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:08:27 INFO - Running post-action listener: _resource_record_post_action 09:08:27 INFO - [mozharness: 2017-06-03 16:08:27.862238Z] Finished populate-webroot step (success) 09:08:27 INFO - [mozharness: 2017-06-03 16:08:27.862333Z] Running create-virtualenv step. 09:08:27 INFO - Running pre-action listener: _resource_record_pre_action 09:08:27 INFO - Running main action method: create_virtualenv 09:08:27 INFO - Creating virtualenv /builds/slave/test/build/venv 09:08:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:08:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:08:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:08:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:08:27 INFO - Using real prefix '/usr' 09:08:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:08:28 INFO - Installing distribute.............................................................................................................................................................................................done. 09:08:30 INFO - Installing pip.................done. 09:08:30 INFO - Return code: 0 09:08:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:08:30 INFO - Reading from file tmpfile_stdout 09:08:30 INFO - Output received: 09:08:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:08:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:08:30 INFO - Using env: {'DISPLAY': ':0', 09:08:30 INFO - 'HOME': '/home/cltbld', 09:08:30 INFO - 'LANG': 'en_US.UTF-8', 09:08:30 INFO - 'LANGUAGE': 'en_US:en', 09:08:30 INFO - 'LOGNAME': 'cltbld', 09:08:30 INFO - 'MAIL': '/var/mail/cltbld', 09:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:30 INFO - 'MOZ_NO_REMOTE': '1', 09:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:30 INFO - 'NO_EM_RESTART': '1', 09:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:30 INFO - 'PWD': '/builds/slave/test', 09:08:30 INFO - 'SHELL': '/bin/bash', 09:08:30 INFO - 'SHLVL': '1', 09:08:30 INFO - 'TERM': 'linux', 09:08:30 INFO - 'TMOUT': '86400', 09:08:30 INFO - 'USER': 'cltbld', 09:08:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:30 INFO - '_': '/tools/buildbot/bin/python'} 09:08:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:08:30 INFO - Cleaning up... 09:08:30 INFO - Return code: 0 09:08:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:08:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:08:30 INFO - Using env: {'DISPLAY': ':0', 09:08:30 INFO - 'HOME': '/home/cltbld', 09:08:30 INFO - 'LANG': 'en_US.UTF-8', 09:08:30 INFO - 'LANGUAGE': 'en_US:en', 09:08:30 INFO - 'LOGNAME': 'cltbld', 09:08:30 INFO - 'MAIL': '/var/mail/cltbld', 09:08:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:30 INFO - 'MOZ_NO_REMOTE': '1', 09:08:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:30 INFO - 'NO_EM_RESTART': '1', 09:08:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:30 INFO - 'PWD': '/builds/slave/test', 09:08:30 INFO - 'SHELL': '/bin/bash', 09:08:30 INFO - 'SHLVL': '1', 09:08:30 INFO - 'TERM': 'linux', 09:08:30 INFO - 'TMOUT': '86400', 09:08:30 INFO - 'USER': 'cltbld', 09:08:30 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:30 INFO - '_': '/tools/buildbot/bin/python'} 09:08:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:31 INFO - Downloading/unpacking psutil>=3.1.1 09:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:32 INFO - Installing collected packages: psutil 09:08:32 INFO - Running setup.py install for psutil 09:08:32 INFO - building 'psutil._psutil_linux' extension 09:08:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 09:08:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 09:08:32 INFO - building 'psutil._psutil_posix' extension 09:08:32 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 09:08:32 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 09:08:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:08:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:08:32 INFO - Successfully installed psutil 09:08:32 INFO - Cleaning up... 09:08:32 INFO - Return code: 0 09:08:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:08:32 INFO - Using env: {'DISPLAY': ':0', 09:08:32 INFO - 'HOME': '/home/cltbld', 09:08:32 INFO - 'LANG': 'en_US.UTF-8', 09:08:32 INFO - 'LANGUAGE': 'en_US:en', 09:08:32 INFO - 'LOGNAME': 'cltbld', 09:08:32 INFO - 'MAIL': '/var/mail/cltbld', 09:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:32 INFO - 'MOZ_NO_REMOTE': '1', 09:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:32 INFO - 'NO_EM_RESTART': '1', 09:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:32 INFO - 'PWD': '/builds/slave/test', 09:08:32 INFO - 'SHELL': '/bin/bash', 09:08:32 INFO - 'SHLVL': '1', 09:08:32 INFO - 'TERM': 'linux', 09:08:32 INFO - 'TMOUT': '86400', 09:08:32 INFO - 'USER': 'cltbld', 09:08:32 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:32 INFO - '_': '/tools/buildbot/bin/python'} 09:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:08:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:08:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:08:34 INFO - Installing collected packages: mozsystemmonitor 09:08:34 INFO - Running setup.py install for mozsystemmonitor 09:08:34 INFO - Successfully installed mozsystemmonitor 09:08:34 INFO - Cleaning up... 09:08:34 INFO - Return code: 0 09:08:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:08:34 INFO - Using env: {'DISPLAY': ':0', 09:08:34 INFO - 'HOME': '/home/cltbld', 09:08:34 INFO - 'LANG': 'en_US.UTF-8', 09:08:34 INFO - 'LANGUAGE': 'en_US:en', 09:08:34 INFO - 'LOGNAME': 'cltbld', 09:08:34 INFO - 'MAIL': '/var/mail/cltbld', 09:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:34 INFO - 'MOZ_NO_REMOTE': '1', 09:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:34 INFO - 'NO_EM_RESTART': '1', 09:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:34 INFO - 'PWD': '/builds/slave/test', 09:08:34 INFO - 'SHELL': '/bin/bash', 09:08:34 INFO - 'SHLVL': '1', 09:08:34 INFO - 'TERM': 'linux', 09:08:34 INFO - 'TMOUT': '86400', 09:08:34 INFO - 'USER': 'cltbld', 09:08:34 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:34 INFO - '_': '/tools/buildbot/bin/python'} 09:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:34 INFO - Downloading/unpacking jsonschema==2.5.1 09:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:08:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:35 INFO - Downloading functools32-3.2.3-2.tar.gz 09:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:08:35 INFO - warning: no files found matching '*.txt' 09:08:35 INFO - no previously-included directories found matching 'build' 09:08:35 INFO - no previously-included directories found matching 'dist' 09:08:35 INFO - no previously-included directories found matching '.git*' 09:08:35 INFO - Installing collected packages: jsonschema, functools32 09:08:35 INFO - Running setup.py install for functools32 09:08:35 INFO - warning: no files found matching '*.txt' 09:08:35 INFO - no previously-included directories found matching 'build' 09:08:35 INFO - no previously-included directories found matching 'dist' 09:08:35 INFO - no previously-included directories found matching '.git*' 09:08:35 INFO - Successfully installed jsonschema functools32 09:08:35 INFO - Cleaning up... 09:08:35 INFO - Return code: 0 09:08:35 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:08:35 INFO - Using env: {'DISPLAY': ':0', 09:08:35 INFO - 'HOME': '/home/cltbld', 09:08:35 INFO - 'LANG': 'en_US.UTF-8', 09:08:35 INFO - 'LANGUAGE': 'en_US:en', 09:08:35 INFO - 'LOGNAME': 'cltbld', 09:08:35 INFO - 'MAIL': '/var/mail/cltbld', 09:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:35 INFO - 'MOZ_NO_REMOTE': '1', 09:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:35 INFO - 'NO_EM_RESTART': '1', 09:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:35 INFO - 'PWD': '/builds/slave/test', 09:08:35 INFO - 'SHELL': '/bin/bash', 09:08:35 INFO - 'SHLVL': '1', 09:08:35 INFO - 'TERM': 'linux', 09:08:35 INFO - 'TMOUT': '86400', 09:08:35 INFO - 'USER': 'cltbld', 09:08:35 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:35 INFO - '_': '/tools/buildbot/bin/python'} 09:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:08:36 INFO - Cleaning up... 09:08:36 INFO - Return code: 0 09:08:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:36 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/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:08:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:08:36 INFO - Using env: {'DISPLAY': ':0', 09:08:36 INFO - 'HOME': '/home/cltbld', 09:08:36 INFO - 'LANG': 'en_US.UTF-8', 09:08:36 INFO - 'LANGUAGE': 'en_US:en', 09:08:36 INFO - 'LOGNAME': 'cltbld', 09:08:36 INFO - 'MAIL': '/var/mail/cltbld', 09:08:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:36 INFO - 'MOZ_NO_REMOTE': '1', 09:08:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:36 INFO - 'NO_EM_RESTART': '1', 09:08:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:36 INFO - 'PWD': '/builds/slave/test', 09:08:36 INFO - 'SHELL': '/bin/bash', 09:08:36 INFO - 'SHLVL': '1', 09:08:36 INFO - 'TERM': 'linux', 09:08:36 INFO - 'TMOUT': '86400', 09:08:36 INFO - 'USER': 'cltbld', 09:08:36 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:36 INFO - '_': '/tools/buildbot/bin/python'} 09:08:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:36 INFO - Downloading/unpacking blobuploader==1.2.4 09:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:37 INFO - Downloading blobuploader-1.2.4.tar.gz 09:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:08:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:08:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:37 INFO - Downloading docopt-0.6.1.tar.gz 09:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:08:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:08:38 INFO - Running setup.py install for blobuploader 09:08:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:08:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:08:38 INFO - Running setup.py install for requests 09:08:38 INFO - Running setup.py install for docopt 09:08:38 INFO - Successfully installed blobuploader requests docopt 09:08:38 INFO - Cleaning up... 09:08:38 INFO - Return code: 0 09:08:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:08:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:08:38 INFO - Using env: {'DISPLAY': ':0', 09:08:38 INFO - 'HOME': '/home/cltbld', 09:08:38 INFO - 'LANG': 'en_US.UTF-8', 09:08:38 INFO - 'LANGUAGE': 'en_US:en', 09:08:38 INFO - 'LOGNAME': 'cltbld', 09:08:38 INFO - 'MAIL': '/var/mail/cltbld', 09:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:38 INFO - 'MOZ_NO_REMOTE': '1', 09:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:38 INFO - 'NO_EM_RESTART': '1', 09:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:38 INFO - 'PWD': '/builds/slave/test', 09:08:38 INFO - 'SHELL': '/bin/bash', 09:08:38 INFO - 'SHLVL': '1', 09:08:38 INFO - 'TERM': 'linux', 09:08:38 INFO - 'TMOUT': '86400', 09:08:38 INFO - 'USER': 'cltbld', 09:08:38 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:38 INFO - '_': '/tools/buildbot/bin/python'} 09:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:38 INFO - Running setup.py (path:/tmp/pip-B7laSu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:38 INFO - Running setup.py (path:/tmp/pip-5YTPIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:38 INFO - Running setup.py (path:/tmp/pip-GHlbqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:39 INFO - Running setup.py (path:/tmp/pip-I_OGlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:39 INFO - Running setup.py (path:/tmp/pip-YDltVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:39 INFO - Running setup.py (path:/tmp/pip-XwErfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:39 INFO - Running setup.py (path:/tmp/pip-2fU9dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:39 INFO - Running setup.py (path:/tmp/pip-0vh6LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:39 INFO - Running setup.py (path:/tmp/pip-OM9YDE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:39 INFO - Running setup.py (path:/tmp/pip-LSzRuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:39 INFO - Running setup.py (path:/tmp/pip-y1qpJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:39 INFO - Running setup.py (path:/tmp/pip-_RBanY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:39 INFO - Running setup.py (path:/tmp/pip-fJOE1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:39 INFO - Running setup.py (path:/tmp/pip-L9cy9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:39 INFO - Running setup.py (path:/tmp/pip-awuVOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:39 INFO - Running setup.py (path:/tmp/pip-KBmxMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:39 INFO - Running setup.py (path:/tmp/pip-sHegsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:08:40 INFO - Running setup.py install for manifestparser 09:08:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Running setup.py install for mozcrash 09:08:40 INFO - Running setup.py install for mozdebug 09:08:40 INFO - Running setup.py install for mozdevice 09:08:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Running setup.py install for mozfile 09:08:40 INFO - Running setup.py install for mozhttpd 09:08:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Running setup.py install for mozinfo 09:08:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Running setup.py install for mozInstall 09:08:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:08:40 INFO - Running setup.py install for mozleak 09:08:40 INFO - Running setup.py install for mozlog 09:08:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Running setup.py install for moznetwork 09:08:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Running setup.py install for mozprocess 09:08:41 INFO - Running setup.py install for mozprofile 09:08:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Running setup.py install for mozrunner 09:08:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Running setup.py install for mozscreenshot 09:08:41 INFO - Running setup.py install for moztest 09:08:41 INFO - Running setup.py install for mozversion 09:08:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:08:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:08:41 INFO - Cleaning up... 09:08:41 INFO - Return code: 0 09:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:41 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/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:41 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:08:41 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:08:41 INFO - Using env: {'DISPLAY': ':0', 09:08:41 INFO - 'HOME': '/home/cltbld', 09:08:41 INFO - 'LANG': 'en_US.UTF-8', 09:08:41 INFO - 'LANGUAGE': 'en_US:en', 09:08:41 INFO - 'LOGNAME': 'cltbld', 09:08:41 INFO - 'MAIL': '/var/mail/cltbld', 09:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:41 INFO - 'MOZ_NO_REMOTE': '1', 09:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:41 INFO - 'NO_EM_RESTART': '1', 09:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:41 INFO - 'PWD': '/builds/slave/test', 09:08:41 INFO - 'SHELL': '/bin/bash', 09:08:41 INFO - 'SHLVL': '1', 09:08:41 INFO - 'TERM': 'linux', 09:08:41 INFO - 'TMOUT': '86400', 09:08:41 INFO - 'USER': 'cltbld', 09:08:41 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:41 INFO - '_': '/tools/buildbot/bin/python'} 09:08:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:08:42 INFO - Running setup.py (path:/tmp/pip-qHrZXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:08:42 INFO - Running setup.py (path:/tmp/pip-ZReqHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:08:42 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)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:08:42 INFO - Running setup.py (path:/tmp/pip-AuKbwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:08:42 INFO - Running setup.py (path:/tmp/pip-087Bg8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:08:42 INFO - Running setup.py (path:/tmp/pip-pBpO67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:08:42 INFO - Running setup.py (path:/tmp/pip-fWjqUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:08:42 INFO - Running setup.py (path:/tmp/pip-FimK4_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:08:42 INFO - Running setup.py (path:/tmp/pip-zJ54mt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:08:42 INFO - Running setup.py (path:/tmp/pip-AxJkBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:08:42 INFO - Running setup.py (path:/tmp/pip-pTtq4A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:08:42 INFO - Running setup.py (path:/tmp/pip-HCgYS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:08:42 INFO - Running setup.py (path:/tmp/pip-VxrQsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:08:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:08:42 INFO - Running setup.py (path:/tmp/pip-5pPVWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:08:43 INFO - Running setup.py (path:/tmp/pip-H2aUTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:08:43 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)) 09:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:43 INFO - Running setup.py (path:/tmp/pip-iBPJ5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:08:43 INFO - Running setup.py (path:/tmp/pip-WAXiVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:08:43 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)) 09:08:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:08:43 INFO - Running setup.py (path:/tmp/pip-v2pwga-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:08:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:44 INFO - Downloading blessings-1.6.tar.gz 09:08:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:08:44 INFO - Installing collected packages: blessings 09:08:44 INFO - Running setup.py install for blessings 09:08:44 INFO - Successfully installed blessings 09:08:44 INFO - Cleaning up... 09:08:44 INFO - Return code: 0 09:08:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:08:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:44 INFO - Reading from file tmpfile_stdout 09:08:44 INFO - Current package versions: 09:08:44 INFO - argparse == 1.2.1 09:08:44 INFO - blessings == 1.6 09:08:44 INFO - blobuploader == 1.2.4 09:08:44 INFO - docopt == 0.6.1 09:08:44 INFO - functools32 == 3.2.3-2 09:08:44 INFO - jsonschema == 2.5.1 09:08:44 INFO - manifestparser == 1.1 09:08:44 INFO - mozInstall == 1.12 09:08:44 INFO - mozcrash == 1.0 09:08:44 INFO - mozdebug == 0.1 09:08:44 INFO - mozdevice == 0.51 09:08:44 INFO - mozfile == 1.2 09:08:44 INFO - mozhttpd == 0.7 09:08:44 INFO - mozinfo == 0.9 09:08:44 INFO - mozleak == 0.1 09:08:44 INFO - mozlog == 3.4 09:08:44 INFO - moznetwork == 0.27 09:08:44 INFO - mozprocess == 0.25 09:08:44 INFO - mozprofile == 0.28 09:08:44 INFO - mozrunner == 6.13 09:08:44 INFO - mozscreenshot == 0.1 09:08:44 INFO - mozsystemmonitor == 0.3 09:08:44 INFO - moztest == 0.8 09:08:44 INFO - mozversion == 1.4 09:08:44 INFO - psutil == 3.1.1 09:08:44 INFO - requests == 1.2.3 09:08:44 INFO - wsgiref == 0.1.2 09:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:44 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/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:44 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:08:44 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:08:44 INFO - Using env: {'DISPLAY': ':0', 09:08:44 INFO - 'HOME': '/home/cltbld', 09:08:44 INFO - 'LANG': 'en_US.UTF-8', 09:08:44 INFO - 'LANGUAGE': 'en_US:en', 09:08:44 INFO - 'LOGNAME': 'cltbld', 09:08:44 INFO - 'MAIL': '/var/mail/cltbld', 09:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:44 INFO - 'MOZ_NO_REMOTE': '1', 09:08:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:44 INFO - 'NO_EM_RESTART': '1', 09:08:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:44 INFO - 'PWD': '/builds/slave/test', 09:08:44 INFO - 'SHELL': '/bin/bash', 09:08:44 INFO - 'SHLVL': '1', 09:08:44 INFO - 'TERM': 'linux', 09:08:44 INFO - 'TMOUT': '86400', 09:08:44 INFO - 'USER': 'cltbld', 09:08:44 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:44 INFO - '_': '/tools/buildbot/bin/python'} 09:08:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:08:45 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:08:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:08:46 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:08:46 INFO - Installing collected packages: marionette-driver 09:08:46 INFO - Running setup.py install for marionette-driver 09:08:46 INFO - Successfully installed marionette-driver 09:08:46 INFO - Cleaning up... 09:08:46 INFO - Return code: 0 09:08:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:08:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:08:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x16f03b0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18caa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a6edd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 09:08:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:08:46 INFO - Using env: {'DISPLAY': ':0', 09:08:46 INFO - 'HOME': '/home/cltbld', 09:08:46 INFO - 'LANG': 'en_US.UTF-8', 09:08:46 INFO - 'LANGUAGE': 'en_US:en', 09:08:46 INFO - 'LOGNAME': 'cltbld', 09:08:46 INFO - 'MAIL': '/var/mail/cltbld', 09:08:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:46 INFO - 'MOZ_NO_REMOTE': '1', 09:08:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:08:46 INFO - 'NO_EM_RESTART': '1', 09:08:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:08:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:46 INFO - 'PWD': '/builds/slave/test', 09:08:46 INFO - 'SHELL': '/bin/bash', 09:08:46 INFO - 'SHLVL': '1', 09:08:46 INFO - 'TERM': 'linux', 09:08:46 INFO - 'TMOUT': '86400', 09:08:46 INFO - 'USER': 'cltbld', 09:08:46 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:08:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:46 INFO - '_': '/tools/buildbot/bin/python'} 09:08:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:08:46 INFO - Cleaning up... 09:08:46 INFO - Return code: 0 09:08:46 INFO - Running post-action listener: _resource_record_post_action 09:08:46 INFO - Running post-action listener: _start_resource_monitoring 09:08:46 INFO - Starting resource monitoring. 09:08:46 INFO - [mozharness: 2017-06-03 16:08:46.608690Z] Finished create-virtualenv step (success) 09:08:46 INFO - [mozharness: 2017-06-03 16:08:46.609199Z] Running install step. 09:08:46 INFO - Running pre-action listener: _resource_record_pre_action 09:08:46 INFO - Running main action method: install 09:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:08:46 INFO - Reading from file tmpfile_stdout 09:08:46 INFO - Detecting whether we're running mozinstall >=1.0... 09:08:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:08:46 INFO - Reading from file tmpfile_stdout 09:08:46 INFO - Output received: 09:08:46 INFO - Usage: mozinstall [options] installer 09:08:46 INFO - Options: 09:08:46 INFO - -h, --help show this help message and exit 09:08:46 INFO - -d DEST, --destination=DEST 09:08:46 INFO - Directory to install application into. [default: 09:08:46 INFO - "/builds/slave/test"] 09:08:46 INFO - --app=APP Application being installed. [default: firefox] 09:08:46 INFO - mkdir: /builds/slave/test/build/application 09:08:46 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'] 09:08:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 09:09:03 INFO - Reading from file tmpfile_stdout 09:09:03 INFO - Output received: 09:09:03 INFO - /builds/slave/test/build/application/firefox/firefox 09:09:03 INFO - Running post-action listener: _resource_record_post_action 09:09:03 INFO - [mozharness: 2017-06-03 16:09:03.794971Z] Finished install step (success) 09:09:03 INFO - [mozharness: 2017-06-03 16:09:03.795250Z] Running setup-mitmproxy step. 09:09:03 INFO - Running pre-action listener: _resource_record_pre_action 09:09:03 INFO - Running main action method: setup_mitmproxy 09:09:03 INFO - Skipping: mitmproxy is not required 09:09:03 INFO - Running post-action listener: _resource_record_post_action 09:09:03 INFO - [mozharness: 2017-06-03 16:09:03.796381Z] Finished setup-mitmproxy step (success) 09:09:03 INFO - [mozharness: 2017-06-03 16:09:03.796603Z] Running run-tests step. 09:09:03 INFO - Running pre-action listener: _resource_record_pre_action 09:09:03 INFO - Running main action method: run_tests 09:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:09:03 INFO - Python 2.7.3 09:09:03 INFO - Return code: 0 09:09:03 INFO - grabbing minidump binary from tooltool 09:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:03 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a6b840>, '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 0x1a6c740>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a6cbd0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:09:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:09:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 09:09:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:09:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:09:03 INFO - Return code: 0 09:09:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:09:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:09:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:09:03 INFO - ENV: RUST_BACKTRACE is now 1 09:09:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:09:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:09:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:09:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:09:03 INFO - Using env: {'DISPLAY': ':0', 09:09:03 INFO - 'HOME': '/home/cltbld', 09:09:03 INFO - 'LANG': 'en_US.UTF-8', 09:09:03 INFO - 'LANGUAGE': 'en_US:en', 09:09:03 INFO - 'LOGNAME': 'cltbld', 09:09:03 INFO - 'MAIL': '/var/mail/cltbld', 09:09:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:09:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:09:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:03 INFO - 'MOZ_NO_REMOTE': '1', 09:09:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:09:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:09:03 INFO - 'NO_EM_RESTART': '1', 09:09:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:09:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:03 INFO - 'PWD': '/builds/slave/test', 09:09:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:09:03 INFO - 'RUST_BACKTRACE': '1', 09:09:03 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:09:03 INFO - 'SHELL': '/bin/bash', 09:09:03 INFO - 'SHLVL': '1', 09:09:03 INFO - 'TERM': 'linux', 09:09:03 INFO - 'TMOUT': '86400', 09:09:03 INFO - 'USER': 'cltbld', 09:09:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116', 09:09:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:03 INFO - '_': '/tools/buildbot/bin/python'} 09:09:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:09:04 INFO - mozversion application_buildid: 20170603153409 09:09:04 INFO - mozversion application_changeset: 98f1390029f9bd558de991a53c92342ac0addfc4 09:09:04 INFO - mozversion application_display_name: Nightly 09:09:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:09:04 INFO - mozversion application_name: Firefox 09:09:04 INFO - mozversion application_remotingname: firefox 09:09:04 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:09:04 INFO - mozversion application_vendor: Mozilla 09:09:04 INFO - mozversion application_version: 55.0a1 09:09:04 INFO - mozversion platform_buildid: 20170603153409 09:09:04 INFO - mozversion platform_changeset: 98f1390029f9bd558de991a53c92342ac0addfc4 09:09:04 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:09:04 INFO - mozversion platform_version: 55.0a1 09:09:04 INFO - using testdate: 1496506144 09:09:04 INFO - actual date: 1496506144 09:09:04 INFO - starting webserver on 'localhost:54258' 09:09:04 INFO - SUITE-START | Running 2 tests 09:09:04 INFO - TEST-START | dromaeo_css 09:09:04 INFO - operating with platform_type : linux_ 09:09:04 INFO - Initialising browser for dromaeo_css test... 09:09:04 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54258/getInfo.html -profile /tmp/tmp_TDkmi/profile 09:09:04 INFO - TEST-INFO | started process 4132 (/builds/slave/test/build/application/firefox/firefox http://localhost:54258/getInfo.html) 09:09:39 INFO - TEST-INFO | 4132: killed by SIGTERM 09:09:39 INFO - Browser initialized. 09:09:39 INFO - Running cycle 1/1 for dromaeo_css test... 09:09:39 INFO - TEST-INFO | started process 4338 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_TDkmi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:50 INFO - PID 4338 | RSS: Main: 188809216 09:09:50 INFO - PID 4338 | 09:12:51 INFO - PID 4338 | Cycle 1(1): loaded http://localhost:54258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54258/tests/dromaeo/cssquery-ext.html) 09:12:53 INFO - PID 4338 | RSS: Main: 183889920 09:12:53 INFO - PID 4338 | 09:15:54 INFO - PID 4338 | Cycle 1(1): loaded http://localhost:54258/tests/dromaeo/cssquery-ext.html (next: http://localhost:54258/tests/dromaeo/cssquery-jquery.html) 09:15:55 INFO - PID 4338 | RSS: Main: 188616704 09:15:55 INFO - PID 4338 | 09:18:57 INFO - PID 4338 | Cycle 1(1): loaded http://localhost:54258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54258/tests/dromaeo/cssquery-mootools.html) 09:18:57 INFO - PID 4338 | RSS: Main: 187510784 09:18:57 INFO - PID 4338 | 09:21:59 INFO - PID 4338 | Cycle 1(1): loaded http://localhost:54258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54258/tests/dromaeo/cssquery-prototype.html) 09:22:00 INFO - PID 4338 | RSS: Main: 189964288 09:22:00 INFO - PID 4338 | 09:25:02 INFO - PID 4338 | Cycle 1(1): loaded http://localhost:54258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54258/tests/dromaeo/cssquery-yui.html) 09:25:03 INFO - PID 4338 | RSS: Main: 189100032 09:25:03 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | Cycle 1(1): loaded http://localhost:54258/tests/dromaeo/cssquery-yui.html (next: http://localhost:54258/tests/dromaeo/cssquery-dojo.html) 09:28:05 INFO - PID 4338 | RSS: Main: 187908096 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | __start_tp_report 09:28:05 INFO - PID 4338 | _x_x_mozilla_page_load 09:28:05 INFO - PID 4338 | _x_x_mozilla_page_load_details 09:28:05 INFO - PID 4338 | |i|pagename|runs| 09:28:05 INFO - PID 4338 | |0;dojo.html;3146.1855475820894;3148.4175427545892;3149.4173577888087;3176.5235214717777;3185.5221716742485;5909.320428150764;6018;6052.818415447533;6057;6072.48383887369;5952.107183922414;5981.850453738659;5985.461308482236;6073.1497590337385;6078;5432.348118225818;5533.418991005937;5556.111022236443;5593.608447408683;5621.903728772891;5647.830565083054;5660.9716951415185;5663.546916246711;5665.1785491103765;5672.546196304285;5471.261379713767;5524.281843360378;5556.6943818089985;5566.248556444909;5597.580181486404;5553.555715542747;5558.082916318803;5576.832695019156;5604.8038318658655;5617.157426386034;5544;5549.112142057292;5549.722513874331;5559.749811258463;5572.498475137217;5885.234919460486;5939.70301484924;5975.641461512323;6019.8194054178;6028.125921741323;6257.624542527417;6398.488120950313;6407.8398040048905;6414.037894315843;6427.58220715652;6507.284198738135;6511.251206111263;6512.283648798676;6521.836954076139;6524.869502609969;5936;6029.60807547508;6034.96982515089;6036.275646922398;6041.516678665696;6548.443382312462;6556.213254409501;6634.303398143125;6642.734290628321;6654.567453115629;6167.537434692416;6204.441600255998;6224.813255602339;6340.461060809791;6348;6436.903446448307;6462.256840463313;6468.320826313264;6474.384933431316;6490.221173459215;6283.905741413882;6456.967715161394;6458.160439142881;6459.128017717642;6470.9029364559565;6389.424951754271;6393.968030159913;6439.8068057958335;6476.967615161988;6525.510586706016;5216.921746173811;5222.164453687392;5225.268462415265;5231.503007214308;5263.526282634582;4482.327650852399;4482.551744825557;4496.348029535764;4513.593776560126;4530.116627257653;5542.0301447246575;5602.075657516464;5624.578156638268;5630.296212973379;5690.943090569124;6428.325025872309;6451.387118223761;6457.257415397289;6477.255115661759;6484.643344616092;5606.1590761385305;5618.49837755389;5620.747066382022;5622.184783206412;5648;5585.525230355465;5606.467385598362;5664.20701101846;5666.688332141689;5702.714864256854;6230.003199488151;6230.190075290274;6236.563440559208;6271.937280627318;6272.059191121369;6435.935640643534;6488.318726533835;6509.837254068497;6517.022446633043;6529;6114.297368146176;6283.560150789401;6301.369863013662;6305.90541141874;6311.589746666452;6226.066090086342;6255.343188965093;6340.968295158495;6364.236291645032;6371.108044873814;6521.380468855451;6526.771562995463;6533.281339052795;6536.019597060289;6538.705758240795;6090.025595904634;6211.254649442096;6222.408871157233;6225.719842607185;6313.273973493107;6151.384861513813;6275.246970363585;6283.37166283368;6286.18279623646;6291.779787707408;6097.420745029215;6114.297368146176;6222.657753823583;6225.844353891189;6226.657533835682;6293.181886354744;6314.431701146918;6362.045693146065;6384.361563843764;6391.169148010729;6121.143039974496;6137.6931153441255;6155.692215389123;6161.4146656067605;6162.44537991565 09:28:05 INFO - PID 4338 | |1;ext.html;734.4308161175088;773.6557232031746;794.2851433709662;1124.7919134960027;1131.3268605179915;36784.26431471372;37553.12234388283;37804.05489862748;37936;38055.238895222115;39368.409473857915;39837.80081099598;40002;40055.79972100143;40131.19737605246;7297.89053164203;7329.267073292682;7397.260273972586;7475.289847464483;7558.584277864716;7311.341979221869;7453.701851925916;7459.328660420561;7461.365783908374;7463.141738700037;7417.666205020787;7434.628268586576;7473.103227612694;7487.850242995137;7490.325870671639;7436.553806771611;7467.701291948316;7468.365188958945;7476.551406915575;7477;7323.084614423197;7340.302671246223;7345.742898998509;7351.816204594875;7356.521826081287;36120.81939590311;36274.81862590696;36362.090947726254;36439.4534081989;36453.27093458116;3380.323935212967;3428.5885693716664;3446.965530344715;3451.050960985724;3456.2050728332374;27282.45435091287;27533.86233068842;27557.44885102307;27710.168694938984;27769.16692499228;3717.052151701299;3723.497327860758;3726.273376691546;3733.925321493582;3752.7560708553856;137184;140295;153049.23475382663;160374;161404.19297903436;43420.3486947696;43674.563254367684;43694.78152609216;43781;43884.12231755347;44532;44556.77721611436;45135.54864451313;45701.771491142994;45949;13887.58337249884;14168;14237;14245.857541424453;14276.21480818565;11422.828657570142;11471.311721296583;11501.13741469393;11531.88468115325;11555.653330400102;13994.16035037901;14022.789658155136;14085.39420207265;14111.506097286545;14157.292135393189;5817.486327911011;5879.3826648202175;5939.851503712442;5976.342602313742;6021.5182785377065;963.21016766251;986.4032260482448;990.4008075114596;991.1030517381781;1000.4297550396204;21896.452588685093;22635.2310596816;22712.435453314043;23093.88453057724;23299.53400931972;1070.812607793633;1076.7052818375796;1090.907272881798;1094.2723089145788;1111.6387174168428;11929.343886086348;12152.57465988686;12187.085968552217;12194.268343899394;12850.357482125857;199043;200191;200210;200503;200973;48230.517694823306;48444;48770.829896056646;48775.512244877806;48806.51193488091;1395.6720170759863;1446.6528033271939;1448.0659974316718;1461.437346621537;1467.5890750589851;20977.58044839125;21846.235381761562;23433.179838705564;23537.17619883296;23745.168919087748;35537;44241.77879110584;45318.320225197305;45448.77275613601;45481.54518454773;181603;182949.08525457288;183013;183468;183717;33116.337673245915;33627.49558756572;33628;33827.83086084554;34386.48420273648;40965.1806963865;42091;42409;42468.78765606152;42960.14079718326;28340.433191335644;30055.549166762077;30973.845130775102;31584;33023.174420639676;30067.849660751555;30202.848985754932;30833.383332332778;30873.536896946116;32658.83670581632;35220.823895880356;35261.64738352584;35264.471032934016;35337;35357.823210883784;29546.704532954394;29585.112446626637;30197.547036794906;30277.848610756806;32244.35511289808;23254.883725581265;23945.52108957846;24087.036518539062;24186.669733164847;24203.757962420856 09:28:05 INFO - PID 4338 | |2;jquery.html;424.2660197857704;425.385318215179;432.8183281541028;438.8354367112333;446.8927457410218;14713.411463541472;15269.541913742605;18912.527186820338;20146.49633759157;20166.69749953752;21514.462138446546;22300;22435.887820560874;22468.8876555617;22504.662430063563;5863.149843272723;6006.699665016732;6016.097585362187;6026;6032.788852390167;5747.482726554609;5808.97095514524;5845.2401187845735;5853.85365365865;5871.412858714137;5777.826665200051;5779.422057794208;5835.212246346752;6329.335419780926;6639.4688424926135;6602.834929126762;6619.768308109217;6625.503087268426;6645.235797883209;6676.165479315086;6440.227172739301;6459.128017717595;6463.5798673086565;6489.383508566679;6517.0224466329955;25901.28423332819;26236.344091397867;26585.46829063408;26669.93320267187;26703.599446008324;1937.8352840008474;1956.4717526267827;1961.5390383259924;1967.1934506852122;1972.615340008699;17588.648227035388;18009.63980720378;18091.819081809146;18095.909520452446;18116.637667246712;2377.2766356601214;2377.869217193054;2438.30508305134;2464.642626819101;2471.221565206954;112121.43939280332;113140.4342978288;113150.86849131486;114071.42964285209;114198;18065.283798039167;18976;19204.327848525234;19804.70292945607;19998.90000549988;35834.46248306277;36023.4596481053;36073.27853442916;36168;36213;10664.200184986157;10688.732781680521;10716.410597417216;10724.517396717167;10780.35317880914;8678.522681252593;8745.081766414562;8781.165789250139;8911.821763564822;8915.197632213132;10448.582056717798;10570.682879513626;10644.733881653021;10669.35983840972;10865.83701244482;2110.001547068297;2127.6702111172795;2139.7753235910004;2151.3761009307127;2252.6170551006367;934.5794392523392;936.2930987104828;948.8956214816365;982.6020461713018;1004.322082594246;17845.7323140153;21578.244761433587;22422.215222467134;23040.769592303863;24711.50576988486;980.7401038724744;983.5573991703762;995.9203263738883;1005.9144972677258;1040.2718097331897;9548.856767148498;9554.522273886278;10050.899491004997;10519.263651544472;10967.98380445384;437436.287599642;438278;442339;443567;444513;98806;101840.49079754703;104524;104883.4755826216;105950;1207.8127890177036;1221.993890030562;1252.5240408644838;1256.7109564799964;1267.1889990406148;17715.822841771933;19548.511287217934;20027.198912043354;20074.297399590945;20370;47796;47815.76092119517;47852;48100.27849582329;48726.756366217945;61551;61580.384196157465;62533;62557.68721156547;62680.37319626745;13995;14044.368003440075;14094.50669226572;14449.711005780036;14997.100173989596;16288.511344659679;16292.755608665642;16338.836611633731;16375.754363684317;16395.59011024734;13516.594502164951;14173.22047287409;14409.13545187292;14477.638059048608;14491.78262326087;12676.936615316865;12759.170653907466;12923.353832308536;13036.283004434468;13630.93184534071;14773.630659233606;14842.183679897278;15087.698246034799;15173.16547589849;15200.695986079996;14512.927435363177;15096.54710358691;15142.242887855784;15150.848491515384;15338.616534586725;13910.72178556403;13962.79055814184;13997.860021399656;14045.859541404863;14059.789103163255 09:28:05 INFO - PID 4338 | |3;mootools.html;1296.7276871856911;1353.5939218234532;1363.9931800340996;1364.9044566880316;1370.883474904633;10897.128229741622;11393.974542291193;11482.425878706063;11491.138164637643;11767.70580735482;11396.601118960838;11503.654890353282;11508.769824603503;11535.423228838567;11554.19120661554;2354.2937118864356;2404.483036147228;2406.109739396426;2406.855588664686;2410.120306088274;2344.753800850912;2436.9512609747794;2443.633454981758;2445.0097710427303;2455.8649274289905;2341.5716412988063;2349.929502114939;2367.2543148908126;2381.678473406094;2383.916562920298;2259.8531095478907;2290.03818396274;2291.2438895164555;2299.9195028174017;2307.619242824932;2214.767449417804;2216.0360243294217;2218.3566765637947;2222.7777222277805;2224.8887555622155;10868.45657717111;11075.889241107567;11090.722731931686;11102.056325212367;11132.721681957935;5946.345901950782;6160.6919654017565;6169.876602467925;6179.87640247197;6190.257169139687;3432.9656703432897;3551.0944709099012;3697.963020369789;3708.054446116223;3709.7032237421213;1220.3471142938486;1220.945057472407;1243.9004879609608;1246.214884622682;1252.0296770003229;14998.400063997427;15548.144852033134;16359.83640163595;16465;16521.504354869252;5045.520675535819;5063.5695965843315;5229;5229.764660590206;5261.263423120766;12258.08064395174;12314.445849936774;12393.318367489876;12394.31831249272;12397.75204495923;6102.328743838174;6212.502999760099;6216.906746398808;6222.408871157233;6223.408776166257;5658.405867383948;5778.6532808030815;5812.651240925474;5843.561732870052;5844.766209351664;4153.448507696826;4192.350185721218;4195.077083041726;4216.072464057902;4217.852375166915;1184.2835084773587;1195.7907366210877;1207.275634619239;1208.0577149823152;1226.2152222577558;812.5287333346645;833.6040380819184;847.8007668197969;850.6597361055627;875.5315905990392;5951.821445356646;5998.490128339053;6125.264968203844;6156.138140660311;6217.906731399033;584.8362458511624;607.884501944629;611.3550204534259;617.3116974573319;619.6344156947332;3835.5013848200115;3900.9219815604097;3904.1215726461887;3934.7835869027476;4098.975256185953;13387.732245355039;14301.070430421989;14567.490137845125;14709.190994495191;14842.554723358317;14238.644033899236;14369.928150359181;14409.033504197352;14501.782473262909;14735;8918.598663060178;8918.866217006751;9066.320025998077;9082.772930676785;9130.771730706652;6083.482903953037;6187.876242475035;6489.286178520265;6570.080188773671;6872;12833.16584422047;12910;12925.482980680668;13079.738405231652;13092.410841511964;13221.471141154247;13282.800757988445;13373.39819708096;13396.062275640612;13531.864681353061;8844.159804818531;8854.557272136239;8936.687215947411;8955.955220223857;8962.910370896208;10668.30656007357;10672.30630009047;10699.572017119228;10747.355158690503;10753.677389678322;8921.15249051339;8927.642894284156;9022;9253.259739220712;9296.535173241446;8952.597133128895;8955.552222388986;8983.46099234048;9037.231835293811;9181.081891810765;7374.889376659247;7384.667689954073;7536.962315188608;7544.585047822423;7610.429217808797;5764.048931926185;6030.155778191144;6086.421789929949;6278.466330361788;6380.170577824853;5752.712364381848;5892.793752218652;6006.67960239999;6032.3967603239325;6062.514998800174 09:28:05 INFO - PID 4338 | |4;prototype.html;388.9416587511871;390.4221751807325;393.3784620299925;396.8234135809564;398.57551707431594;6167.7841275555365;6182.505399568035;6204.96897515513;6225.284092329383;6255.937440625592;6000.819975400734;6094.421030002154;6132.386761323865;6171.7531298748;6199.380061993809;4919.736921016337;5052.671576347544;5055.545000949914;5064.696118232918;5084.262781896623;5090.465501122384;5094.949050509485;5106.183010718286;5113.054084994269;5121.692698438105;5031.094403007457;5035.874103147432;5046.7224302663335;5053.469385714502;5056.241563765446;5053.36832895888;5056.570191533724;5064.898702025976;5082.669626474268;5087.465816089294;4545.977270113662;4551.726896386227;4553.362529245908;4561.1789877821975;4579.633629309647;6100.908486372708;6174.876502469925;6180.2892667343385;6181.167676040838;6188.721507532172;5787;5800.680962547058;5803.332616749086;5806;5819.24349834523;6643.269240383554;6651.700673469657;6660.300668429842;6668.133142691468;6683.53215274931;4967.081089998325;4975.800967961313;4983.127952608279;4993.350864387643;4993.475685053054;6717.63053032083;6858.417034552019;6988.965055174743;7067.151941767021;7070.9292907070785;4819.156647586658;5017.9247311290355;5050.393952725696;5086.618503612244;5088.491150884882;5101.974490127525;5135.842945962705;5155;5159;5176.696370115263;5561.610687251854;5690.630109042899;5721.542276617861;5725.8568535786935;5737.971310143423;6607.273199948002;6613.107230523914;6615.76844810439;6622.86754264912;6624.337566243434;1772.5391398236293;1774.946751597454;1794.676958147546;1798.2896755781394;1803.0804289812029;1205.2768338996532;1244.2410129820803;1251.7121062155754;1260.533602567056;1262.5391732017747;5831.950248955144;5855.853603659943;5860;5875;5876.6777475067765;6732.562383445061;6735.966320168368;6739.966300168566;6761.425278851254;6800.455963522906;5741.88516229673;5760.135979603093;5775.653460792366;5807.216025836543;5807.245058142415;3571.499990001402;3598.8020658864016;3634.3821550336506;3635.8909232723063;3641.308151451216;6532.706028228741;6563.391968967633;6570.901436478457;6574.80275591733;6590.439812615886;6919.827004324932;6952.652367381611;6959.164900212007;6963.408110310579;6965.373116419547;6489.707963141575;6517.674116294166;6564.704588293443;6589;6600.471962243009;5968.224130863134;6743.617558400418;6762;6774.8645027100165;6835.487338449736;6745.190577130776;6746.257911629815;6755;6758.898616520854;6788.287229840796;6315.4947604192475;6482.805515834532;6487.351264873475;6610.504212184009;6631.6684165792485;4630.907381852411;4667.766611669471;4688.578027977498;4704.176769065331;4708.92936605958;6714.261431242657;6761.154855643044;6763.966180169067;6769.255381907888;6793;6516.283208846929;6557.377049180321;6561.606303621798;6570.8028759137305;6586;6593.736250549924;6608.801735947929;6618.90071648916;6653.900191497229;6662.933370666426;6417.26201486835;6489.123968264413;6508.837279068061;6540.37866402691;6586.901196482152;6599.819141973528;6616.90074648871;6660.333966603494;6673.165854268216;6685.164354455693;6360.681965901779;6416.935830641633;6426.686392898459;6434.64609446485;6452.806415807533 09:28:05 INFO - PID 4338 | |5;yui.html;743.1713639292191;763.2138896936154;798.6645608844285;827.9213474719901;858.2447446247303;16931.830681693194;17483;17496.387626433076;17525.561860953483;18383.080845957684;18666.440006799818;18799.530011749714;18875.811241887575;19115.330963416283;19138.90430547849;915.0666320353236;931.5668214280358;940.6237504998021;940.9576569054375;941.3081385181886;902.8013836955892;983.1986930651532;1006.3760468509535;1010.4493051287029;1012.1801340913846;1030.752619371353;1032.2052019944638;1033.2870319479534;1034.5499707627207;1034.6792494326746;1022.8977102289786;1024.3444195714749;1025.0518270599703;1030.850526673632;1033.6072292528866;751.7519218657884;754.4907187648371;757.4054367321626;758.2000988956648;760.9657565409569;16085.517434476984;16119.677606447805;16170.434034808843;16210;17490;18333.63332733351;18441;18928.810711892842;18999;19007.904960475247;14174.43302267908;14381.424743010268;14463.710725785531;14500.854991450162;14558.9080818939;3413.7269018478464;3517.2262102337445;3520.559930008749;3522.330757156132;3538.31002954424;83880;86012.56993715053;86131.13868861293;86235.70646440372;86394;14175.78736318956;14523.419063237352;14746.852531474762;15002.174880381684;15100.169490677898;25350.1127460538;26356;26405;26528;26795.33011674724;8230.711925082593;8377.078521362646;8538.573071346407;8563.272121869586;8596.699115531052;3991.261616600938;4069.8168582413864;4074.8166332515107;4079.571644977294;4082.408050832613;3974.4038394241097;4060.573639767782;4070.2266569351727;4077.8776636700945;4254.234237837219;810.9959450202829;841.8947631546057;843.852325842975;845.940784145104;845.9577021149041;655.131950166032;679.5311235247664;682.0485422835118;686.8900975843841;696.154172680196;10428.374297542172;10799.298045627167;11711.355875426763;11885.692573816874;12396.566120185751;659.1036190780535;681.3254877671169;684.1277371351566;709.6664567653195;714.5855403865745;4933.87665308363;5549.639273447294;5591.944080559224;5598.468145526169;5689.829305120853;72354;73874.63062684653;75874.24125758783;89110.55444722735;91730.54134729283;68687.31312686909;69932.65033674901;70441.64779176071;71474;73089;58679.70660146757;60445;60615;61091.6945415279;62717.68641156765;11174.66476005721;11770;11777.882221177679;11888.901095608702;12118.333491657691;28381.432371352872;28984.710152898202;29066.83732650755;29952.550711738906;30140.09579712612;35966.46050309195;36051.819740901126;36241;36791.26417471582;37039;15550.300236489158;15558.377664893278;15941.123238221546;15982.28079736438;16152.67694646124;18901;19650.312239071565;19723.30968416156;20244;20266.59466810685;17171.656566868343;17294;17366.565835854206;17646.1176941155;17954.5511362217;17177.57056073558;17745.201465934322;17883.73174402359;17907.641847162722;18249.54376140554;15781;15942;15998.20008999522;16285.511434656979;17047.062411567484;11519.654410367702;11648.41757912084;11658.825117623073;11760.117991150526;11909.523619055488;12295.262284262973;12667;12724;12765.234085954873;13595.660108497366 09:28:05 INFO - PID 4338 | __end_tp_report 09:28:05 INFO - PID 4338 | __start_cc_report 09:28:05 INFO - PID 4338 | _x_x_mozilla_cycle_collect,392 09:28:05 INFO - PID 4338 | __end_cc_report 09:28:05 INFO - PID 4338 | __startTimestamp1496507285635__endTimestamp 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | ------- Summary: start ------- 09:28:05 INFO - PID 4338 | Number of tests: 6 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | [#0] dojo.html Cycles:165 Average:5946.79 Median:6155.69 stddev:673.58 (10.9%) stddev-sans-first:638.81 09:28:05 INFO - PID 4338 | Values: 3146.2 3148.4 3149.4 3176.5 3185.5 5909.3 6018.0 6052.8 6057.0 6072.5 5952.1 5981.9 5985.5 6073.1 6078.0 5432.3 5533.4 5556.1 5593.6 5621.9 5647.8 5661.0 5663.5 5665.2 5672.5 5471.3 5524.3 5556.7 5566.2 5597.6 5553.6 5558.1 5576.8 5604.8 5617.2 5544.0 5549.1 5549.7 5559.7 5572.5 5885.2 5939.7 5975.6 6019.8 6028.1 6257.6 6398.5 6407.8 6414.0 6427.6 6507.3 6511.3 6512.3 6521.8 6524.9 5936.0 6029.6 6035.0 6036.3 6041.5 6548.4 6556.2 6634.3 6642.7 6654.6 6167.5 6204.4 6224.8 6340.5 6348.0 6436.9 6462.3 6468.3 6474.4 6490.2 6283.9 6457.0 6458.2 6459.1 6470.9 6389.4 6394.0 6439.8 6477.0 6525.5 5216.9 5222.2 5225.3 5231.5 5263.5 4482.3 4482.6 4496.3 4513.6 4530.1 5542.0 5602.1 5624.6 5630.3 5690.9 6428.3 6451.4 6457.3 6477.3 6484.6 5606.2 5618.5 5620.7 5622.2 5648.0 5585.5 5606.5 5664.2 5666.7 5702.7 6230.0 6230.2 6236.6 6271.9 6272.1 6435.9 6488.3 6509.8 6517.0 6529.0 6114.3 6283.6 6301.4 6305.9 6311.6 6226.1 6255.3 6341.0 6364.2 6371.1 6521.4 6526.8 6533.3 6536.0 6538.7 6090.0 6211.3 6222.4 6225.7 6313.3 6151.4 6275.2 6283.4 6286.2 6291.8 6097.4 6114.3 6222.7 6225.8 6226.7 6293.2 6314.4 6362.0 6384.4 6391.2 6121.1 6137.7 6155.7 6161.4 6162.4 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | [#1] ext.html Cycles:180 Average:34304.31 Median:3449.01 stddev:46324.03 (1343.1%) stddev-sans-first:46385.39 09:28:05 INFO - PID 4338 | Values: 734.4 773.7 794.3 1124.8 1131.3 36784.3 37553.1 37804.1 37936.0 38055.2 39368.4 39837.8 40002.0 40055.8 40131.2 7297.9 7329.3 7397.3 7475.3 7558.6 7311.3 7453.7 7459.3 7461.4 7463.1 7417.7 7434.6 7473.1 7487.9 7490.3 7436.6 7467.7 7468.4 7476.6 7477.0 7323.1 7340.3 7345.7 7351.8 7356.5 36120.8 36274.8 36362.1 36439.5 36453.3 3380.3 3428.6 3447.0 3451.1 3456.2 27282.5 27533.9 27557.4 27710.2 27769.2 3717.1 3723.5 3726.3 3733.9 3752.8 137184.0 140295.0 153049.2 160374.0 161404.2 43420.3 43674.6 43694.8 43781.0 43884.1 44532.0 44556.8 45135.5 45701.8 45949.0 13887.6 14168.0 14237.0 14245.9 14276.2 11422.8 11471.3 11501.1 11531.9 11555.7 13994.2 14022.8 14085.4 14111.5 14157.3 5817.5 5879.4 5939.9 5976.3 6021.5 963.2 986.4 990.4 991.1 1000.4 21896.5 22635.2 22712.4 23093.9 23299.5 1070.8 1076.7 1090.9 1094.3 1111.6 11929.3 12152.6 12187.1 12194.3 12850.4 199043.0 200191.0 200210.0 200503.0 200973.0 48230.5 48444.0 48770.8 48775.5 48806.5 1395.7 1446.7 1448.1 1461.4 1467.6 20977.6 21846.2 23433.2 23537.2 23745.2 35537.0 44241.8 45318.3 45448.8 45481.5 181603.0 182949.1 183013.0 183468.0 183717.0 33116.3 33627.5 33628.0 33827.8 34386.5 40965.2 42091.0 42409.0 42468.8 42960.1 28340.4 30055.5 30973.8 31584.0 33023.2 30067.8 30202.8 30833.4 30873.5 32658.8 35220.8 35261.6 35264.5 35337.0 35357.8 29546.7 29585.1 30197.5 30277.8 32244.4 23254.9 23945.5 24087.0 24186.7 24203.8 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | [#2] jquery.html Cycles:180 Average:31461.75 Median:11240.00 stddev:73845.60 (657.0%) stddev-sans-first:74015.98 09:28:05 INFO - PID 4338 | Values: 424.3 425.4 432.8 438.8 446.9 14713.4 15269.5 18912.5 20146.5 20166.7 21514.5 22300.0 22435.9 22468.9 22504.7 5863.1 6006.7 6016.1 6026.0 6032.8 5747.5 5809.0 5845.2 5853.9 5871.4 5777.8 5779.4 5835.2 6329.3 6639.5 6602.8 6619.8 6625.5 6645.2 6676.2 6440.2 6459.1 6463.6 6489.4 6517.0 25901.3 26236.3 26585.5 26669.9 26703.6 1937.8 1956.5 1961.5 1967.2 1972.6 17588.6 18009.6 18091.8 18095.9 18116.6 2377.3 2377.9 2438.3 2464.6 2471.2 112121.4 113140.4 113150.9 114071.4 114198.0 18065.3 18976.0 19204.3 19804.7 19998.9 35834.5 36023.5 36073.3 36168.0 36213.0 10664.2 10688.7 10716.4 10724.5 10780.4 8678.5 8745.1 8781.2 8911.8 8915.2 10448.6 10570.7 10644.7 10669.4 10865.8 2110.0 2127.7 2139.8 2151.4 2252.6 934.6 936.3 948.9 982.6 1004.3 17845.7 21578.2 22422.2 23040.8 24711.5 980.7 983.6 995.9 1005.9 1040.3 9548.9 9554.5 10050.9 10519.3 10968.0 437436.3 438278.0 442339.0 443567.0 444513.0 98806.0 101840.5 104524.0 104883.5 105950.0 1207.8 1222.0 1252.5 1256.7 1267.2 17715.8 19548.5 20027.2 20074.3 20370.0 47796.0 47815.8 47852.0 48100.3 48726.8 61551.0 61580.4 62533.0 62557.7 62680.4 13995.0 14044.4 14094.5 14449.7 14997.1 16288.5 16292.8 16338.8 16375.8 16395.6 13516.6 14173.2 14409.1 14477.6 14491.8 12676.9 12759.2 12923.4 13036.3 13630.9 14773.6 14842.2 15087.7 15173.2 15200.7 14512.9 15096.5 15142.2 15150.8 15338.6 13910.7 13962.8 13997.9 14045.9 14059.8 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | [#3] mootools.html Cycles:180 Average:6895.16 Median:3492.03 stddev:4440.93 (127.2%) stddev-sans-first:4433.46 09:28:05 INFO - PID 4338 | Values: 1296.7 1353.6 1364.0 1364.9 1370.9 10897.1 11394.0 11482.4 11491.1 11767.7 11396.6 11503.7 11508.8 11535.4 11554.2 2354.3 2404.5 2406.1 2406.9 2410.1 2344.8 2437.0 2443.6 2445.0 2455.9 2341.6 2349.9 2367.3 2381.7 2383.9 2259.9 2290.0 2291.2 2299.9 2307.6 2214.8 2216.0 2218.4 2222.8 2224.9 10868.5 11075.9 11090.7 11102.1 11132.7 5946.3 6160.7 6169.9 6179.9 6190.3 3433.0 3551.1 3698.0 3708.1 3709.7 1220.3 1220.9 1243.9 1246.2 1252.0 14998.4 15548.1 16359.8 16465.0 16521.5 5045.5 5063.6 5229.0 5229.8 5261.3 12258.1 12314.4 12393.3 12394.3 12397.8 6102.3 6212.5 6216.9 6222.4 6223.4 5658.4 5778.7 5812.7 5843.6 5844.8 4153.4 4192.4 4195.1 4216.1 4217.9 1184.3 1195.8 1207.3 1208.1 1226.2 812.5 833.6 847.8 850.7 875.5 5951.8 5998.5 6125.3 6156.1 6217.9 584.8 607.9 611.4 617.3 619.6 3835.5 3900.9 3904.1 3934.8 4099.0 13387.7 14301.1 14567.5 14709.2 14842.6 14238.6 14369.9 14409.0 14501.8 14735.0 8918.6 8918.9 9066.3 9082.8 9130.8 6083.5 6187.9 6489.3 6570.1 6872.0 12833.2 12910.0 12925.5 13079.7 13092.4 13221.5 13282.8 13373.4 13396.1 13531.9 8844.2 8854.6 8936.7 8956.0 8962.9 10668.3 10672.3 10699.6 10747.4 10753.7 8921.2 8927.6 9022.0 9253.3 9296.5 8952.6 8955.6 8983.5 9037.2 9181.1 7374.9 7384.7 7537.0 7544.6 7610.4 5764.0 6030.2 6086.4 6278.5 6380.2 5752.7 5892.8 6006.7 6032.4 6062.5 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | [#4] prototype.html Cycles:180 Average:5548.43 Median:6169.77 stddev:1566.12 (25.4%) stddev-sans-first:1521.88 09:28:05 INFO - PID 4338 | Values: 388.9 390.4 393.4 396.8 398.6 6167.8 6182.5 6205.0 6225.3 6255.9 6000.8 6094.4 6132.4 6171.8 6199.4 4919.7 5052.7 5055.5 5064.7 5084.3 5090.5 5094.9 5106.2 5113.1 5121.7 5031.1 5035.9 5046.7 5053.5 5056.2 5053.4 5056.6 5064.9 5082.7 5087.5 4546.0 4551.7 4553.4 4561.2 4579.6 6100.9 6174.9 6180.3 6181.2 6188.7 5787.0 5800.7 5803.3 5806.0 5819.2 6643.3 6651.7 6660.3 6668.1 6683.5 4967.1 4975.8 4983.1 4993.4 4993.5 6717.6 6858.4 6989.0 7067.2 7070.9 4819.2 5017.9 5050.4 5086.6 5088.5 5102.0 5135.8 5155.0 5159.0 5176.7 5561.6 5690.6 5721.5 5725.9 5738.0 6607.3 6613.1 6615.8 6622.9 6624.3 1772.5 1774.9 1794.7 1798.3 1803.1 1205.3 1244.2 1251.7 1260.5 1262.5 5832.0 5855.9 5860.0 5875.0 5876.7 6732.6 6736.0 6740.0 6761.4 6800.5 5741.9 5760.1 5775.7 5807.2 5807.2 3571.5 3598.8 3634.4 3635.9 3641.3 6532.7 6563.4 6570.9 6574.8 6590.4 6919.8 6952.7 6959.2 6963.4 6965.4 6489.7 6517.7 6564.7 6589.0 6600.5 5968.2 6743.6 6762.0 6774.9 6835.5 6745.2 6746.3 6755.0 6758.9 6788.3 6315.5 6482.8 6487.4 6610.5 6631.7 4630.9 4667.8 4688.6 4704.2 4708.9 6714.3 6761.2 6764.0 6769.3 6793.0 6516.3 6557.4 6561.6 6570.8 6586.0 6593.7 6608.8 6618.9 6653.9 6662.9 6417.3 6489.1 6508.8 6540.4 6586.9 6599.8 6616.9 6660.3 6673.2 6685.2 6360.7 6416.9 6426.7 6434.6 6452.8 09:28:05 INFO - PID 4338 | 09:28:05 INFO - PID 4338 | [#5] yui.html Cycles:180 Average:18295.45 Median:26466.50 stddev:22042.19 (83.3%) stddev-sans-first:22064.62 09:28:05 INFO - PID 4338 | Values: 743.2 763.2 798.7 827.9 858.2 16931.8 17483.0 17496.4 17525.6 18383.1 18666.4 18799.5 18875.8 19115.3 19138.9 915.1 931.6 940.6 941.0 941.3 902.8 983.2 1006.4 1010.4 1012.2 1030.8 1032.2 1033.3 1034.5 1034.7 1022.9 1024.3 1025.1 1030.9 1033.6 751.8 754.5 757.4 758.2 761.0 16085.5 16119.7 16170.4 16210.0 17490.0 18333.6 18441.0 18928.8 18999.0 19007.9 14174.4 14381.4 14463.7 14500.9 14558.9 3413.7 3517.2 3520.6 3522.3 3538.3 83880.0 86012.6 86131.1 86235.7 86394.0 14175.8 14523.4 14746.9 15002.2 15100.2 25350.1 26356.0 26405.0 26528.0 26795.3 8230.7 8377.1 8538.6 8563.3 8596.7 3991.3 4069.8 4074.8 4079.6 4082.4 3974.4 4060.6 4070.2 4077.9 4254.2 811.0 841.9 843.9 845.9 846.0 655.1 679.5 682.0 686.9 696.2 10428.4 10799.3 11711.4 11885.7 12396.6 659.1 681.3 684.1 709.7 714.6 4933.9 5549.6 5591.9 5598.5 5689.8 72354.0 73874.6 75874.2 89110.6 91730.5 68687.3 69932.7 70441.6 71474.0 73089.0 58679.7 60445.0 60615.0 61091.7 62717.7 11174.7 11770.0 11777.9 11888.9 12118.3 28381.4 28984.7 29066.8 29952.6 30140.1 35966.5 36051.8 36241.0 36791.3 37039.0 15550.3 15558.4 15941.1 15982.3 16152.7 18901.0 19650.3 19723.3 20244.0 20266.6 17171.7 17294.0 17366.6 17646.1 17954.6 17177.6 17745.2 17883.7 17907.6 18249.5 15781.0 15942.0 15998.2 16285.5 17047.1 11519.7 11648.4 11658.8 11760.1 11909.5 12295.3 12667.0 12724.0 12765.2 13595.7 09:28:05 INFO - PID 4338 | -------- Summary: end -------- 09:28:05 INFO - PID 4338 | 09:28:06 INFO - TEST-INFO | 4338: exit 0 09:28:06 INFO - TEST-OK | dromaeo_css | took 1142508ms 09:28:06 INFO - TEST-START | kraken 09:28:06 INFO - operating with platform_type : linux_ 09:28:06 INFO - Initialising browser for kraken test... 09:28:06 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:54258/getInfo.html -profile /tmp/tmpokXFl9/profile 09:28:06 INFO - TEST-INFO | started process 4749 (/builds/slave/test/build/application/firefox/firefox http://localhost:54258/getInfo.html) 09:28:41 INFO - TEST-INFO | 4749: killed by SIGTERM 09:28:41 INFO - Browser initialized. 09:28:41 INFO - Running cycle 1/1 for kraken test... 09:28:41 INFO - TEST-INFO | started process 4951 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpokXFl9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:28:53 INFO - PID 4951 | RSS: Main: 188014592 09:28:53 INFO - PID 4951 | 09:28:55 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54258/tests/kraken/driver.html?testName=audio-beat-detection) 09:28:56 INFO - PID 4951 | RSS: Main: 191983616 09:28:56 INFO - PID 4951 | 09:28:59 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54258/tests/kraken/driver.html?testName=audio-dft) 09:28:59 INFO - PID 4951 | RSS: Main: 190296064 09:28:59 INFO - PID 4951 | 09:29:02 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54258/tests/kraken/driver.html?testName=audio-fft) 09:29:03 INFO - PID 4951 | RSS: Main: 185729024 09:29:03 INFO - PID 4951 | 09:29:05 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54258/tests/kraken/driver.html?testName=audio-oscillator) 09:29:06 INFO - PID 4951 | RSS: Main: 186597376 09:29:06 INFO - PID 4951 | 09:29:08 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:29:08 INFO - PID 4951 | RSS: Main: 186408960 09:29:08 INFO - PID 4951 | 09:29:11 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54258/tests/kraken/driver.html?testName=imaging-darkroom) 09:29:12 INFO - PID 4951 | RSS: Main: 187498496 09:29:12 INFO - PID 4951 | 09:29:16 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54258/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:16 INFO - PID 4951 | RSS: Main: 187142144 09:29:16 INFO - PID 4951 | 09:29:19 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54258/tests/kraken/driver.html?testName=json-parse-financial) 09:29:20 INFO - PID 4951 | RSS: Main: 187846656 09:29:20 INFO - PID 4951 | 09:29:21 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:22 INFO - PID 4951 | RSS: Main: 188076032 09:29:22 INFO - PID 4951 | 09:29:24 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:24 INFO - PID 4951 | RSS: Main: 187953152 09:29:24 INFO - PID 4951 | 09:29:27 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:27 INFO - PID 4951 | RSS: Main: 188035072 09:29:27 INFO - PID 4951 | 09:29:30 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:29:31 INFO - PID 4951 | RSS: Main: 187564032 09:29:31 INFO - PID 4951 | 09:29:34 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:29:34 INFO - PID 4951 | RSS: Main: 188223488 09:29:34 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | Cycle 1(1): loaded http://localhost:54258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54258/tests/kraken/driver.html?testName=ai-astar) 09:29:36 INFO - PID 4951 | RSS: Main: 189771776 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | __start_tp_report 09:29:36 INFO - PID 4951 | _x_x_mozilla_page_load 09:29:36 INFO - PID 4951 | _x_x_mozilla_page_load_details 09:29:36 INFO - PID 4951 | |i|pagename|runs| 09:29:36 INFO - PID 4951 | |0;ai-astar;105;110;111;111;112;114;110;111;111;112 09:29:36 INFO - PID 4951 | |1;audio-beat-detection;149;121;122;163;128;121;123;120;143;121 09:29:36 INFO - PID 4951 | |2;audio-dft;164;165;164;162;163;165;168;169;171;165 09:29:36 INFO - PID 4951 | |3;audio-fft;74;73;73;99;80;80;75;74;96;73 09:29:36 INFO - PID 4951 | |4;audio-oscillator;81;82;101;83;82;81;93;81;83;109 09:29:36 INFO - PID 4951 | |5;imaging-gaussian-blur;114;115;114;115;114;114;114;114;113;115 09:29:36 INFO - PID 4951 | |6;imaging-darkroom;150;150;151;151;155;150;151;150;150;150 09:29:36 INFO - PID 4951 | |7;imaging-desaturate;95;96;95;95;94;94;94;94;94;94 09:29:36 INFO - PID 4951 | |8;json-parse-financial;56;59;55;55;56;57;60;57;56;58 09:29:36 INFO - PID 4951 | |9;json-stringify-tinderbox;58;56;56;55;56;56;55;57;56;56 09:29:36 INFO - PID 4951 | |10;stanford-crypto-aes;87;95;85;86;87;87;88;86;129;86 09:29:36 INFO - PID 4951 | |11;stanford-crypto-ccm;132;137;135;134;131;136;139;138;128;145 09:29:36 INFO - PID 4951 | |12;stanford-crypto-pbkdf2;189;194;192;191;198;186;189;187;193;201 09:29:36 INFO - PID 4951 | |13;stanford-crypto-sha256-iterative;73;72;70;74;70;72;69;75;69;72 09:29:36 INFO - PID 4951 | __end_tp_report 09:29:36 INFO - PID 4951 | __start_cc_report 09:29:36 INFO - PID 4951 | _x_x_mozilla_cycle_collect,1004 09:29:36 INFO - PID 4951 | __end_cc_report 09:29:36 INFO - PID 4951 | __startTimestamp1496507376886__endTimestamp 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | ------- Summary: start ------- 09:29:36 INFO - PID 4951 | Number of tests: 14 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#0] ai-astar Cycles:10 Average:110.70 Median:111.00 stddev:2.31 (2.1%) stddev-sans-first:1.22 09:29:36 INFO - PID 4951 | Values: 105.0 110.0 111.0 111.0 112.0 114.0 110.0 111.0 111.0 112.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#1] audio-beat-detection Cycles:10 Average:131.10 Median:125.50 stddev:15.15 (12.1%) stddev-sans-first:14.62 09:29:36 INFO - PID 4951 | Values: 149.0 121.0 122.0 163.0 128.0 121.0 123.0 120.0 143.0 121.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#2] audio-dft Cycles:10 Average:165.60 Median:165.00 stddev:2.84 (1.7%) stddev-sans-first:2.95 09:29:36 INFO - PID 4951 | Values: 164.0 165.0 164.0 162.0 163.0 165.0 168.0 169.0 171.0 165.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#3] audio-fft Cycles:10 Average:79.70 Median:77.50 stddev:9.78 (12.6%) stddev-sans-first:10.15 09:29:36 INFO - PID 4951 | Values: 74.0 73.0 73.0 99.0 80.0 80.0 75.0 74.0 96.0 73.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#4] audio-oscillator Cycles:10 Average:87.60 Median:82.00 stddev:10.01 (12.2%) stddev-sans-first:10.33 09:29:36 INFO - PID 4951 | Values: 81.0 82.0 101.0 83.0 82.0 81.0 93.0 81.0 83.0 109.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#5] imaging-gaussian-blur Cycles:10 Average:114.20 Median:114.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 09:29:36 INFO - PID 4951 | Values: 114.0 115.0 114.0 115.0 114.0 114.0 114.0 114.0 113.0 115.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#6] imaging-darkroom Cycles:10 Average:150.80 Median:150.50 stddev:1.55 (1.0%) stddev-sans-first:1.62 09:29:36 INFO - PID 4951 | Values: 150.0 150.0 151.0 151.0 155.0 150.0 151.0 150.0 150.0 150.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:94.50 stddev:0.71 (0.7%) stddev-sans-first:0.73 09:29:36 INFO - PID 4951 | Values: 95.0 96.0 95.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:1.66 (2.9%) stddev-sans-first:1.73 09:29:36 INFO - PID 4951 | Values: 56.0 59.0 55.0 55.0 56.0 57.0 60.0 57.0 56.0 58.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#9] json-stringify-tinderbox Cycles:10 Average:56.10 Median:56.00 stddev:0.88 (1.6%) stddev-sans-first:0.60 09:29:36 INFO - PID 4951 | Values: 58.0 56.0 56.0 55.0 56.0 56.0 55.0 57.0 56.0 56.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#10] stanford-crypto-aes Cycles:10 Average:91.60 Median:87.00 stddev:13.43 (15.4%) stddev-sans-first:14.15 09:29:36 INFO - PID 4951 | Values: 87.0 95.0 85.0 86.0 87.0 87.0 88.0 86.0 129.0 86.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#11] stanford-crypto-ccm Cycles:10 Average:135.50 Median:136.50 stddev:4.74 (3.5%) stddev-sans-first:4.86 09:29:36 INFO - PID 4951 | Values: 132.0 137.0 135.0 134.0 131.0 136.0 139.0 138.0 128.0 145.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.00 Median:192.50 stddev:4.74 (2.5%) stddev-sans-first:4.90 09:29:36 INFO - PID 4951 | Values: 189.0 194.0 192.0 191.0 198.0 186.0 189.0 187.0 193.0 201.0 09:29:36 INFO - PID 4951 | 09:29:36 INFO - PID 4951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.60 Median:72.00 stddev:2.07 (2.9%) stddev-sans-first:2.13 09:29:36 INFO - PID 4951 | Values: 73.0 72.0 70.0 74.0 70.0 72.0 69.0 75.0 69.0 72.0 09:29:36 INFO - PID 4951 | -------- Summary: end -------- 09:29:36 INFO - PID 4951 | 09:29:38 INFO - TEST-INFO | 4951: exit 0 09:29:38 INFO - TEST-OK | kraken | took 91472ms 09:29:38 INFO - SUITE-END | took 1233s 09:29:38 INFO - Completed test suite (00:20:34) 09:29:38 INFO - javascript benchmark 09:29:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7793.618987230079, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.1855475820894, 3148.4175427545892, 3149.4173577888087, 3176.5235214717777, 3185.5221716742485, 5909.320428150764, 6018.0, 6052.818415447533, 6057.0, 6072.48383887369, 5952.107183922414, 5981.850453738659, 5985.461308482236, 6073.1497590337385, 6078.0, 5432.348118225818, 5533.418991005937, 5556.111022236443, 5593.608447408683, 5621.903728772891, 5647.830565083054, 5660.9716951415185, 5663.546916246711, 5665.1785491103765, 5672.546196304285, 5471.261379713767, 5524.281843360378, 5556.6943818089985, 5566.248556444909, 5597.580181486404, 5553.555715542747, 5558.082916318803, 5576.832695019156, 5604.8038318658655, 5617.157426386034, 5544.0, 5549.112142057292, 5549.722513874331, 5559.749811258463, 5572.498475137217, 5885.234919460486, 5939.70301484924, 5975.641461512323, 6019.8194054178, 6028.125921741323, 6257.624542527417, 6398.488120950313, 6407.8398040048905, 6414.037894315843, 6427.58220715652, 6507.284198738135, 6511.251206111263, 6512.283648798676, 6521.836954076139, 6524.869502609969, 5936.0, 6029.60807547508, 6034.96982515089, 6036.275646922398, 6041.516678665696, 6548.443382312462, 6556.213254409501, 6634.303398143125, 6642.734290628321, 6654.567453115629, 6167.537434692416, 6204.441600255998, 6224.813255602339, 6340.461060809791, 6348.0, 6436.903446448307, 6462.256840463313, 6468.320826313264, 6474.384933431316, 6490.221173459215, 6283.905741413882, 6456.967715161394, 6458.160439142881, 6459.128017717642, 6470.9029364559565, 6389.424951754271, 6393.968030159913, 6439.8068057958335, 6476.967615161988, 6525.510586706016, 5216.921746173811, 5222.164453687392, 5225.268462415265, 5231.503007214308, 5263.526282634582, 4482.327650852399, 4482.551744825557, 4496.348029535764, 4513.593776560126, 4530.116627257653, 5542.0301447246575, 5602.075657516464, 5624.578156638268, 5630.296212973379, 5690.943090569124, 6428.325025872309, 6451.387118223761, 6457.257415397289, 6477.255115661759, 6484.643344616092, 5606.1590761385305, 5618.49837755389, 5620.747066382022, 5622.184783206412, 5648.0, 5585.525230355465, 5606.467385598362, 5664.20701101846, 5666.688332141689, 5702.714864256854, 6230.003199488151, 6230.190075290274, 6236.563440559208, 6271.937280627318, 6272.059191121369, 6435.935640643534, 6488.318726533835, 6509.837254068497, 6517.022446633043, 6529.0, 6114.297368146176, 6283.560150789401, 6301.369863013662, 6305.90541141874, 6311.589746666452, 6226.066090086342, 6255.343188965093, 6340.968295158495, 6364.236291645032, 6371.108044873814, 6521.380468855451, 6526.771562995463, 6533.281339052795, 6536.019597060289, 6538.705758240795, 6090.025595904634, 6211.254649442096, 6222.408871157233, 6225.719842607185, 6313.273973493107, 6151.384861513813, 6275.246970363585, 6283.37166283368, 6286.18279623646, 6291.779787707408, 6097.420745029215, 6114.297368146176, 6222.657753823583, 6225.844353891189, 6226.657533835682, 6293.181886354744, 6314.431701146918, 6362.045693146065, 6384.361563843764, 6391.169148010729, 6121.143039974496, 6137.6931153441255, 6155.692215389123, 6161.4146656067605, 6162.44537991565], "value": 5898.734579526969, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.4308161175088, 773.6557232031746, 794.2851433709662, 1124.7919134960027, 1131.3268605179915, 36784.26431471372, 37553.12234388283, 37804.05489862748, 37936.0, 38055.238895222115, 39368.409473857915, 39837.80081099598, 40002.0, 40055.79972100143, 40131.19737605246, 7297.89053164203, 7329.267073292682, 7397.260273972586, 7475.289847464483, 7558.584277864716, 7311.341979221869, 7453.701851925916, 7459.328660420561, 7461.365783908374, 7463.141738700037, 7417.666205020787, 7434.628268586576, 7473.103227612694, 7487.850242995137, 7490.325870671639, 7436.553806771611, 7467.701291948316, 7468.365188958945, 7476.551406915575, 7477.0, 7323.084614423197, 7340.302671246223, 7345.742898998509, 7351.816204594875, 7356.521826081287, 36120.81939590311, 36274.81862590696, 36362.090947726254, 36439.4534081989, 36453.27093458116, 3380.323935212967, 3428.5885693716664, 3446.965530344715, 3451.050960985724, 3456.2050728332374, 27282.45435091287, 27533.86233068842, 27557.44885102307, 27710.168694938984, 27769.16692499228, 3717.052151701299, 3723.497327860758, 3726.273376691546, 3733.925321493582, 3752.7560708553856, 137184.0, 140295.0, 153049.23475382663, 160374.0, 161404.19297903436, 43420.3486947696, 43674.563254367684, 43694.78152609216, 43781.0, 43884.12231755347, 44532.0, 44556.77721611436, 45135.54864451313, 45701.771491142994, 45949.0, 13887.58337249884, 14168.0, 14237.0, 14245.857541424453, 14276.21480818565, 11422.828657570142, 11471.311721296583, 11501.13741469393, 11531.88468115325, 11555.653330400102, 13994.16035037901, 14022.789658155136, 14085.39420207265, 14111.506097286545, 14157.292135393189, 5817.486327911011, 5879.3826648202175, 5939.851503712442, 5976.342602313742, 6021.5182785377065, 963.21016766251, 986.4032260482448, 990.4008075114596, 991.1030517381781, 1000.4297550396204, 21896.452588685093, 22635.2310596816, 22712.435453314043, 23093.88453057724, 23299.53400931972, 1070.812607793633, 1076.7052818375796, 1090.907272881798, 1094.2723089145788, 1111.6387174168428, 11929.343886086348, 12152.57465988686, 12187.085968552217, 12194.268343899394, 12850.357482125857, 199043.0, 200191.0, 200210.0, 200503.0, 200973.0, 48230.517694823306, 48444.0, 48770.829896056646, 48775.512244877806, 48806.51193488091, 1395.6720170759863, 1446.6528033271939, 1448.0659974316718, 1461.437346621537, 1467.5890750589851, 20977.58044839125, 21846.235381761562, 23433.179838705564, 23537.17619883296, 23745.168919087748, 35537.0, 44241.77879110584, 45318.320225197305, 45448.77275613601, 45481.54518454773, 181603.0, 182949.08525457288, 183013.0, 183468.0, 183717.0, 33116.337673245915, 33627.49558756572, 33628.0, 33827.83086084554, 34386.48420273648, 40965.1806963865, 42091.0, 42409.0, 42468.78765606152, 42960.14079718326, 28340.433191335644, 30055.549166762077, 30973.845130775102, 31584.0, 33023.174420639676, 30067.849660751555, 30202.848985754932, 30833.383332332778, 30873.536896946116, 32658.83670581632, 35220.823895880356, 35261.64738352584, 35264.471032934016, 35337.0, 35357.823210883784, 29546.704532954394, 29585.112446626637, 30197.547036794906, 30277.848610756806, 32244.35511289808, 23254.883725581265, 23945.52108957846, 24087.036518539062, 24186.669733164847, 24203.757962420856], "value": 16283.455103910863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.2660197857704, 425.385318215179, 432.8183281541028, 438.8354367112333, 446.8927457410218, 14713.411463541472, 15269.541913742605, 18912.527186820338, 20146.49633759157, 20166.69749953752, 21514.462138446546, 22300.0, 22435.887820560874, 22468.8876555617, 22504.662430063563, 5863.149843272723, 6006.699665016732, 6016.097585362187, 6026.0, 6032.788852390167, 5747.482726554609, 5808.97095514524, 5845.2401187845735, 5853.85365365865, 5871.412858714137, 5777.826665200051, 5779.422057794208, 5835.212246346752, 6329.335419780926, 6639.4688424926135, 6602.834929126762, 6619.768308109217, 6625.503087268426, 6645.235797883209, 6676.165479315086, 6440.227172739301, 6459.128017717595, 6463.5798673086565, 6489.383508566679, 6517.0224466329955, 25901.28423332819, 26236.344091397867, 26585.46829063408, 26669.93320267187, 26703.599446008324, 1937.8352840008474, 1956.4717526267827, 1961.5390383259924, 1967.1934506852122, 1972.615340008699, 17588.648227035388, 18009.63980720378, 18091.819081809146, 18095.909520452446, 18116.637667246712, 2377.2766356601214, 2377.869217193054, 2438.30508305134, 2464.642626819101, 2471.221565206954, 112121.43939280332, 113140.4342978288, 113150.86849131486, 114071.42964285209, 114198.0, 18065.283798039167, 18976.0, 19204.327848525234, 19804.70292945607, 19998.90000549988, 35834.46248306277, 36023.4596481053, 36073.27853442916, 36168.0, 36213.0, 10664.200184986157, 10688.732781680521, 10716.410597417216, 10724.517396717167, 10780.35317880914, 8678.522681252593, 8745.081766414562, 8781.165789250139, 8911.821763564822, 8915.197632213132, 10448.582056717798, 10570.682879513626, 10644.733881653021, 10669.35983840972, 10865.83701244482, 2110.001547068297, 2127.6702111172795, 2139.7753235910004, 2151.3761009307127, 2252.6170551006367, 934.5794392523392, 936.2930987104828, 948.8956214816365, 982.6020461713018, 1004.322082594246, 17845.7323140153, 21578.244761433587, 22422.215222467134, 23040.769592303863, 24711.50576988486, 980.7401038724744, 983.5573991703762, 995.9203263738883, 1005.9144972677258, 1040.2718097331897, 9548.856767148498, 9554.522273886278, 10050.899491004997, 10519.263651544472, 10967.98380445384, 437436.287599642, 438278.0, 442339.0, 443567.0, 444513.0, 98806.0, 101840.49079754703, 104524.0, 104883.4755826216, 105950.0, 1207.8127890177036, 1221.993890030562, 1252.5240408644838, 1256.7109564799964, 1267.1889990406148, 17715.822841771933, 19548.511287217934, 20027.198912043354, 20074.297399590945, 20370.0, 47796.0, 47815.76092119517, 47852.0, 48100.27849582329, 48726.756366217945, 61551.0, 61580.384196157465, 62533.0, 62557.68721156547, 62680.37319626745, 13995.0, 14044.368003440075, 14094.50669226572, 14449.711005780036, 14997.100173989596, 16288.511344659679, 16292.755608665642, 16338.836611633731, 16375.754363684317, 16395.59011024734, 13516.594502164951, 14173.22047287409, 14409.13545187292, 14477.638059048608, 14491.78262326087, 12676.936615316865, 12759.170653907466, 12923.353832308536, 13036.283004434468, 13630.93184534071, 14773.630659233606, 14842.183679897278, 15087.698246034799, 15173.16547589849, 15200.695986079996, 14512.927435363177, 15096.54710358691, 15142.242887855784, 15150.848491515384, 15338.616534586725, 13910.72178556403, 13962.79055814184, 13997.860021399656, 14045.859541404863, 14059.789103163255], "value": 11195.468485953272, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1296.7276871856911, 1353.5939218234532, 1363.9931800340996, 1364.9044566880316, 1370.883474904633, 10897.128229741622, 11393.974542291193, 11482.425878706063, 11491.138164637643, 11767.70580735482, 11396.601118960838, 11503.654890353282, 11508.769824603503, 11535.423228838567, 11554.19120661554, 2354.2937118864356, 2404.483036147228, 2406.109739396426, 2406.855588664686, 2410.120306088274, 2344.753800850912, 2436.9512609747794, 2443.633454981758, 2445.0097710427303, 2455.8649274289905, 2341.5716412988063, 2349.929502114939, 2367.2543148908126, 2381.678473406094, 2383.916562920298, 2259.8531095478907, 2290.03818396274, 2291.2438895164555, 2299.9195028174017, 2307.619242824932, 2214.767449417804, 2216.0360243294217, 2218.3566765637947, 2222.7777222277805, 2224.8887555622155, 10868.45657717111, 11075.889241107567, 11090.722731931686, 11102.056325212367, 11132.721681957935, 5946.345901950782, 6160.6919654017565, 6169.876602467925, 6179.87640247197, 6190.257169139687, 3432.9656703432897, 3551.0944709099012, 3697.963020369789, 3708.054446116223, 3709.7032237421213, 1220.3471142938486, 1220.945057472407, 1243.9004879609608, 1246.214884622682, 1252.0296770003229, 14998.400063997427, 15548.144852033134, 16359.83640163595, 16465.0, 16521.504354869252, 5045.520675535819, 5063.5695965843315, 5229.0, 5229.764660590206, 5261.263423120766, 12258.08064395174, 12314.445849936774, 12393.318367489876, 12394.31831249272, 12397.75204495923, 6102.328743838174, 6212.502999760099, 6216.906746398808, 6222.408871157233, 6223.408776166257, 5658.405867383948, 5778.6532808030815, 5812.651240925474, 5843.561732870052, 5844.766209351664, 4153.448507696826, 4192.350185721218, 4195.077083041726, 4216.072464057902, 4217.852375166915, 1184.2835084773587, 1195.7907366210877, 1207.275634619239, 1208.0577149823152, 1226.2152222577558, 812.5287333346645, 833.6040380819184, 847.8007668197969, 850.6597361055627, 875.5315905990392, 5951.821445356646, 5998.490128339053, 6125.264968203844, 6156.138140660311, 6217.906731399033, 584.8362458511624, 607.884501944629, 611.3550204534259, 617.3116974573319, 619.6344156947332, 3835.5013848200115, 3900.9219815604097, 3904.1215726461887, 3934.7835869027476, 4098.975256185953, 13387.732245355039, 14301.070430421989, 14567.490137845125, 14709.190994495191, 14842.554723358317, 14238.644033899236, 14369.928150359181, 14409.033504197352, 14501.782473262909, 14735.0, 8918.598663060178, 8918.866217006751, 9066.320025998077, 9082.772930676785, 9130.771730706652, 6083.482903953037, 6187.876242475035, 6489.286178520265, 6570.080188773671, 6872.0, 12833.16584422047, 12910.0, 12925.482980680668, 13079.738405231652, 13092.410841511964, 13221.471141154247, 13282.800757988445, 13373.39819708096, 13396.062275640612, 13531.864681353061, 8844.159804818531, 8854.557272136239, 8936.687215947411, 8955.955220223857, 8962.910370896208, 10668.30656007357, 10672.30630009047, 10699.572017119228, 10747.355158690503, 10753.677389678322, 8921.15249051339, 8927.642894284156, 9022.0, 9253.259739220712, 9296.535173241446, 8952.597133128895, 8955.552222388986, 8983.46099234048, 9037.231835293811, 9181.081891810765, 7374.889376659247, 7384.667689954073, 7536.962315188608, 7544.585047822423, 7610.429217808797, 5764.048931926185, 6030.155778191144, 6086.421789929949, 6278.466330361788, 6380.170577824853, 5752.712364381848, 5892.793752218652, 6006.67960239999, 6032.3967603239325, 6062.514998800174], "value": 5135.330196485881, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [388.9416587511871, 390.4221751807325, 393.3784620299925, 396.8234135809564, 398.57551707431594, 6167.7841275555365, 6182.505399568035, 6204.96897515513, 6225.284092329383, 6255.937440625592, 6000.819975400734, 6094.421030002154, 6132.386761323865, 6171.7531298748, 6199.380061993809, 4919.736921016337, 5052.671576347544, 5055.545000949914, 5064.696118232918, 5084.262781896623, 5090.465501122384, 5094.949050509485, 5106.183010718286, 5113.054084994269, 5121.692698438105, 5031.094403007457, 5035.874103147432, 5046.7224302663335, 5053.469385714502, 5056.241563765446, 5053.36832895888, 5056.570191533724, 5064.898702025976, 5082.669626474268, 5087.465816089294, 4545.977270113662, 4551.726896386227, 4553.362529245908, 4561.1789877821975, 4579.633629309647, 6100.908486372708, 6174.876502469925, 6180.2892667343385, 6181.167676040838, 6188.721507532172, 5787.0, 5800.680962547058, 5803.332616749086, 5806.0, 5819.24349834523, 6643.269240383554, 6651.700673469657, 6660.300668429842, 6668.133142691468, 6683.53215274931, 4967.081089998325, 4975.800967961313, 4983.127952608279, 4993.350864387643, 4993.475685053054, 6717.63053032083, 6858.417034552019, 6988.965055174743, 7067.151941767021, 7070.9292907070785, 4819.156647586658, 5017.9247311290355, 5050.393952725696, 5086.618503612244, 5088.491150884882, 5101.974490127525, 5135.842945962705, 5155.0, 5159.0, 5176.696370115263, 5561.610687251854, 5690.630109042899, 5721.542276617861, 5725.8568535786935, 5737.971310143423, 6607.273199948002, 6613.107230523914, 6615.76844810439, 6622.86754264912, 6624.337566243434, 1772.5391398236293, 1774.946751597454, 1794.676958147546, 1798.2896755781394, 1803.0804289812029, 1205.2768338996532, 1244.2410129820803, 1251.7121062155754, 1260.533602567056, 1262.5391732017747, 5831.950248955144, 5855.853603659943, 5860.0, 5875.0, 5876.6777475067765, 6732.562383445061, 6735.966320168368, 6739.966300168566, 6761.425278851254, 6800.455963522906, 5741.88516229673, 5760.135979603093, 5775.653460792366, 5807.216025836543, 5807.245058142415, 3571.499990001402, 3598.8020658864016, 3634.3821550336506, 3635.8909232723063, 3641.308151451216, 6532.706028228741, 6563.391968967633, 6570.901436478457, 6574.80275591733, 6590.439812615886, 6919.827004324932, 6952.652367381611, 6959.164900212007, 6963.408110310579, 6965.373116419547, 6489.707963141575, 6517.674116294166, 6564.704588293443, 6589.0, 6600.471962243009, 5968.224130863134, 6743.617558400418, 6762.0, 6774.8645027100165, 6835.487338449736, 6745.190577130776, 6746.257911629815, 6755.0, 6758.898616520854, 6788.287229840796, 6315.4947604192475, 6482.805515834532, 6487.351264873475, 6610.504212184009, 6631.6684165792485, 4630.907381852411, 4667.766611669471, 4688.578027977498, 4704.176769065331, 4708.92936605958, 6714.261431242657, 6761.154855643044, 6763.966180169067, 6769.255381907888, 6793.0, 6516.283208846929, 6557.377049180321, 6561.606303621798, 6570.8028759137305, 6586.0, 6593.736250549924, 6608.801735947929, 6618.90071648916, 6653.900191497229, 6662.933370666426, 6417.26201486835, 6489.123968264413, 6508.837279068061, 6540.37866402691, 6586.901196482152, 6599.819141973528, 6616.90074648871, 6660.333966603494, 6673.165854268216, 6685.164354455693, 6360.681965901779, 6416.935830641633, 6426.686392898459, 6434.64609446485, 6452.806415807533], "value": 5057.683249825295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.1713639292191, 763.2138896936154, 798.6645608844285, 827.9213474719901, 858.2447446247303, 16931.830681693194, 17483.0, 17496.387626433076, 17525.561860953483, 18383.080845957684, 18666.440006799818, 18799.530011749714, 18875.811241887575, 19115.330963416283, 19138.90430547849, 915.0666320353236, 931.5668214280358, 940.6237504998021, 940.9576569054375, 941.3081385181886, 902.8013836955892, 983.1986930651532, 1006.3760468509535, 1010.4493051287029, 1012.1801340913846, 1030.752619371353, 1032.2052019944638, 1033.2870319479534, 1034.5499707627207, 1034.6792494326746, 1022.8977102289786, 1024.3444195714749, 1025.0518270599703, 1030.850526673632, 1033.6072292528866, 751.7519218657884, 754.4907187648371, 757.4054367321626, 758.2000988956648, 760.9657565409569, 16085.517434476984, 16119.677606447805, 16170.434034808843, 16210.0, 17490.0, 18333.63332733351, 18441.0, 18928.810711892842, 18999.0, 19007.904960475247, 14174.43302267908, 14381.424743010268, 14463.710725785531, 14500.854991450162, 14558.9080818939, 3413.7269018478464, 3517.2262102337445, 3520.559930008749, 3522.330757156132, 3538.31002954424, 83880.0, 86012.56993715053, 86131.13868861293, 86235.70646440372, 86394.0, 14175.78736318956, 14523.419063237352, 14746.852531474762, 15002.174880381684, 15100.169490677898, 25350.1127460538, 26356.0, 26405.0, 26528.0, 26795.33011674724, 8230.711925082593, 8377.078521362646, 8538.573071346407, 8563.272121869586, 8596.699115531052, 3991.261616600938, 4069.8168582413864, 4074.8166332515107, 4079.571644977294, 4082.408050832613, 3974.4038394241097, 4060.573639767782, 4070.2266569351727, 4077.8776636700945, 4254.234237837219, 810.9959450202829, 841.8947631546057, 843.852325842975, 845.940784145104, 845.9577021149041, 655.131950166032, 679.5311235247664, 682.0485422835118, 686.8900975843841, 696.154172680196, 10428.374297542172, 10799.298045627167, 11711.355875426763, 11885.692573816874, 12396.566120185751, 659.1036190780535, 681.3254877671169, 684.1277371351566, 709.6664567653195, 714.5855403865745, 4933.87665308363, 5549.639273447294, 5591.944080559224, 5598.468145526169, 5689.829305120853, 72354.0, 73874.63062684653, 75874.24125758783, 89110.55444722735, 91730.54134729283, 68687.31312686909, 69932.65033674901, 70441.64779176071, 71474.0, 73089.0, 58679.70660146757, 60445.0, 60615.0, 61091.6945415279, 62717.68641156765, 11174.66476005721, 11770.0, 11777.882221177679, 11888.901095608702, 12118.333491657691, 28381.432371352872, 28984.710152898202, 29066.83732650755, 29952.550711738906, 30140.09579712612, 35966.46050309195, 36051.819740901126, 36241.0, 36791.26417471582, 37039.0, 15550.300236489158, 15558.377664893278, 15941.123238221546, 15982.28079736438, 16152.67694646124, 18901.0, 19650.312239071565, 19723.30968416156, 20244.0, 20266.59466810685, 17171.656566868343, 17294.0, 17366.565835854206, 17646.1176941155, 17954.5511362217, 17177.57056073558, 17745.201465934322, 17883.73174402359, 17907.641847162722, 18249.54376140554, 15781.0, 15942.0, 15998.20008999522, 16285.511434656979, 17047.062411567484, 11519.654410367702, 11648.41757912084, 11658.825117623073, 11760.117991150526, 11909.523619055488, 12295.262284262973, 12667.0, 12724.0, 12765.234085954873, 13595.660108497366], "value": 8023.022861002866, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 111.0, 111.0, 112.0, 114.0, 110.0, 111.0, 111.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 121.0, 122.0, 163.0, 128.0, 121.0, 123.0, 120.0, 143.0, 121.0], "value": 131.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 164.0, 162.0, 163.0, 165.0, 168.0, 169.0, 171.0, 165.0], "value": 165.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 99.0, 80.0, 80.0, 75.0, 74.0, 96.0, 73.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 101.0, 83.0, 82.0, 81.0, 93.0, 81.0, 83.0, 109.0], "value": 87.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 113.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 150.0, 151.0, 151.0, 155.0, 150.0, 151.0, 150.0, 150.0, 150.0], "value": 150.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 59.0, 55.0, 55.0, 56.0, 57.0, 60.0, 57.0, 56.0, 58.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 55.0, 56.0, 56.0, 55.0, 57.0, 56.0, 56.0], "value": 56.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 95.0, 85.0, 86.0, 87.0, 87.0, 88.0, 86.0, 129.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 137.0, 135.0, 134.0, 131.0, 136.0, 139.0, 138.0, 128.0, 145.0], "value": 135.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 194.0, 192.0, 191.0, 198.0, 186.0, 189.0, 187.0, 193.0, 201.0], "value": 192.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 70.0, 74.0, 70.0, 72.0, 69.0, 75.0, 69.0, 72.0], "value": 71.6, "unit": "score"}]}]} 09:29:38 INFO - Return code: 0 09:29:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:29:38 INFO - # TBPL SUCCESS # 09:29:38 INFO - Running post-action listener: _resource_record_post_action 09:29:38 INFO - [mozharness: 2017-06-03 16:29:38.292524Z] Finished run-tests step (success) 09:29:38 INFO - Running post-run listener: _resource_record_post_run 09:29:38 INFO - Total resource usage - Wall time: 1251s; CPU: 13.0%; Read bytes: 602112; Write bytes: 437624832; Read time: 856; Write time: 237640 09:29:38 INFO - TinderboxPrint: CPU usage
12.7% 09:29:38 INFO - TinderboxPrint: I/O read bytes / time
602,112 / 856 09:29:38 INFO - TinderboxPrint: I/O write bytes / time
437,624,832 / 237,640 09:29:38 INFO - TinderboxPrint: CPU idle
8,719.9 (87.3%) 09:29:38 INFO - TinderboxPrint: CPU user
1,211.1 (12.1%) 09:29:38 INFO - TinderboxPrint: Swap in / out
0 / 0 09:29:38 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 112975872; Read time: 68; Write time: 71716 09:29:38 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 09:29:38 INFO - run-tests - Wall time: 1234s; CPU: 13.0%; Read bytes: 593920; Write bytes: 324464640; Read time: 780; Write time: 165924 09:29:38 INFO - Running post-run listener: _upload_blobber_files 09:29:38 INFO - Blob upload gear active. 09:29:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:29:38 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 09:29:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:29:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:29:39 INFO - (blobuploader) - INFO - Open directory for files ... 09:29:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:29:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:29:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:40 INFO - (blobuploader) - INFO - Done attempting. 09:29:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:29:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:41 INFO - (blobuploader) - INFO - Done attempting. 09:29:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:29:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:29:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:44 INFO - (blobuploader) - INFO - Done attempting. 09:29:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:29:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:29:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:29:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:29:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:29:45 INFO - (blobuploader) - INFO - Done attempting. 09:29:45 INFO - (blobuploader) - INFO - Iteration through files over. 09:29:45 INFO - Return code: 0 09:29:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:29:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:29:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/647ba9fdbaf94f2a194e8490918624b666390fd9449ec4a9f555d9cd55ac4da83efacd653c4e253e68acdd3d58402d2e791065f5febf597959b832e8d81b9fc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2582fde1b80ca25ddc000285b4c6831bdef3dbdca56c2002403d06b4de96b20d36ff10e7f2a6b413b562adea27ea75ee26f21cf5f58c95984b316d6c69a8c51d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3c2913cceba4b2a0d44ecddfe689cce6b50c559f2e8437a7fb2882623706318b9594199066cc6ec82f8ce9692198cd4cd7f4670e4bdc1a6bbdfcf6a3e9d5a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/943364ece1349bfa68f1fdb8975c7f0812f593c3eba77e52e0c01fb7bcd76cf390249909d9bf31352a9949896daa14e21835d9bc2e5fb2666d24f3e3ace1a437"} 09:29:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:29:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:29:45 INFO - Contents: 09:29:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/647ba9fdbaf94f2a194e8490918624b666390fd9449ec4a9f555d9cd55ac4da83efacd653c4e253e68acdd3d58402d2e791065f5febf597959b832e8d81b9fc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2582fde1b80ca25ddc000285b4c6831bdef3dbdca56c2002403d06b4de96b20d36ff10e7f2a6b413b562adea27ea75ee26f21cf5f58c95984b316d6c69a8c51d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3c2913cceba4b2a0d44ecddfe689cce6b50c559f2e8437a7fb2882623706318b9594199066cc6ec82f8ce9692198cd4cd7f4670e4bdc1a6bbdfcf6a3e9d5a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/943364ece1349bfa68f1fdb8975c7f0812f593c3eba77e52e0c01fb7bcd76cf390249909d9bf31352a9949896daa14e21835d9bc2e5fb2666d24f3e3ace1a437"} 09:29:45 INFO - Running post-run listener: copy_logs_to_upload_dir 09:29:45 INFO - Copying logs to upload dir... 09:29:45 INFO - mkdir: /builds/slave/test/build/upload/logs 09:29:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1309.218251 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 49 secs) (at 2017-06-03 09:29:45.494347) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-03 09:29:45.501998) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/647ba9fdbaf94f2a194e8490918624b666390fd9449ec4a9f555d9cd55ac4da83efacd653c4e253e68acdd3d58402d2e791065f5febf597959b832e8d81b9fc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2582fde1b80ca25ddc000285b4c6831bdef3dbdca56c2002403d06b4de96b20d36ff10e7f2a6b413b562adea27ea75ee26f21cf5f58c95984b316d6c69a8c51d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3c2913cceba4b2a0d44ecddfe689cce6b50c559f2e8437a7fb2882623706318b9594199066cc6ec82f8ce9692198cd4cd7f4670e4bdc1a6bbdfcf6a3e9d5a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/943364ece1349bfa68f1fdb8975c7f0812f593c3eba77e52e0c01fb7bcd76cf390249909d9bf31352a9949896daa14e21835d9bc2e5fb2666d24f3e3ace1a437"} build_url:https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/PZKpTjq4QjWO3upaqXKHsg/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/647ba9fdbaf94f2a194e8490918624b666390fd9449ec4a9f555d9cd55ac4da83efacd653c4e253e68acdd3d58402d2e791065f5febf597959b832e8d81b9fc1", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2582fde1b80ca25ddc000285b4c6831bdef3dbdca56c2002403d06b4de96b20d36ff10e7f2a6b413b562adea27ea75ee26f21cf5f58c95984b316d6c69a8c51d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/a3c2913cceba4b2a0d44ecddfe689cce6b50c559f2e8437a7fb2882623706318b9594199066cc6ec82f8ce9692198cd4cd7f4670e4bdc1a6bbdfcf6a3e9d5a63", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/943364ece1349bfa68f1fdb8975c7f0812f593c3eba77e52e0c01fb7bcd76cf390249909d9bf31352a9949896daa14e21835d9bc2e5fb2666d24f3e3ace1a437"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-03 09:29:45.532181) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:29:45.532716) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1496505939.665866-175037116 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004469 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-03 09:29:45.615470) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-03 09:29:45.615799) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-03 09:29:45.616157) ========= ========= Total master_lag: 0.05 =========